-- Logs begin at Sat 2024-07-06 04:54:02 UTC, end at Wed 2025-03-12 15:00:44 UTC. -- Mar 12 14:59:00 volumio go-librespot[3135]: time="2025-03-12T14:59:00Z" level=debug msg="obtained new client token: AABbjaFFJ/jC60zhd0UCQWD/OEqWt6z5DZ66bQEkYgOfxGGKp2yMJy8SA5Gfc1c5M/xCrnvIBjJuy3puvcJID84176OeG1awa++DCqnEVWSlz0GMVm0QrKqc+mzV3FFjCoMDRBDiJTWJa+SVMPv8m0gGFxRyJHaKGE9UsD3Wm+n6AZPnacgxtURHpdg7jl+D4zlmMbyya+ftnSi5wMlQG1/N4MVfxMJ/WZO3moCK8gHmLvPgEwPH6naZVBIr" Mar 12 14:59:00 volumio go-librespot[3135]: time="2025-03-12T14:59:00Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:00 volumio go-librespot[3135]: time="2025-03-12T14:59:00Z" level=debug msg="completed keyexchange" Mar 12 14:59:00 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:00 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:00 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:00 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:00 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:00 volumio go-librespot[3135]: time="2025-03-12T14:59:00Z" level=debug msg="completed challenge" Mar 12 14:59:00 volumio go-librespot[3135]: time="2025-03-12T14:59:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:01 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:01 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:01 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:01 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:01 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:02 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:02 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:02 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:02 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:02 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:02 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:02 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:03 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:03 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:03 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:03 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:03 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 157. Mar 12 14:59:03 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:03 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:03 volumio go-librespot[3144]: Librespot-go daemon starting... Mar 12 14:59:03 volumio go-librespot[3144]: time="2025-03-12T14:59:03Z" level=info msg="generated new device id: a64fbc849e3c0e3db40ef32ef1036544fb58294d" Mar 12 14:59:03 volumio go-librespot[3144]: time="2025-03-12T14:59:03Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:04 volumio go-librespot[3144]: time="2025-03-12T14:59:04Z" level=debug msg="obtained new client token: AADq99DtwZKT0AWcE58ofqo6TrKpo/jFXbcgmlAlVj6ggxFnwKM/5uuBGcSEpX0uhbff0/D37eaogvkJJvy31E+9e/jes3C4XmVM7yB4iM5bKBTjKM4QpNLzonIIv718uK1LWJaTB4UHvUsYFKbAs4Bbo1ifYv1Z8CfUZiiYT2AvzBiRCW92lNyDR+jCH8Wp8QX1YzYu+yRNeMheDg/dL3x1/exP71F6ntCMezLzZXM5B9CW32HxcUAngHEV" Mar 12 14:59:04 volumio go-librespot[3144]: time="2025-03-12T14:59:04Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:04 volumio go-librespot[3144]: time="2025-03-12T14:59:04Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Mar 12 14:59:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:04 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:04 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:04 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:04 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:04 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:05 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::getQueue Mar 12 14:59:05 volumio volumio[868]: info: CorePlayQueue::getQueue Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::volumioPause Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::pause Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::stPlaybackTimer Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::servicePause Mar 12 14:59:05 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::servicePause Mar 12 14:59:05 volumio volumio[868]: info: ControllerMpd::pause Mar 12 14:59:05 volumio volumio[868]: verbose: ControllerMpd::sendMpdCommand pause Mar 12 14:59:05 volumio volumio[868]: info: Mar 12 14:59:05 volumio volumio[868]: ---------------------------- MPD announces state update: player Mar 12 14:59:05 volumio volumio[868]: info: sendMpdCommand pause took 2 milliseconds Mar 12 14:59:05 volumio volumio[868]: info: ControllerMpd::getState Mar 12 14:59:05 volumio volumio[868]: verbose: ControllerMpd::sendMpdCommand status Mar 12 14:59:05 volumio volumio[868]: info: sendMpdCommand status took 1 milliseconds Mar 12 14:59:05 volumio volumio[868]: verbose: ControllerMpd::parseState Mar 12 14:59:05 volumio volumio[868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 12 14:59:05 volumio volumio[868]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 12 14:59:05 volumio volumio[868]: verbose: ControllerMpd::parseTrackInfo Mar 12 14:59:05 volumio volumio[868]: info: ControllerMpd::pushState Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::servicePushState Mar 12 14:59:05 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:05 volumio volumio[868]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7873,"duration":615,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"1. Andante: Locatelli Violin Concerto No. 2 in C Minor, Op. 3, No. 2","artist":"The String Soloists","album":"2016 L'Arte del Violino (Locatelli Violin Concertos) (DSD256 Binaural)","uri":"NAS/DSD_MUSIC/CLASSICA/Lisa Jacobs - L'Arte del Violino (Locatelli Violin Concertos) (2016) The String Soloists DSD256/DSD256 Binaural/01 1. Andante (Locatelli Violin Concerto No. 2 in C Minor, Op. 3, No. 2).dsf","trackType":"dsf"} Mar 12 14:59:05 volumio volumio[868]: verbose: CURRENT POSITION 0 Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::syncState stateService pause Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::syncState currentStatus pause Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::pushState Mar 12 14:59:05 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 14:59:05 volumio volumio[868]: info: CoreCommandRouter::volumioPushState Mar 12 14:59:05 volumio volumio[868]: info: CoreStateMachine::stPlaybackTimer Mar 12 14:59:05 volumio volumio[868]: info: ------------------------------ 13ms Mar 12 14:59:05 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:05 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 158. Mar 12 14:59:07 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:07 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:07 volumio go-librespot[3152]: Librespot-go daemon starting... Mar 12 14:59:07 volumio go-librespot[3152]: time="2025-03-12T14:59:07Z" level=info msg="generated new device id: a33796bde11b4929b71099351f934e46c4c09237" Mar 12 14:59:07 volumio go-librespot[3152]: time="2025-03-12T14:59:07Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:07 volumio go-librespot[3152]: time="2025-03-12T14:59:07Z" level=debug msg="obtained new client token: AABtf6xGI69XrAR0du8K4TbMHGgaksky2elTPxuox1SywPO+jGnoTNmSc28NlALHwpKuaYh0lxCmZgRmbpkIpFl1pgHJXZ273ipbJY5rvzF8gfPLaVbcpRbfg79qHE40dw2JG+DfVc7mTQFDWoKXz75pi5bgpNRuopLhSwFvrgxfTKyqIFDexOwxMS5XzlRmZrUtucGo+xTYKqxFcKZLc7AjAOGn1MjOzahuIs34pHvntlIGDhUn/tOqZKcoLZE=" Mar 12 14:59:07 volumio go-librespot[3152]: time="2025-03-12T14:59:07Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:07 volumio go-librespot[3152]: time="2025-03-12T14:59:07Z" level=debug msg="completed keyexchange" Mar 12 14:59:08 volumio go-librespot[3152]: time="2025-03-12T14:59:08Z" level=debug msg="completed challenge" Mar 12 14:59:08 volumio go-librespot[3152]: time="2025-03-12T14:59:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:08 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:08 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 159. Mar 12 14:59:11 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:11 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:11 volumio go-librespot[3160]: Librespot-go daemon starting... Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=info msg="generated new device id: 14848ab9a963f40fba9015844b0074ef9daf2ff2" Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=debug msg="obtained new client token: AAAKV5xjqetuLkFD722GR+nInz9ad+xqM64aL/k6NRR/K2wIX5m4rQaMQGuX7God+pB375OXhayyYPT14Wu6OWTZ1e4N19o40GgJYok6DA6Y8hicKUX9mT9avoSGzWup4gW0MQmrQ3jEEIo0yYcQ/rJyCHqL2GrI+ixamM/zvRE15/Bb1VkiqX8uBJWa8OIX8zMZTE7mBBAMsKv2YgXWLM3GNaDB8MhKMdjmxaV7HNclMfAIvhDZ+ZhVWtrHkCM=" Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=debug msg="completed keyexchange" Mar 12 14:59:11 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:11 volumio go-librespot[3160]: time="2025-03-12T14:59:11Z" level=debug msg="new websocket client" Mar 12 14:59:11 volumio volumio[868]: info: Connection to go-librespot Websocket established Mar 12 14:59:12 volumio go-librespot[3160]: time="2025-03-12T14:59:12Z" level=debug msg="completed challenge" Mar 12 14:59:12 volumio go-librespot[3160]: time="2025-03-12T14:59:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:12 volumio volumio[868]: info: Connection to go-librespot Websocket closed Mar 12 14:59:14 volumio volumio[868]: info: Getting Spotify volume Mar 12 14:59:14 volumio volumio[868]: (node:868) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:14 volumio volumio[868]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 12 14:59:14 volumio volumio[868]: (node:868) 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: 18) Mar 12 14:59:14 volumio volumio[868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 12 14:59:14 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:14 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:15 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:15 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 160. Mar 12 14:59:15 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:15 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:15 volumio go-librespot[3168]: Librespot-go daemon starting... Mar 12 14:59:15 volumio go-librespot[3168]: time="2025-03-12T14:59:15Z" level=info msg="generated new device id: e8de6d37ffedab8bb60ca7d3dd6b3eb1d1b9313a" Mar 12 14:59:15 volumio go-librespot[3168]: time="2025-03-12T14:59:15Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:15 volumio go-librespot[3168]: time="2025-03-12T14:59:15Z" level=debug msg="obtained new client token: AADxKPWnrGgUl5BlA/UJA3avlWc3ynbRWmFeq1ZL0IHos/qibXSQ/lIdQV3ZseNADtKgXrGu8uxN/0ETq0itLmE520oEWJHDSBOXU7HH241M0AGEbHZlAzOGUxleWAZOKRnC/x7822bhygBpjV4gqit3ZG2fjLOtXmx1QYsa6cyCtIgfsk4e5jOmmS658C4ewEySjie4MAy1qkw1ZwWHdlqjKocO53p9wEXaYy5gY3iUpnFAnPCjAoTwGiwuLxE=" Mar 12 14:59:15 volumio go-librespot[3168]: time="2025-03-12T14:59:15Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:15 volumio go-librespot[3168]: time="2025-03-12T14:59:15Z" level=debug msg="completed keyexchange" Mar 12 14:59:16 volumio go-librespot[3168]: time="2025-03-12T14:59:16Z" level=debug msg="completed challenge" Mar 12 14:59:16 volumio go-librespot[3168]: time="2025-03-12T14:59:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:17 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:17 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:18 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:18 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 161. Mar 12 14:59:19 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:19 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:19 volumio go-librespot[3176]: Librespot-go daemon starting... Mar 12 14:59:19 volumio go-librespot[3176]: time="2025-03-12T14:59:19Z" level=info msg="generated new device id: 360b4beca0e53b54be3519670950826db6c745bf" Mar 12 14:59:19 volumio go-librespot[3176]: time="2025-03-12T14:59:19Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:19 volumio go-librespot[3176]: time="2025-03-12T14:59:19Z" level=debug msg="obtained new client token: AAAiw3TEfUcN1pFHuOHkwrRSEVrtbs75pzBtSmRxwQdfM8UeQhIQZH5dPj9v1s5OqmcLU+z4o78KS1Nk/KhAY66yphdIbutrBSnhrVBuwA7/3JpO2a474Qa3O29fKSiDuSz+kZyjL+D28rKrZv1EWjdBRFWZ5wIAymBCbSAy9UxMl2D4va70hiLjg+37fVvX2s+YzpRYRgqbE1zR7tkr43eu1S50CdUpSXmTSZpBrwVQ7riqVFSG06ZwbJrU3sI=" Mar 12 14:59:19 volumio go-librespot[3176]: time="2025-03-12T14:59:19Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:19 volumio go-librespot[3176]: time="2025-03-12T14:59:19Z" level=debug msg="completed keyexchange" Mar 12 14:59:20 volumio go-librespot[3176]: time="2025-03-12T14:59:20Z" level=debug msg="completed challenge" Mar 12 14:59:20 volumio go-librespot[3176]: time="2025-03-12T14:59:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:21 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:21 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 162. Mar 12 14:59:23 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:23 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:23 volumio go-librespot[3185]: Librespot-go daemon starting... Mar 12 14:59:23 volumio go-librespot[3185]: time="2025-03-12T14:59:23Z" level=info msg="generated new device id: 3377fa582abdf375b0316ac4c12a8fa3db197bde" Mar 12 14:59:23 volumio go-librespot[3185]: time="2025-03-12T14:59:23Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:23 volumio go-librespot[3185]: time="2025-03-12T14:59:23Z" level=debug msg="obtained new client token: AAACIy5QXbAuWFmPDg9ZXINsxHc0F6V45zYYgxpxR4VLpU4dT071K7clLRekZSa+c3ndj28RIF/QJQ/q8MqA515j2JjzxdOImK5WigAgodZLUJIFpGSv12kkQmYLSkmduZVccL67zm6HNB9JS5PQzrTkxUg5bNJ9l8uPtLS2JXnCqiWWUvkATaZJUjtgevIWo6CvNskB0ituSKY8/1vAsYB31udvSv9GH9n8ZwXlQu4+zJtLuTisKrGm2knJNxk=" Mar 12 14:59:23 volumio go-librespot[3185]: time="2025-03-12T14:59:23Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:23 volumio go-librespot[3185]: time="2025-03-12T14:59:23Z" level=debug msg="completed keyexchange" Mar 12 14:59:24 volumio go-librespot[3185]: time="2025-03-12T14:59:24Z" level=debug msg="completed challenge" Mar 12 14:59:24 volumio go-librespot[3185]: time="2025-03-12T14:59:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:24 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:24 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:27 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:27 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 163. Mar 12 14:59:27 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:27 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:27 volumio go-librespot[3231]: Librespot-go daemon starting... Mar 12 14:59:27 volumio go-librespot[3231]: time="2025-03-12T14:59:27Z" level=info msg="generated new device id: 3c73aea06135cf1c6ca93d6bd1f51b59f977572b" Mar 12 14:59:27 volumio go-librespot[3231]: time="2025-03-12T14:59:27Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:27 volumio go-librespot[3231]: time="2025-03-12T14:59:27Z" level=debug msg="obtained new client token: AABPsJiey9aOgfL/oKzOpf7YCLv5vKFkv3diVPEuwo8WqlYR45NE/a9V1iOCr5pxvtXw5n4fOdk0uIgCyHjJBIrD300sBpuSqTQk7xgUZNmwC6kNpfiLo444XlYKJump93xxn6Ya38Y4pxkT/B2rIoDH7ItP0jzeyBwz4OSTeHkCpXDTBZDAcJJnusW1wkpZXB7J0xBdb1CNFyItf+JX9R5PYePGamuCXinlULfJZ2Wwdd7MTuytHT8uJpjcr+Q=" Mar 12 14:59:27 volumio go-librespot[3231]: time="2025-03-12T14:59:27Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:27 volumio go-librespot[3231]: time="2025-03-12T14:59:27Z" level=debug msg="completed keyexchange" Mar 12 14:59:28 volumio go-librespot[3231]: time="2025-03-12T14:59:28Z" level=debug msg="completed challenge" Mar 12 14:59:28 volumio go-librespot[3231]: time="2025-03-12T14:59:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:30 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:30 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. Mar 12 14:59:31 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:31 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:31 volumio go-librespot[3239]: Librespot-go daemon starting... Mar 12 14:59:31 volumio go-librespot[3239]: time="2025-03-12T14:59:31Z" level=info msg="generated new device id: e1e851f78a58e457e2be88082ff3ca1989c2a74c" Mar 12 14:59:31 volumio go-librespot[3239]: time="2025-03-12T14:59:31Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:31 volumio go-librespot[3239]: time="2025-03-12T14:59:31Z" level=debug msg="obtained new client token: AADHX6/LLv2immfItHBL2dxJMEmkgAXmMCuyU/VIM4Xa+yr5oGNDmQ1HBkLK+aezLs/sf0QJ7/2766dL3LnBLiojRoWrtFEwY/oUMelD8Y99PJ6f9pOc0JZVWE8QY5ghO6Oo+n38wc3gK3k5GbM+0EaoKRKaL9A/4wcJWVK1KOSxF3zuVtY32aaQEPIaKFjJy1RANCh0uE5vRBVOWEWkjenTVzL2BT4YkFMDXvf4SsjvNcvTr+V40YT3gF+6fbA=" Mar 12 14:59:31 volumio go-librespot[3239]: time="2025-03-12T14:59:31Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:31 volumio go-librespot[3239]: time="2025-03-12T14:59:31Z" level=debug msg="completed keyexchange" Mar 12 14:59:32 volumio go-librespot[3239]: time="2025-03-12T14:59:32Z" level=debug msg="completed challenge" Mar 12 14:59:32 volumio go-librespot[3239]: time="2025-03-12T14:59:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:33 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:33 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. Mar 12 14:59:35 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:35 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:35 volumio go-librespot[3248]: Librespot-go daemon starting... Mar 12 14:59:35 volumio go-librespot[3248]: time="2025-03-12T14:59:35Z" level=info msg="generated new device id: 8fa46b01a6b0e6fa458bb0650685236e7eb8fc1a" Mar 12 14:59:35 volumio go-librespot[3248]: time="2025-03-12T14:59:35Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:35 volumio go-librespot[3248]: time="2025-03-12T14:59:35Z" level=debug msg="obtained new client token: AACysRXInplwYlKLldoc2cqDZk2nqPmHMfBgxpQyxIgmOWqAWeObQXoPO6z+/RHWfsVYoEuvtIhH8n8lrWNyDubj/yMei7UASeMkOie5Mzynq/0xGdx9FcNMQZ1ks6u+fCy11n37VL64Lnz+uaNSSp8cpnr975C3rYwpY3Hl5F+24lENzOdVUa78E24tF84F5nxwQe+lAFLFdi7tkTw0IR6XHFb/xeRTtd08xxyTGjh+gLagrUlworTLlbKT7rI=" Mar 12 14:59:35 volumio go-librespot[3248]: time="2025-03-12T14:59:35Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:35 volumio go-librespot[3248]: time="2025-03-12T14:59:35Z" level=debug msg="completed keyexchange" Mar 12 14:59:36 volumio go-librespot[3248]: time="2025-03-12T14:59:36Z" level=debug msg="completed challenge" Mar 12 14:59:36 volumio go-librespot[3248]: time="2025-03-12T14:59:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:36 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:36 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:36 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 14:59:36 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 14:59:36 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 14:59:36 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 14:59:36 volumio volumio[868]: info: Received Get System Version Mar 12 14:59:36 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 14:59:39 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:39 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:39 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 14:59:39 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 14:59:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. Mar 12 14:59:39 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:39 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:39 volumio go-librespot[3270]: Librespot-go daemon starting... Mar 12 14:59:39 volumio go-librespot[3270]: time="2025-03-12T14:59:39Z" level=info msg="generated new device id: a17d0f5422db223ca7d791049a7fc22e9856afbf" Mar 12 14:59:39 volumio go-librespot[3270]: time="2025-03-12T14:59:39Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:39 volumio go-librespot[3270]: time="2025-03-12T14:59:39Z" level=debug msg="obtained new client token: AACBQU9TAF3nJVYYw2d7f+S+m3pF/Ylr8vkAZOqtWC64TCZzCYbO+7kDUFupqsQUO5XzvkMbtoBNayhVEujVqzdwuapkq2xgLVa3bFi4EqkX801y3snW/YzAZ0sZyJKofp6sr5M8MJM6wY1JIITq1iT7XTtHr3rR7WXBpt88z6LSHL6p28BgFHw2hfUtbrwlteHxOtk2phQhIl29Oe47fMcw9FwkPS+ARhA/VMVz1Hl9lhO6de5LH3RN0TxgheQ=" Mar 12 14:59:39 volumio go-librespot[3270]: time="2025-03-12T14:59:39Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:39 volumio go-librespot[3270]: time="2025-03-12T14:59:39Z" level=debug msg="completed keyexchange" Mar 12 14:59:40 volumio go-librespot[3270]: time="2025-03-12T14:59:40Z" level=debug msg="completed challenge" Mar 12 14:59:40 volumio go-librespot[3270]: time="2025-03-12T14:59:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:42 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:42 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 12 14:59:42 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 12 14:59:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Mar 12 14:59:43 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:43 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:43 volumio go-librespot[3282]: Librespot-go daemon starting... Mar 12 14:59:43 volumio go-librespot[3282]: time="2025-03-12T14:59:43Z" level=info msg="generated new device id: 031e817e1adff82b32d9bf8bf57e97bc39e07057" Mar 12 14:59:43 volumio go-librespot[3282]: time="2025-03-12T14:59:43Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:43 volumio go-librespot[3282]: time="2025-03-12T14:59:43Z" level=debug msg="obtained new client token: AAA9sI/mlTP+IaW6o30y1bbNMx/9oVqfkmvVacjaAHEFWcn/95hBZUzlT0PlN9HrNeg+N2AfxNuxHxJPpDwQRr695RVa5s1XxD/GL6Dj5Y85tLbuERmJfdCWkgFG810lFZv6zdX4l6YCSL9RS2ooBA5wyH4nBZ+Cwg84ui2523O76k78mDkOlShDdo2ImndCIdVTloom1R7vqRQPSsTJjM0+6N7KD0QP387bfclHRHAChwAAGfKzfhj8Il5c9xM=" Mar 12 14:59:43 volumio go-librespot[3282]: time="2025-03-12T14:59:43Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:43 volumio go-librespot[3282]: time="2025-03-12T14:59:43Z" level=debug msg="completed keyexchange" Mar 12 14:59:44 volumio go-librespot[3282]: time="2025-03-12T14:59:44Z" level=debug msg="completed challenge" Mar 12 14:59:44 volumio go-librespot[3282]: time="2025-03-12T14:59:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:45 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:45 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:45 volumio ntpd[809]: 162.159.200.123 local addr 10.10.1.22 -> Mar 12 14:59:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Mar 12 14:59:47 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:47 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:47 volumio go-librespot[3290]: Librespot-go daemon starting... Mar 12 14:59:47 volumio go-librespot[3290]: time="2025-03-12T14:59:47Z" level=info msg="generated new device id: fafdb990287c8f95251fcb7bcaf20fd8d672506a" Mar 12 14:59:47 volumio go-librespot[3290]: time="2025-03-12T14:59:47Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:47 volumio go-librespot[3290]: time="2025-03-12T14:59:47Z" level=debug msg="obtained new client token: AAC/FeEZQREnNvEc6T0Bn53Optvjj2Qxp6lHX5lzaE02rhGC4HozxFY3rS/7naysYOwtFzZ+RsaRaFQORH+Lcm02tR/DkhAFw6GgK/TJb7UY5qF8cABMVdLwuSMWszfijcQ8v9o4+2i5NMK62A84VTvoWJwN4WaR6qtLPaOmxHNr313/cVkA5Ao5nvCqUTKyWIMXpNHrg66muFmvZJ6mf3YcsgXBaAq9TDGWsJjiyHuX4W5whM7v06V9jCq8Mjk=" Mar 12 14:59:47 volumio go-librespot[3290]: time="2025-03-12T14:59:47Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:47 volumio go-librespot[3290]: time="2025-03-12T14:59:47Z" level=debug msg="completed keyexchange" Mar 12 14:59:48 volumio go-librespot[3290]: time="2025-03-12T14:59:48Z" level=debug msg="completed challenge" Mar 12 14:59:48 volumio go-librespot[3290]: time="2025-03-12T14:59:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:48 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:48 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:48 volumio ntpd[809]: 93.94.88.51 local addr 10.10.1.22 -> Mar 12 14:59:51 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:51 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Mar 12 14:59:51 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:51 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:51 volumio go-librespot[3299]: Librespot-go daemon starting... Mar 12 14:59:51 volumio go-librespot[3299]: time="2025-03-12T14:59:51Z" level=info msg="generated new device id: 13fa41aaa207bf6611b2a9e6f2d31751a20d2e18" Mar 12 14:59:51 volumio go-librespot[3299]: time="2025-03-12T14:59:51Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:51 volumio go-librespot[3299]: time="2025-03-12T14:59:51Z" level=debug msg="obtained new client token: AADLCK7rd3Ebf+Cys2RTv8xlkxC1teTs4oiQEtVliDkqFmjZGQwqIakViOJD6ukeZaJZafx55DctyNT+tgtbk47H0ZF48AsB3Xq4H/E1YAhtrnygsSjaE/utbC/PN2pl9MVoxTDzfERfmYAlE9GN20T9GSnNpwsoAliSJalpUUDwN52rL0jKkjHCPzaM/wgWy9UpBmfsHT2CTpq2qlu6wW1MKte3SW9/H2sNK/sIKVYCwwgRMZn69sKkmQB6tFs=" Mar 12 14:59:51 volumio go-librespot[3299]: time="2025-03-12T14:59:51Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:51 volumio go-librespot[3299]: time="2025-03-12T14:59:51Z" level=debug msg="completed keyexchange" Mar 12 14:59:52 volumio go-librespot[3299]: time="2025-03-12T14:59:52Z" level=debug msg="completed challenge" Mar 12 14:59:52 volumio go-librespot[3299]: time="2025-03-12T14:59:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:52 volumio ntpd[809]: 93.94.88.50 local addr 10.10.1.22 -> Mar 12 14:59:54 volumio volumio[868]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Mar 12 14:59:54 volumio sudo[3308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 12 14:59:54 volumio sudo[3308]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 14:59:54 volumio sudo[3308]: pam_unix(sudo:session): session closed for user root Mar 12 14:59:54 volumio volumio[868]: info: MPD Permissions set Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 12 14:59:54 volumio sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 14:59:54 volumio systemd[1]: Stopping Music Player Daemon... Mar 12 14:59:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 14:59:54 volumio systemd[1]: mpd.service: Succeeded. Mar 12 14:59:54 volumio systemd[1]: Stopped Music Player Daemon. Mar 12 14:59:54 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:54 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:54 volumio systemd[1]: Starting Music Player Daemon... Mar 12 14:59:54 volumio sudo[3317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 12 14:59:54 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 14:59:54 volumio sudo[3317]: pam_unix(sudo:session): session closed for user root Mar 12 14:59:54 volumio ntpd[809]: 216.128.11.77 local addr 10.10.1.22 -> Mar 12 14:59:54 volumio mpd[3320]: Mar 12 14:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 12 14:59:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Mar 12 14:59:55 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:55 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:55 volumio go-librespot[3321]: Librespot-go daemon starting... Mar 12 14:59:55 volumio go-librespot[3321]: time="2025-03-12T14:59:55Z" level=info msg="generated new device id: dc93c98a9e929182599c277b90038a02ffbac24d" Mar 12 14:59:55 volumio go-librespot[3321]: time="2025-03-12T14:59:55Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:55 volumio go-librespot[3321]: time="2025-03-12T14:59:55Z" level=debug msg="obtained new client token: AABjS2RNZjkSlYRbTJmrRYm28oRVJKAmJDvFx/P9b4s4oDQCuBAjw0IoKMRoN73Ngu0X7C2sFSyjWMJfD0ny0cWz5BwNfPFMPqrnNDEGLSeUFR2GxYltroCKlxXTtDQmenXwN/4XWVwGtdNq3UUq1GOOsETYxZS2UPXJPsRMP97xTf+uUYDWog7yTqba3lNKmvpWZzxoB/1VqsuJAzUYIO+lh1j/OcfJ1rtvjv164ttkr5ws7mifdNg1rMgOzgU=" Mar 12 14:59:55 volumio systemd[1]: Started Music Player Daemon. Mar 12 14:59:55 volumio sudo[3311]: pam_unix(sudo:session): session closed for user root Mar 12 14:59:55 volumio volumio[868]: error: updateQueue error: null Mar 12 14:59:55 volumio go-librespot[3321]: time="2025-03-12T14:59:55Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 12 14:59:55 volumio go-librespot[3321]: time="2025-03-12T14:59:55Z" level=debug msg="completed keyexchange" Mar 12 14:59:56 volumio go-librespot[3321]: time="2025-03-12T14:59:56Z" level=debug msg="completed challenge" Mar 12 14:59:56 volumio go-librespot[3321]: time="2025-03-12T14:59:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 14:59:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 14:59:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 14:59:57 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 14:59:57 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 14:59:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 14:59:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. Mar 12 14:59:59 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 14:59:59 volumio systemd[1]: Started go-librespot Daemon. Mar 12 14:59:59 volumio go-librespot[3331]: Librespot-go daemon starting... Mar 12 14:59:59 volumio go-librespot[3331]: time="2025-03-12T14:59:59Z" level=info msg="generated new device id: 066549d5c42f4ba19975f8a71bd3affc024cb4d6" Mar 12 14:59:59 volumio go-librespot[3331]: time="2025-03-12T14:59:59Z" level=debug msg="stored credentials found for adonzell" Mar 12 14:59:59 volumio go-librespot[3331]: time="2025-03-12T14:59:59Z" level=debug msg="obtained new client token: AADmhafGgKMBNlrFy0FZ99+3RU/GsjFn8ZgPj5OM2ucX6W4iEVVIvVdelHZUoV2ezwPyRs7C0DLS4mS1Ul+lSZJ+mu0AJ3AzHQtPrmBuA1JUXKPy9Mo7YkMzy5G2ll9D+Mc75wkT4A+c96XVdzukrSSBk48TXFEJ6xkJu5ePfQdxFALXdtBNaIWrcVguIepLf8Ivo+ZlUCd7qhprIcl1FAVl6FNNFpKQ7tHgVFsSkpz+K4A5GCtro0jWxYI6DaY=" Mar 12 14:59:59 volumio go-librespot[3331]: time="2025-03-12T14:59:59Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 14:59:59 volumio go-librespot[3331]: time="2025-03-12T14:59:59Z" level=debug msg="completed keyexchange" Mar 12 15:00:00 volumio go-librespot[3331]: time="2025-03-12T15:00:00Z" level=debug msg="completed challenge" Mar 12 15:00:00 volumio go-librespot[3331]: time="2025-03-12T15:00:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:00 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:00 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:03 volumio volumio[868]: info: CoreCommandRouter::volumioPlay Mar 12 15:00:03 volumio volumio[868]: info: CoreStateMachine::play index undefined Mar 12 15:00:03 volumio volumio[868]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 15:00:03 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 15:00:03 volumio volumio[868]: info: CoreStateMachine::startPlaybackTimer Mar 12 15:00:03 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 15:00:03 volumio volumio[868]: info: ControllerMpd::resume Mar 12 15:00:03 volumio volumio[868]: verbose: ControllerMpd::sendMpdCommand play Mar 12 15:00:03 volumio volumio[868]: info: sendMpdCommand play took 1 milliseconds Mar 12 15:00:03 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:03 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 172. Mar 12 15:00:03 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:03 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:03 volumio go-librespot[3339]: Librespot-go daemon starting... Mar 12 15:00:03 volumio go-librespot[3339]: time="2025-03-12T15:00:03Z" level=info msg="generated new device id: 00328e42cc5c9625b8d7f91bafd668513655213b" Mar 12 15:00:03 volumio go-librespot[3339]: time="2025-03-12T15:00:03Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:03 volumio go-librespot[3339]: time="2025-03-12T15:00:03Z" level=debug msg="obtained new client token: AADPFJ19Y9nxf8sO4sKJ/8JJLMD8BNfRbPPre+CIfMQ7KXUWNQ0VSF65XIXds7pS9yG/FSDmdKVm9kHRIx7CEx9SYjzbdEVTzYcZWWvm1610dJt4S+FTj3RlEIgrcjMjmK7voodhpG5OtE0Cd9VfRrTDZkpwS3Q2u7mk7xTI7BmVBZmJhcDT6+UcI42JXBZnqQ5A7fRWXwnZJ745JXeU7oTx4ycetK+KoeW1jp+wkfr41iqftekaZane43qdVAo=" Mar 12 15:00:03 volumio go-librespot[3339]: time="2025-03-12T15:00:03Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:03 volumio go-librespot[3339]: time="2025-03-12T15:00:03Z" level=debug msg="completed keyexchange" Mar 12 15:00:04 volumio go-librespot[3339]: time="2025-03-12T15:00:04Z" level=debug msg="completed challenge" Mar 12 15:00:04 volumio go-librespot[3339]: time="2025-03-12T15:00:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:06 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:06 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 173. Mar 12 15:00:07 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:07 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:07 volumio go-librespot[3347]: Librespot-go daemon starting... Mar 12 15:00:07 volumio go-librespot[3347]: time="2025-03-12T15:00:07Z" level=info msg="generated new device id: f49404208c9946aa8df6684b927d337d945ed932" Mar 12 15:00:07 volumio go-librespot[3347]: time="2025-03-12T15:00:07Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:07 volumio go-librespot[3347]: time="2025-03-12T15:00:07Z" level=debug msg="obtained new client token: AABLRLY/crQarRFzRc+XzyTLOscEaeX/jgQY0UDddB+pDCi6w3LEsld15ceyv3dg1Tmt8nfxNqYVao5rhxBR7+b6Z5ilHL5BkEfQYF4BhX3LsULzTKOmZhcu2S9H3gP0YBIpGg6Dgzj/0rHanvwx3Tu78uMBblu+K8MpCWqAEQ/heShyQuaC4BhHgu9JA3aRacPuEKai/0xTcGCQhht5M84hvDpiKSnaSLDJZaWpTFGiVS/Rs5CvFhbhq5S94aU=" Mar 12 15:00:07 volumio go-librespot[3347]: time="2025-03-12T15:00:07Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:07 volumio go-librespot[3347]: time="2025-03-12T15:00:07Z" level=debug msg="completed keyexchange" Mar 12 15:00:08 volumio go-librespot[3347]: time="2025-03-12T15:00:08Z" level=debug msg="completed challenge" Mar 12 15:00:08 volumio go-librespot[3347]: time="2025-03-12T15:00:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:09 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:09 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 174. Mar 12 15:00:11 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:11 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:11 volumio go-librespot[3356]: Librespot-go daemon starting... Mar 12 15:00:11 volumio go-librespot[3356]: time="2025-03-12T15:00:11Z" level=info msg="generated new device id: 419fd83ef34c6358557aaf2d0f61950d66c43156" Mar 12 15:00:11 volumio go-librespot[3356]: time="2025-03-12T15:00:11Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:11 volumio go-librespot[3356]: time="2025-03-12T15:00:11Z" level=debug msg="obtained new client token: AABxlnCm7I5QCzeS4hcmvjXCsGiKSCggE198DYDuWWe3H/cWFTFUH7hdhYs8HqctqH6eREOtUI62CbVx8vRvoGYSLExwlp6FF2MMBuh3bmGrYnu32BN7PbG9of12rDBrRcUV3Mgjr8GOerPE50oIBj3yqCtocDLGEKRV0CiO+ik7pPfQsU/ZsdQZESdMIqUMs0HqlK2y2IGDjFV6HIi7BDrTVvd1mq+u0a60YvVWt0pNlT9dV45EL4GlUNWooKw=" Mar 12 15:00:11 volumio go-librespot[3356]: time="2025-03-12T15:00:11Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:11 volumio go-librespot[3356]: time="2025-03-12T15:00:11Z" level=debug msg="completed keyexchange" Mar 12 15:00:12 volumio go-librespot[3356]: time="2025-03-12T15:00:12Z" level=debug msg="completed challenge" Mar 12 15:00:12 volumio go-librespot[3356]: time="2025-03-12T15:00:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:12 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:12 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:15 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:15 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 175. Mar 12 15:00:15 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:15 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:15 volumio go-librespot[3364]: Librespot-go daemon starting... Mar 12 15:00:15 volumio go-librespot[3364]: time="2025-03-12T15:00:15Z" level=info msg="generated new device id: a2e21c7c115dca36d9a08e44e1006596beacab14" Mar 12 15:00:15 volumio go-librespot[3364]: time="2025-03-12T15:00:15Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:15 volumio go-librespot[3364]: time="2025-03-12T15:00:15Z" level=debug msg="obtained new client token: AABVB66vEQo5ZNL5zJ4W/s7oSIaa24o8v5DQXUssx3qw064A3VlLKxsapQoZsSRUmBoG8ljehr4bRsTdKkkqHgxCVOOJ7PNXk5i6nLEmyjnd8LYimeylCn/+tSdm+0r3BKSyubhJlG+GwQfXDuOwnCb8O9J0tI66j9lGu6FcuFtXGud1YFmM+tu4cT6UJaJkcu8ZD7+H1W/lpvaJwvpwptbTel6SXGIF0Vz6fXY4STu5mm0zkOFPk95M92iXyoY=" Mar 12 15:00:15 volumio go-librespot[3364]: time="2025-03-12T15:00:15Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:15 volumio go-librespot[3364]: time="2025-03-12T15:00:15Z" level=debug msg="completed keyexchange" Mar 12 15:00:16 volumio go-librespot[3364]: time="2025-03-12T15:00:16Z" level=debug msg="completed challenge" Mar 12 15:00:16 volumio go-librespot[3364]: time="2025-03-12T15:00:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:18 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:18 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:18 volumio ntpd[809]: 95.110.254.234 local addr 10.10.1.22 -> Mar 12 15:00:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 176. Mar 12 15:00:19 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:19 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:19 volumio go-librespot[3372]: Librespot-go daemon starting... Mar 12 15:00:19 volumio go-librespot[3372]: time="2025-03-12T15:00:19Z" level=info msg="generated new device id: dd3d34f6b52f3eccfeaa0249e8a60ce229d292b0" Mar 12 15:00:19 volumio go-librespot[3372]: time="2025-03-12T15:00:19Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:19 volumio go-librespot[3372]: time="2025-03-12T15:00:19Z" level=debug msg="obtained new client token: AABxMMN417tsWJtYPMr8WMWbF60IxR8gnl3RJQhH55Kxg+dIUg/dBaV+9KbL98eFq81sDZ7+OAq6T5VDeoUX4N3tgynZFWvgZNgg7Q7hRmmgUfNknqQYCHN+lmakJv2FdtMmf8OIjnt+9totzpSxl3HCVz3wLsC9Z7ceX7S7lqp7lNq7Dxr2UK6q/OZ+n8b/PoRwsVDg7HWzuwNoJOiy0WY7pefmUsv2i9EtANOcZ1dJ4KgqBbbXFjoMvcvDSE0=" Mar 12 15:00:19 volumio go-librespot[3372]: time="2025-03-12T15:00:19Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:19 volumio go-librespot[3372]: time="2025-03-12T15:00:19Z" level=debug msg="completed keyexchange" Mar 12 15:00:20 volumio go-librespot[3372]: time="2025-03-12T15:00:20Z" level=debug msg="completed challenge" Mar 12 15:00:20 volumio go-librespot[3372]: time="2025-03-12T15:00:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:21 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:21 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 177. Mar 12 15:00:23 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:23 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:23 volumio go-librespot[3380]: Librespot-go daemon starting... Mar 12 15:00:23 volumio go-librespot[3380]: time="2025-03-12T15:00:23Z" level=info msg="generated new device id: 81687b70a5bbdd7323629620f6df7e91830456a1" Mar 12 15:00:23 volumio go-librespot[3380]: time="2025-03-12T15:00:23Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:23 volumio go-librespot[3380]: time="2025-03-12T15:00:23Z" level=debug msg="obtained new client token: AABswz4XOMqx6FRE0RTtvr9oDvJzPlBI7zlccI75498pd9BXIJdvNc4KHD6coX6MSijysew1NWP/qXQF5yOrEFqqwAmjwlTosbU9V/pUggA53lTNNTlfcRCLUNy3o6SxdNj7UunEgsfgUdm5jvFntp8NMv1dYM7k1Utgl7cdpuC/M+mCkrj5TIfeuDAaoCrbqMfFahA5nsCX9vVWY0ADOhfTW0InoiCBCn6j4zamA983z+rp3O4ybuUvH1+ZW3c=" Mar 12 15:00:23 volumio go-librespot[3380]: time="2025-03-12T15:00:23Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:23 volumio go-librespot[3380]: time="2025-03-12T15:00:23Z" level=debug msg="completed keyexchange" Mar 12 15:00:24 volumio go-librespot[3380]: time="2025-03-12T15:00:24Z" level=debug msg="completed challenge" Mar 12 15:00:24 volumio go-librespot[3380]: time="2025-03-12T15:00:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:24 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:24 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:27 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:27 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 178. Mar 12 15:00:27 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:27 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:27 volumio go-librespot[3426]: Librespot-go daemon starting... Mar 12 15:00:27 volumio go-librespot[3426]: time="2025-03-12T15:00:27Z" level=info msg="generated new device id: bc4f74271422b32db3a657a9a205c3b4666de651" Mar 12 15:00:27 volumio go-librespot[3426]: time="2025-03-12T15:00:27Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:27 volumio go-librespot[3426]: time="2025-03-12T15:00:27Z" level=debug msg="obtained new client token: AACnb5ibw4EnyZdanwNzPfHRnAt/4qyWcZjm2GLHztWCwkole3pv6FvSM8bU+401MBtaHZa33UzwGKaoyB17diWbFy3G9Wb255JC8uj/Gtg7oV2DmFj/WZPX0uS8LWYr1ovJUDNg0bcTFPp3KWfk4kDGf5VeEm40hEfxEnE3TjTGuEksuxiTPCBvFdKWRR4DKSmqlDdPH9x/m0EiAunTM+Qt2ni4TbtVpR/jgmXF479X5hwW6NCPM3pVlTDJz5U=" Mar 12 15:00:27 volumio go-librespot[3426]: time="2025-03-12T15:00:27Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:27 volumio go-librespot[3426]: time="2025-03-12T15:00:27Z" level=debug msg="completed keyexchange" Mar 12 15:00:28 volumio go-librespot[3426]: time="2025-03-12T15:00:28Z" level=debug msg="completed challenge" Mar 12 15:00:28 volumio go-librespot[3426]: time="2025-03-12T15:00:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:30 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:30 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 179. Mar 12 15:00:31 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:31 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:31 volumio go-librespot[3434]: Librespot-go daemon starting... Mar 12 15:00:31 volumio go-librespot[3434]: time="2025-03-12T15:00:31Z" level=info msg="generated new device id: c859237ea3a39d0a71172ee5a222f94534227ec4" Mar 12 15:00:31 volumio go-librespot[3434]: time="2025-03-12T15:00:31Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:31 volumio go-librespot[3434]: time="2025-03-12T15:00:31Z" level=debug msg="obtained new client token: AAB7IAckQSrAnFWMZFJQr/zAYBrerKrzFMQTLN+JAzqHOkIhUNohWJefNoGS6+mDQX0j+WKFGn8E+/wGiwMjqqTlQsZSIdzZEAIwHaKyWuOkKhgz8eagww/guHW4dKVgo0os1namgLc5PSf7Z5WwiiNfnkgHIq+g5TWezXtYl0vXO38dmRA9ijam9DWa4gAWgaYoADtkHJcVkVRQSpO4dDByHXz5JtsjSe15y94sKf8XPlPvNhKe2UjK2QjtzJs=" Mar 12 15:00:31 volumio go-librespot[3434]: time="2025-03-12T15:00:31Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:31 volumio go-librespot[3434]: time="2025-03-12T15:00:31Z" level=debug msg="completed keyexchange" Mar 12 15:00:32 volumio go-librespot[3434]: time="2025-03-12T15:00:32Z" level=debug msg="completed challenge" Mar 12 15:00:32 volumio go-librespot[3434]: time="2025-03-12T15:00:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:33 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:33 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 180. Mar 12 15:00:35 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:35 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:35 volumio go-librespot[3443]: Librespot-go daemon starting... Mar 12 15:00:35 volumio go-librespot[3443]: time="2025-03-12T15:00:35Z" level=info msg="generated new device id: bdd1e6c593f9eaf9d3f0e7265a92deaa64c05f4c" Mar 12 15:00:35 volumio go-librespot[3443]: time="2025-03-12T15:00:35Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:35 volumio go-librespot[3443]: time="2025-03-12T15:00:35Z" level=debug msg="obtained new client token: AACX2mbUnw+ZkrHsuvx3yf607I/uuzrK8D26i4hGOZMymLEc/9sOYoMvSVCJPedv8mOAKpS3k0M6aW9CFkMsM2+IAnhki5F6GZvfzS0CmgBLQ3OMh1lVpeyXpZU4jN0W0mmvecOhP3AwleWyrYgq14n2Ko+zRl/FL/j0o+2lVAxrrRd7N/AMsxMDhiqELnk00ZgSWEXnK715DIbT/KZa9/EnuzrhjGA6OCUxYa175zU9gVlxObw4o6oOhAGH7+E=" Mar 12 15:00:35 volumio go-librespot[3443]: time="2025-03-12T15:00:35Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 12 15:00:35 volumio go-librespot[3443]: time="2025-03-12T15:00:35Z" level=debug msg="completed keyexchange" Mar 12 15:00:36 volumio go-librespot[3443]: time="2025-03-12T15:00:36Z" level=debug msg="completed challenge" Mar 12 15:00:36 volumio go-librespot[3443]: time="2025-03-12T15:00:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:36 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:36 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:38 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Mar 12 15:00:38 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Mar 12 15:00:39 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:39 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 181. Mar 12 15:00:39 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:39 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:39 volumio go-librespot[3451]: Librespot-go daemon starting... Mar 12 15:00:39 volumio go-librespot[3451]: time="2025-03-12T15:00:39Z" level=info msg="generated new device id: 9b5769084211e7e824c1b518af192d6ddcfe000c" Mar 12 15:00:39 volumio go-librespot[3451]: time="2025-03-12T15:00:39Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:39 volumio go-librespot[3451]: time="2025-03-12T15:00:39Z" level=debug msg="obtained new client token: AAA5kbqFOb/qa2PHoSVG31QFhsT6TZZ4YCDj59gif1nPZeMJduPlGJcn9wr554Bj46gfS9juoQvlUXbhVDDAPibzBcCrQDfHvb+dUOEEFsRMl+TdNUrYcrWkUJ96BbK6L85QIhFo2NSgw6E5PwzlXpiRjVxL2f39GqzdRqkd7u2/magjpLq9uGV1uCbt/FSvQzZpraRPSFYad6zfXNd5p9KhKH5bLBY6rkigGuUh2tovVX2JAIam6U6+zk1SYO0=" Mar 12 15:00:39 volumio go-librespot[3451]: time="2025-03-12T15:00:39Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:39 volumio go-librespot[3451]: time="2025-03-12T15:00:39Z" level=debug msg="completed keyexchange" Mar 12 15:00:40 volumio go-librespot[3451]: time="2025-03-12T15:00:40Z" level=debug msg="completed challenge" Mar 12 15:00:40 volumio go-librespot[3451]: time="2025-03-12T15:00:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:42 volumio volumio[868]: info: Initializing connection to go-librespot Websocket Mar 12 15:00:42 volumio volumio[868]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 12 15:00:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 12 15:00:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 182. Mar 12 15:00:43 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 15:00:43 volumio systemd[1]: Started go-librespot Daemon. Mar 12 15:00:43 volumio go-librespot[3459]: Librespot-go daemon starting... Mar 12 15:00:43 volumio go-librespot[3459]: time="2025-03-12T15:00:43Z" level=info msg="generated new device id: 590a87d20798275b3325d4df1ef10a943750eac9" Mar 12 15:00:43 volumio go-librespot[3459]: time="2025-03-12T15:00:43Z" level=debug msg="stored credentials found for adonzell" Mar 12 15:00:43 volumio go-librespot[3459]: time="2025-03-12T15:00:43Z" level=debug msg="obtained new client token: AACEdmvT/3O5pcAIOlo4mPOtDqy44cuek3Dkq3pCNSm+qUkZO4YhOXKFEWj0X8Ia/LjLf6e0a3LYflOTArhknzr7WWGxY5loVr+3YqhsAIXkDpbLjMaXqHp+WnBZjcnfxHO04vzRU2tUfobLeTqKVzxALI5cu6nhucxh6Zayda6P+5uBhIS9q16/HJNTT10ALqrjSSMuMIj//rvOoLurXbVEziwP2Ub9SBRNHayKXfJy3Wxk0/Eas0BqJIqyn8E=" Mar 12 15:00:43 volumio go-librespot[3459]: time="2025-03-12T15:00:43Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 15:00:43 volumio go-librespot[3459]: time="2025-03-12T15:00:43Z" level=debug msg="completed keyexchange" Mar 12 15:00:43 volumio volumio[868]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Mar 12 15:00:43 volumio volumio[868]: info: PLUGIN onReboot : networkfs Mar 12 15:00:43 volumio sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/DSD_MUSIC Mar 12 15:00:43 volumio sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 15:00:43 volumio systemd[1]: mnt-NAS-DSD_MUSIC.mount: Succeeded. Mar 12 15:00:43 volumio sudo[3468]: pam_unix(sudo:session): session closed for user root Mar 12 15:00:43 volumio sudo[3476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Leftovers Mar 12 15:00:43 volumio sudo[3476]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 15:00:44 volumio systemd[1]: mnt-NAS-Leftovers.mount: Succeeded. Mar 12 15:00:44 volumio sudo[3476]: pam_unix(sudo:session): session closed for user root Mar 12 15:00:44 volumio sudo[3480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Music Mar 12 15:00:44 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 15:00:44 volumio go-librespot[3459]: time="2025-03-12T15:00:44Z" level=debug msg="completed challenge" Mar 12 15:00:44 volumio go-librespot[3459]: time="2025-03-12T15:00:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 12 15:00:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 12 15:00:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 12 15:00:44 volumio systemd[1]: mnt-NAS-Music.mount: Succeeded. Mar 12 15:00:44 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root Mar 12 15:00:44 volumio volumio[868]: info: PLUGIN onReboot : audiophonicsonoff Mar 12 15:00:44 volumio volumio[868]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 15:00:44 volumio volumio[868]: TypeError: Cannot read property 'writeSync' of undefined Mar 12 15:00:44 volumio volumio[868]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Mar 12 15:00:44 volumio volumio[868]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Mar 12 15:00:44 volumio volumio[868]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Mar 12 15:00:44 volumio volumio[868]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 12 15:00:44 volumio volumio[868]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 12 15:00:44 volumio volumio[868]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Mar 12 15:00:44 volumio volumio[868]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Mar 12 15:00:44 volumio volumio[868]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Mar 12 15:00:44 volumio volumio[868]: at Socket.emit (events.js:315:20) Mar 12 15:00:44 volumio volumio[868]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 12 15:00:44 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 12 15:00:44 volumio volumio[868]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 15:00:44 volumio sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 14:59 Mar 12 15:00:44 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"