-- Logs begin at Tue 2025-04-22 14:58:45 CEST, end at Tue 2025-04-22 16:46:19 CEST. -- Apr 22 16:45:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3052. Apr 22 16:45:00 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:00 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: Librespot-go daemon starting... Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=info msg="generated new device id: 9e4ff17717bb0d931e4a5578916946bcf9c3897b" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" 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]" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="zeroconf server listening on port 37339" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="obtained new client token: AABWWUynlMcDipHAog9mNbp2OepF/z/x6b4zeich6BvZsX3mcPz/4KizEJ6HseBF8di+RYv6GuYA9sc0JMw4A8KWr8ZWPM6DToGHpIHgqty99K1bo+lJzmmH0QJEiEnvoBS4fVjQMqa0G2S7xYUXQ/d40nsvbnNrltryna8AxVmGidOhDZuYsTbCzTzTplhT9SdfAHQvX2kJJu0XUjUbpLIgPLC28KHQaNWNk/w6xgMEM1DnvXEUwnjqJOyi" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=info msg="connected to ap-gew4.spotify.com:443" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=debug msg="completed challenge" Apr 22 16:45:00 volumio-boss-dac go-librespot[14903]: time="2025-04-22T16:45:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:01 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:01 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:04 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:04 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3053. Apr 22 16:45:04 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:04 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: Librespot-go daemon starting... Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=info msg="generated new device id: 2f1388171f48b52022fe27b0af771e0962c3470b" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" 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]" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="zeroconf server listening on port 37631" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="obtained new client token: AABkXwhlqFGD9HaOtSmowZu7eIBrj9X20DqrJShJyEWx0hWJsfiP8PpA5HDE+ECUGWf+9RtgCqpzbmvYnvqjMvqpKSqlRWK2gQFIfI8NfLL/jQ26v96V2CvVxesUE43xSNI9d9DbLY7OSUM8ZvnYelXvFGDubWA8arI3aJe9EYWixQEugEwNWRVGYH+rNua+GcjsRgcOstlg/tKmNFcsK44c8o/c46hhHuQaW1htb7rLBM2lVzLr+19iNj6m" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=debug msg="completed challenge" Apr 22 16:45:04 volumio-boss-dac go-librespot[14919]: time="2025-04-22T16:45:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:07 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:07 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3054. Apr 22 16:45:08 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:08 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: Librespot-go daemon starting... Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=info msg="generated new device id: 523f96a699b2826d14921db29ad7c4bc90e211dc" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" 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]" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="zeroconf server listening on port 41493" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="obtained new client token: AADef6C/MGQPk4BCEorKlknmoJcNeRK2bGlYKF9rPLHhnBeBrQfkNQlJnbeP+3bafnOR771BRGpUmr2OKPfOZXDVA0n5f+WimGnkl/whNFFLctKN2maFx6nmAoJk0QjSFM0OIcdNbXck3kvTKg8xAUdMUs4PZCRo1lTtaHVFlJdXldi/XLHaPlFTGrKtL+2J6YOgYaJxJ/bYARGCkutAgWpcNJYQGx+6HgK4gTwcY8HMWCeUIHjpb3Yx5F08" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:08 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:08 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:08 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=debug msg="completed challenge" Apr 22 16:45:08 volumio-boss-dac go-librespot[14947]: time="2025-04-22T16:45:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:10 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:10 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3055. Apr 22 16:45:12 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:12 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: Librespot-go daemon starting... Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=info msg="generated new device id: a6a0dc775f2163cf06a6ba0b204c27f1122885fd" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" 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]" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="zeroconf server listening on port 44551" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="obtained new client token: AADgsoE+v2BA+gDvoG+lA8PJUqNJuk6Tx50niOEODsap+jTXtqV+cC/AHr9R1OL6TJdUVg+X/PtYpgTSLNkT4F9oxczs335bIJFVt1HEgQWJrflNxUPPr4VQfZkuSsBHZ/dHHOXBCjKWIkmEttbUZzIeTTvhHfZCi5DNGO3kAsZvADUsF6I9tQ8knIRey/L6PKuYS9eJjc5TRfgAQFCdg8wqltSGZA9+pZ1kcv98jEBnvkiOy/1kWJO4F1EV" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=debug msg="completed challenge" Apr 22 16:45:12 volumio-boss-dac go-librespot[14963]: time="2025-04-22T16:45:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:13 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:13 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:16 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3056. Apr 22 16:45:16 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:16 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:16 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: Librespot-go daemon starting... Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=info msg="generated new device id: 38539ab5133f81852ff943eb1655b6a7502de1bb" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" 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]" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="zeroconf server listening on port 43477" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="obtained new client token: AAD5qlOhM2J03rV1HYYoK1vhQq/F5gxnSC+/5c/rWrIVwTXAdI8ZFGccOKMwnfIimG0w8LlixePJjnPBHIzyUcU/tQLF3gZrTm2Lun9gYMFpTTOCXCf1fvTEda7BKx7cR98fNN+3VibvP5b8qODdZXOlzW9rlRx0MwO3tHsDhU8U/PWE8ICWT0BGICTgXst0nWlvEGLZWY/xDFsakvry+9pJAI8PwUvCoxTUzDrE+3R9gbiw1kF0pOEYCAL6" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=debug msg="completed challenge" Apr 22 16:45:16 volumio-boss-dac go-librespot[14979]: time="2025-04-22T16:45:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:18 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:19 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:19 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:20 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:20 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3057. Apr 22 16:45:20 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:20 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: Librespot-go daemon starting... Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=info msg="generated new device id: cc05e0f4584e7ef976d48bb007f7eb063fa17b82" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" 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]" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="zeroconf server listening on port 37713" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="obtained new client token: AABE9dfDQhcgxqcdYoRndMsj9xUYzSrt5wFnZsFlRuI7vjzy4lTW39QN0XVeqW8VCywnlP+mf9tTF4Qfkk5MzKni30wutR+HDwB2KnFsOGoWIcOBk/T58eMblGlr3a9fMSX1bxzwAXvc28pFu3LrV4eGS4099skM1ZWNNVOPBmTUYsCZLfXbRunlWeIVnf3F3b2jx4Vrewxe7ZN96t2abwctzAklsLRnRj1pG7PfJoacntZO5rizDcchu/ay" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=debug msg="completed challenge" Apr 22 16:45:20 volumio-boss-dac go-librespot[15006]: time="2025-04-22T16:45:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:20 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:20 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:22 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:22 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:24 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:24 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3058. Apr 22 16:45:24 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:24 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: Librespot-go daemon starting... Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=info msg="generated new device id: 449f33506a24d20e72a74ecad3b21a70063b928c" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" 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]" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="zeroconf server listening on port 36703" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="obtained new client token: AACQEY358Qyip/uVsiqfLdQiegKRVypgdz23QR1RbA0iu6gxufUeeWqYsMX3EWXGpxQsSXVmMNpaDLWvxc3CcycVKsyldRBD5CkBzthKbYQXKOu12qYN1/Slu2RsNpiS7WnF79WU4gmDbGv/Uo1daxi5/dGssVgAp4lCPELNlUDbdOD+vPfRbpqgxV0oC4L5061U4f6ar/4n95tV1AMukKuvEREOfZ6Q8uTwIU1rZVzmeh74Tg/pMOzc4G3e" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=debug msg="completed challenge" Apr 22 16:45:24 volumio-boss-dac go-librespot[15023]: time="2025-04-22T16:45:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:24 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:24 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:25 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:25 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:28 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:28 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3059. Apr 22 16:45:28 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:28 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:28 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:28 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: Librespot-go daemon starting... Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=info msg="generated new device id: 36d6a5eaaaa6500c83f2ba97c0192033e9fa0e60" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" 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]" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="zeroconf server listening on port 37377" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="obtained new client token: AABdgCHSAlea3e6wz/hFg7Xc4Jastl43HXtR/TlvEDXNfPEEqsIP2OzbTy4SDX7ssXHEvyge+Ht9eDqSPUgJduToiYJg9Hz717Du12h6gA8p7YyitJeEttDikgzQVcY7Oe4668524/IYl27/k3Mtq8yt4Ev3r+QQkend5le9ekd9ZmxWWkRAhzH1xBW8ps8RWeF5VrFEJgldM5n/J2XhOLPT0mGk6dFHJzOUY7/xfIzkqKRrmhlfWjV4gKQP" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:28 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:28 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:28 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=debug msg="completed challenge" Apr 22 16:45:28 volumio-boss-dac go-librespot[15049]: time="2025-04-22T16:45:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:28 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:28 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:31 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:31 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:32 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:32 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3060. Apr 22 16:45:32 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:32 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: Librespot-go daemon starting... Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=info msg="generated new device id: 4b76c84875ebdbbabeccda6baeaf61729a962f5f" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" 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]" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="zeroconf server listening on port 33263" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="obtained new client token: AACVCBY7GMpmUnza9DhYCYXVpLX75+3oUdFfBKx5HRE0anoxm/6ooStHirIV1T2pfssqK+J/t5KfCMVeSKcwLtecZZ64vG9DqWIkjooDzd45N+dPm4q2firOuXhcACUFCV4YlZEW/q2/igkRNTLFhC3BoPDCY/qEI3HpumcKU4FXu2Dh8YwUu5ktId2AUwx0nhks1FGxX5SDMX8IWmY0TRSzNMiztZsjyBYfYe2XCejkaI8c/sEAl6JYXPd2" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=debug msg="completed challenge" Apr 22 16:45:32 volumio-boss-dac go-librespot[15066]: time="2025-04-22T16:45:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:32 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:32 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 4 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cars - Good Times Roll from the 1978 album Cars","artist":"novaclassicrock.com","album":null,"uri":"http://mediaserv68.live-streams.nl:8070/stream","trackType":""} Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::play index undefined Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::startPlaybackTimer Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: [1745333133322] ControllerWebradio::clearAddPlayTrack Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 30ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 29ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 28ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand stop took 6 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 16:45:33 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:33 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:33 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:33 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:33 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand clear took 8 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand load "http://mediaserv68.live-streams.nl:8070/stream" Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand add "http://mediaserv68.live-streams.nl:8070/stream" Apr 22 16:45:33 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:33 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 601ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 599ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand add "http://mediaserv68.live-streams.nl:8070/stream" took 1 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand play Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand play took 4 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: Apr 22 16:45:33 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://mediaserv68.live-streams.nl:8070/stream","trackType":""} Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://mediaserv68.live-streams.nl:8070/stream","trackType":""} Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://mediaserv68.live-streams.nl:8070/stream","trackType":""} Apr 22 16:45:33 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 32ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 31ms Apr 22 16:45:33 volumio-boss-dac volumio[813]: info: ------------------------------ 32ms Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:34 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:34 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:34 volumio-boss-dac volumio[813]: info: Clearing queue after UPNP request Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::ClearQueue Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::clearPlayQueue Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::saveQueue Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushQueue Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Starting UPNP Playback Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Preparing playback through UPNP Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: options Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: options Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: options Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 4 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 1 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: No code Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 27ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 27ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 26ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 10 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 12 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 11 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 18ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Apr 22 16:45:35 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 9 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 4 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 32ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 31ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 13 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 30ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 25ms Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"MUpuBOxhhEo7kaE-ROZRXw.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/MUpuBOxhhEo7kaE-ROZRXw.mp3","trackType":"mp3"} Apr 22 16:45:35 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:35 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:35 volumio-boss-dac volumio[813]: info: ------------------------------ 26ms Apr 22 16:45:36 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:36 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3061. Apr 22 16:45:36 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:36 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: Librespot-go daemon starting... Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=info msg="generated new device id: d8cd734407ab826b9c4281eab749fe72b24569b7" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" 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]" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="zeroconf server listening on port 38405" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="obtained new client token: AACAq9uiR9GSM1nUNY3c70ju4yUcDCWKcpcwTOLX5iEOLJPNEfKavLj/gj8kJfvQyryXyI8r6osyIacKAxbHEss9Iy+jdXZ6ZWbQkfhRkipZCTUqm7ISbL0M6ZPRsKiM4vwXnExLxSqWQzBQkO+oahiDvD3gkmolpEzzH0cEzOaniRa9lU1TWasvhrnp1KYF4d51TDBBVNJMY0u2S1TzTmqB9MHJy4k543RDlDshn4ZXW6A5O141UbGTgItg" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=debug msg="completed challenge" Apr 22 16:45:36 volumio-boss-dac go-librespot[15108]: time="2025-04-22T16:45:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:36 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:36 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:37 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:37 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:38 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:38 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:38 volumio-boss-dac volumio[813]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:38 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:281:22 Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:284:7 Apr 22 16:45:38 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:38 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:45:40 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:40 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3062. Apr 22 16:45:40 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:40 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:40 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:40 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: Librespot-go daemon starting... Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=info msg="generated new device id: c72273ca94e2cce08b49ef3f1b2654473481fd75" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" 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]" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="zeroconf server listening on port 42721" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="obtained new client token: AACZkesVAGievIj+Iz9lYBHIhiWpWFWROwtVbeEVy0+G0t5ze1g4dQWasr57N/GcBIIaohYO4aM7pgWTuLTsX2Vne9imdil8mt37BO14eQKhM5chxL9+1xClgLsvwBpefRNp0zYrI7BGw7cayncWiX8FVviwIzWw/OzxPkI64XWwgTfCIwQnn97EXQSnfAKlwS7UrXl4w5bWy0bdE25Sycm3ByK6YPgK1nUCje7rSI5pGHQAhypdYtlnUcMR" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=debug msg="completed challenge" Apr 22 16:45:40 volumio-boss-dac go-librespot[15138]: time="2025-04-22T16:45:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:40 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:40 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: Apr 22 16:45:42 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: Apr 22 16:45:42 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: Apr 22 16:45:42 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:42 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:42 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:42 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:42 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ------------------------------ 11ms Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ------------------------------ 11ms Apr 22 16:45:42 volumio-boss-dac volumio[813]: info: ------------------------------ 9ms Apr 22 16:45:43 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:43 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:43 volumio-boss-dac volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 22 16:45:43 volumio-boss-dac volumiologrotate[553]: ls: cannot access 'BOSS': No such file or directory Apr 22 16:45:43 volumio-boss-dac volumiologrotate[553]: ls: cannot access 'DAC': No such file or directory Apr 22 16:45:44 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:44 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3063. Apr 22 16:45:44 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:44 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: Librespot-go daemon starting... Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=info msg="generated new device id: d9e6b02a303b9c5020476c15933c5ebffb2fad97" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" 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]" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="zeroconf server listening on port 39981" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="obtained new client token: AAAiXPCAkurwE0ftD7uXW2waU8qSg9eX/crfqwixyg3JFc3E+IUZcWO6gybAM6PeNzrHgQqv6sgppA416f0+NgjuDecIOThWv79chAmdzkppuwT/xlR0dbNK+L0wmf3hYthHGlHDeJvjq4n16p1n/ODK3Nj3Y6q9j0V2OdBTiAg/ZvPfcCoNMxIdP4qjJj8sEMn5ugWqUlfOf6nW9IfGv1ib0LvDa7Qgu9cLyRW1nzato2anS5ZAs/h2VPwx" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=debug msg="completed challenge" Apr 22 16:45:44 volumio-boss-dac go-librespot[15197]: time="2025-04-22T16:45:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:44 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:44 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:46 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:46 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:48 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:48 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3064. Apr 22 16:45:48 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:48 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: Librespot-go daemon starting... Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=info msg="generated new device id: 3f98e4c41acfe5ef61f65cbfd6233ffb1eafb738" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" 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]" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="zeroconf server listening on port 46027" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="obtained new client token: AAB9lp8k13IzpDnRn6Q57ghSinGo2cVV8QVm0VTIRw0RvQec82XvJh9uHcYiaR42APHl2ls16CzDCWd3RDIeHyqrYb1eJL7dONeOjyGtUu6pmnNs5LYimNnWXW9sUKtCT9Siu7fg1mgml8+kgtb44L6BUWSyXX+eHVOsFCRGzCB+vrzweYGrrIGytl/Lk7vmlVmdC+TbtCl0MZmhARWBmYAmE2azvSbawo2QT7VD/MEinrTK6YaeU8iU1UB3" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:48 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:48 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:48 volumio-boss-dac volumio[813]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:48 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:281:22 Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:284:7 Apr 22 16:45:48 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:48 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:45:48 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:48 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=debug msg="completed challenge" Apr 22 16:45:48 volumio-boss-dac go-librespot[15216]: time="2025-04-22T16:45:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:48 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:48 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:49 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:49 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:52 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:52 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3065. Apr 22 16:45:52 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:52 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:52 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:52 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: Librespot-go daemon starting... Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=info msg="generated new device id: c842acc4b93c24d489911bce306a685264decbf9" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" 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]" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="zeroconf server listening on port 44445" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="obtained new client token: AADha2HviMuWy0HeAxSHXh4yyUYZ9h2jlHo4h9mrnL1acDvORRZ9gc52iUC3ZI6f6lvr8ULmr1+9GUOamMCeZPg7OY+QFypLPBjgRpkJom4hpNwOrfvp93EIyCkQOsMs2sHqBRK3qbMmlqghCDkKPw4jGTs2qEYXkc5R5rE56a6UrUcMy0ht10POLnMcwowjS5yMgjWLkoPZsLZUA1uWURg/kNKdQ9HFnOcy1wM6LW6c7VusB1eOgwY35eWj" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=debug msg="completed challenge" Apr 22 16:45:52 volumio-boss-dac go-librespot[15224]: time="2025-04-22T16:45:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:52 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:52 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Clearing queue after UPNP request Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::ClearQueue Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::stop Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::stPlaybackTimer Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::updateTrackBlock Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrackBlock Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::serviceStop Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::stop Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::clearPlayQueue Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::saveQueue Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushQueue Apr 22 16:45:53 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:53 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand stop took 9 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Starting UPNP Playback Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Preparing playback through UPNP Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 16 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 20 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 18 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 29ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Apr 22 16:45:53 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 12 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":672,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 36ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 34ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FgpMFaoawH6nqSSV6Z6WCQ.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/FgpMFaoawH6nqSSV6Z6WCQ.mp3","trackType":"mp3"} Apr 22 16:45:53 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 28ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 18ms Apr 22 16:45:53 volumio-boss-dac volumio[813]: info: ------------------------------ 17ms Apr 22 16:45:55 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:55 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:56 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:45:56 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3066. Apr 22 16:45:56 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:45:56 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: Librespot-go daemon starting... Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=info msg="generated new device id: 70f94afb9ee8a75a53f6341153d1ecc0ac133b06" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" 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]" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="zeroconf server listening on port 41157" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="obtained new client token: AAAaQL942afyshQ65vlePFvq0pNJwgsM+uzrMcb1mtl9wZrb2vGA9knslKn/l5XCXuZ9w5YiXDkfx5GxV1BE+XMR7pebLM2V0LcerHZb4ISEIpZd2vWsAfVeOCPufq3IpdQTJM6XxUq7ITT/zsNpM6ERdgWYtJTSRBIoQTb0WG+Jjoq4b4LDVN/dxGsoN7IeVe72A4Bxum71ht9nj3lO8HAYlLHr9p1MCcCn0/lzixux5Tm7/0wQcwALqMtA" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="completed keyexchange" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=debug msg="completed challenge" Apr 22 16:45:56 volumio-boss-dac go-librespot[15264]: time="2025-04-22T16:45:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:45:56 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:45:56 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:45:58 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:45:58 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:45:58 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:45:58 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:45:58 volumio-boss-dac volumio[813]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Apr 22 16:45:58 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:281:22 Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:284:7 Apr 22 16:45:58 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:45:58 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:46:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:46:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3067. Apr 22 16:46:00 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:46:00 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: Librespot-go daemon starting... Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=info msg="generated new device id: abf936f88cd25c04989c0d93e658be37dd3d7736" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" 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]" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="zeroconf server listening on port 40747" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="obtained new client token: AABCCb+sil6bmiFPWOrBmoXcCLQc3sRcLbQ/IYalcliy0pd25m04s1EA5G7BphHh2wBRqblE5OsVTgX4Y3uilN5xDfL9geiQZ25OUj7mpX1pkdxDRNRqeDA6hjtQ8FBK4pklu3aEii4gbEJ+CQfN6udL0F240imhS1fSCT+h3hEim0LOr+/L4nA13GY2rex//CN3rRSMyJ30kPKXGiLAJtHB0lEwgQa9PHHyXJSwPi/6cW67mez4WiFItYOV" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="completed keyexchange" Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: Apr 22 16:46:00 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: Apr 22 16:46:00 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: Apr 22 16:46:00 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 4 milliseconds Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 3 milliseconds Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 2 milliseconds Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:00 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:00 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:00 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ------------------------------ 12ms Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ------------------------------ 10ms Apr 22 16:46:00 volumio-boss-dac volumio[813]: info: ------------------------------ 9ms Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=debug msg="completed challenge" Apr 22 16:46:00 volumio-boss-dac go-librespot[15290]: time="2025-04-22T16:46:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:46:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:46:00 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:46:01 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:01 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:46:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3068. Apr 22 16:46:04 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:46:04 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: Librespot-go daemon starting... Apr 22 16:46:04 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:04 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=info msg="generated new device id: 1ba2de7076e7b01337a2b1c11020adb665e7f2f9" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" 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]" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="zeroconf server listening on port 40883" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="obtained new client token: AADpDtSwwQEUpPcdk2ucNEUf6f1pkLzvWhkWJSlQyfZK1otphtXyzqt9hgDyoL8IIG8MUrkbpxc9GibX+GJSaXLB6A2XkMGYkZ9oRzc2Bxrvw11PCvvg2eYT8C3l49Hk4kTS1rElgliyj4oCe5aUJfA5S3yjFT263KiDse1u1KoLjlChmbTPv4ykam+r4xGyIIu6TaUB3br697HgBYsT0uN3BJNSrT/5zF0aXusXyT++GNm+G7ZMssrC5OSE" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="completed keyexchange" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=debug msg="completed challenge" Apr 22 16:46:04 volumio-boss-dac go-librespot[15302]: time="2025-04-22T16:46:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:46:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:46:04 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:46:07 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:07 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:46:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3069. Apr 22 16:46:08 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:46:08 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: Librespot-go daemon starting... Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=info msg="generated new device id: d759df6cac4c264bdb56aa8ee849e1bbb464df23" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" 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]" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="zeroconf server listening on port 34967" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="obtained new client token: AAAhQVjAW8i8/nugzf1C5DrVmjS/vNyWiOgYUkWdHi7QxGShb/lPKP5lybIonzOTXv8VYgj3kGuM0H6jyDyFkTllCdb2QujbTZDnZEsxt7Hu4xKs7vYdjLeFVmaSWmbLYEgprqTcmz4S1KD58cYoMbxgspLri/6688Hm8KR/IYPjCAVJ67qo72uM8O8jWNuYKYtL6bPW4Deymsk5IIUNTT+ccpVyp49P27zShlYGRYBwW6jKCt9j7+bgiu0O" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=info msg="connected to ap-gew4.spotify.com:443" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="completed keyexchange" Apr 22 16:46:08 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:46:08 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:08 volumio-boss-dac volumio[813]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:08 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:281:22 Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:284:7 Apr 22 16:46:08 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:46:08 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:46:08 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:46:08 volumio-boss-dac volumio[813]: info: Listing playlists Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=debug msg="completed challenge" Apr 22 16:46:08 volumio-boss-dac go-librespot[15321]: time="2025-04-22T16:46:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:46:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:46:08 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:46:10 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:10 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:46:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3070. Apr 22 16:46:12 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:46:12 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: Librespot-go daemon starting... Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=info msg="generated new device id: fd77ce1515e5249e4bf907c12f50383129545b6f" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" 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]" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="zeroconf server listening on port 46683" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="obtained new client token: AABt0Cvb61iuUAiXiMr4otGpeugNd/833HRkcYSnOhtnWEv+IrKiZjCp8PJr3Z/ToKSscBvz3LXP7AG4urcyujy0iSuQXIRrayocp8Mw8VBuQaq8mQ4iCHdD5LpYooUh1Y6HbK1xhXKSfmyhGVApLU585r61dxohtZCnLHf5e7viurAvrUVhxCBB70cWPnKbEKCJOaiAvsSILJ0sFJe4bMBk4GvaUHqHpwglbVQz3e/3OOTouMfdawEuxt4t" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="completed keyexchange" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=debug msg="completed challenge" Apr 22 16:46:12 volumio-boss-dac go-librespot[15329]: time="2025-04-22T16:46:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:46:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:46:12 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:46:13 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:13 volumio-boss-dac volumio[813]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 16:46:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3071. Apr 22 16:46:16 volumio-boss-dac systemd[1]: Stopped go-librespot Daemon. Apr 22 16:46:16 volumio-boss-dac systemd[1]: Started go-librespot Daemon. Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: Librespot-go daemon starting... Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=info msg="generated new device id: 0838544423574e471b1daf68a421c2af3a2da7a9" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="stored credentials found for joachimdriessen" Apr 22 16:46:16 volumio-boss-dac volumio[813]: info: Initializing connection to go-librespot Websocket Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="new websocket client" Apr 22 16:46:16 volumio-boss-dac volumio[813]: info: Connection to go-librespot Websocket established Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" 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]" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="zeroconf server listening on port 33197" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="obtained new client token: AAC5d1dPWgC09LWb//IjuCu0hVnRWvudI4ElqOcAFoUhyGD9Sj8Io8qzIo0FA8nYPQWGsaEQ64NuBomagJt4bV0PDFstA8lR18GryNN1JxRg5Ofpz3fwMnUCXB14GuyEpuUfNKBfBWmRKwa5vhEQYtiRpge0XywoxSIyxzXhswQZd20tpuST+Bgs7F4zxiSNxzi3/88qyMBXDNh4oZNyawz4rcMvHazzisgibUTTlg6PEtlzAMq6dSrIztk+" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="completed keyexchange" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=debug msg="completed challenge" Apr 22 16:46:16 volumio-boss-dac go-librespot[15337]: time="2025-04-22T16:46:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 22 16:46:16 volumio-boss-dac volumio[813]: info: Connection to go-librespot Websocket closed Apr 22 16:46:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 16:46:16 volumio-boss-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Clearing queue after UPNP request Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::ClearQueue Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::stop Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::stPlaybackTimer Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::updateTrackBlock Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrackBlock Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::serviceStop Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::stop Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::clearPlayQueue Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::saveQueue Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioPushQueue Apr 22 16:46:17 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:46:17 volumio-boss-dac volumio[813]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand stop took 9 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 1ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Starting UPNP Playback Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Preparing playback through UPNP Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: updateQueue error: null Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 10ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 9ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces system playlist update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Ignoring MPD Status Update Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 4ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 3ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 2ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 17 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 16 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 15 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Apr 22 16:46:17 volumio-boss-dac volumio[813]: ---------------------------- MPD announces state update: player Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::getState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand status Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 16 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 9 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand status took 7 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus stop Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 54ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 53ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 53ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: ControllerMpd::parseTrackInfo Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ControllerMpd::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreCommandRouter::servicePushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: In UPNP mode Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"viutn2V9oX6s9Q8EsUOSRA.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.2.9:8123/api/tts_proxy/viutn2V9oX6s9Q8EsUOSRA.mp3","trackType":"mp3"} Apr 22 16:46:17 volumio-boss-dac volumio[813]: verbose: CURRENT POSITION 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState stateService play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::syncState currentStatus play Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: Received an update from plugin. extracting info from payload Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CoreStateMachine::pushState Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 55ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 44ms Apr 22 16:46:17 volumio-boss-dac volumio[813]: info: ------------------------------ 42ms Apr 22 16:46:18 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:46:18 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:18 volumio-boss-dac volumio[813]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:18 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:281:22 Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at /volumio/node_modules/express/lib/router/index.js:284:7 Apr 22 16:46:18 volumio-boss-dac volumio[813]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Apr 22 16:46:18 volumio-boss-dac volumio[813]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Apr 22 16:46:19 volumio-boss-dac volumio[813]: info: Getting Spotify volume Apr 22 16:46:19 volumio-boss-dac volumio[813]: (node:813) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 16:46:19 volumio-boss-dac volumio[813]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 16:46:19 volumio-boss-dac volumio[813]: (node:813) 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: 159) Apr 22 16:46:19 volumio-boss-dac volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 22 16:46:19 volumio-boss-dac volumio[813]: info: CoreCommandRouter::volumioGetState Apr 22 16:46:19 volumio-boss-dac volumio[813]: info: CorePlayQueue::getTrack 0 Apr 22 16:46:19 volumio-boss-dac volumio[813]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 16:46:19 volumio-boss-dac volumio[813]: TypeError: Cannot read property 'name' of undefined Apr 22 16:46:19 volumio-boss-dac volumio[813]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 22 16:46:19 volumio-boss-dac volumio[813]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 22 16:46:19 volumio-boss-dac volumio[813]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Apr 22 16:46:19 volumio-boss-dac volumio[813]: at Socket.emit (events.js:315:20) Apr 22 16:46:19 volumio-boss-dac volumio[813]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 22 16:46:19 volumio-boss-dac volumio[813]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 22 16:46:19 volumio-boss-dac volumio[813]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 16:46:19 volumio-boss-dac sudo[15384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 16:45 Apr 22 16:46:19 volumio-boss-dac sudo[15384]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"