Nov 01 17:32:00 volumio go-librespot[1572]: time="2025-11-01T17:32:00Z" level=debug msg="completed keyexchange" Nov 01 17:32:00 volumio go-librespot[1572]: time="2025-11-01T17:32:00Z" level=debug msg="completed challenge" Nov 01 17:32:00 volumio go-librespot[1572]: time="2025-11-01T17:32:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:01 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:01 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 453. Nov 01 17:32:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:03 volumio go-librespot[1581]: go-librespot daemon starting... Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="app state loaded" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=info msg="zeroconf server listening on port 34677" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="obtained new client token: AADAu9xgXtU1fQCR7+IIZHQCfPE5A0zO2QHEe384U34Tlha3DsAxzp3cFtCjz2MDIyBnGu7n8OSflUklcxkvTxfLW/TeMIkso5pnoJ1hsOc2zfU0uV5F0a8jXDpQOCM0jjKc3meHeRlL3U2GQaFDYeOrA+lv6BRWOqhVmeUrVACZTNMgIpUbgYvca3/uhtSflgaIi1dFFiNSPRQH75fIHphLjf/NlmxdhJh9DdUbNmn166+aU+evsRU=" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="completed keyexchange" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=debug msg="completed challenge" Nov 01 17:32:03 volumio go-librespot[1583]: time="2025-11-01T17:32:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:04 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:04 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 454. Nov 01 17:32:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:06 volumio go-librespot[1609]: go-librespot daemon starting... Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="app state loaded" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=info msg="zeroconf server listening on port 35827" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="obtained new client token: AABX5DS/Qby8B3teoGrpl/1xa7N1gOSwq05JtVvIpPLq6xmicnsst6n3B2inbp6M7viw3wAzcWXUe//m2dRlNntPb6TpsbfimZjJD3Q2d8NBA43Oivyjjgwge/AO5k24waqCze804y3xqY0deHSIthrY0BqatZXoxxSoeeWZHzmAH/u9+00pOXFz0UPGyBeL+8NO6v3GsUpbr9tRw2O/qqXlp7cw4BAarxGax5x8e9RSc/YbR3CJJ4M=" Nov 01 17:32:06 volumio go-librespot[1610]: time="2025-11-01T17:32:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:07 volumio go-librespot[1610]: time="2025-11-01T17:32:07Z" level=debug msg="completed keyexchange" Nov 01 17:32:07 volumio go-librespot[1610]: time="2025-11-01T17:32:07Z" level=debug msg="completed challenge" Nov 01 17:32:07 volumio go-librespot[1610]: time="2025-11-01T17:32:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:07 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:07 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 455. Nov 01 17:32:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:10 volumio go-librespot[1617]: go-librespot daemon starting... Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="app state loaded" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=info msg="zeroconf server listening on port 39065" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="obtained new client token: AAA5ngf2awWS28a90H5h7051cqM1S/O/8Jj0JJWGcHRDmyi6U5zRzkK5zUrSbBvBdFP9FXNTDR2Yyp60hN3liAL0+hIHOds5hcHrKHwSrTwoAYQuq4bGDlYb3pDW6FH1yN6gxZKYrnbICtMsqcQ1hlshVCh0nOTMGy82CNRh/oAmOf9UBl9qNJmsbr5UO4Q27YF2uj/nEJgPago47jmPr/PpN5rroomU/lQjpAMV7bDigJq++Bd5VU8=" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="completed keyexchange" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=debug msg="completed challenge" Nov 01 17:32:10 volumio go-librespot[1618]: time="2025-11-01T17:32:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:10 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:10 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 456. Nov 01 17:32:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:13 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:13 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:13 volumio go-librespot[1628]: go-librespot daemon starting... Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="app state loaded" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=info msg="zeroconf server listening on port 43605" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="obtained new client token: AACNsgkZFtYavjIjJ5jE7AGmAF5yafZ75uEeEFFYdZwpf/eUJoXRPtRvhqRQgOU76cjdV2FVbuQqUQdrvqaWc7IYiwyOYdv/PhxYTKcO3k1Vy7v4+RnR1sx4LAWKTjBwXbr2a354fLImJ2UEhG8anJVbbm/jWpeJuJfiOg+zqZwf4QafDl79MVpJqzzQWxjOxXUnTa43JcPMvHcwEAv1TN+jBUV37XdlQY/89eY4QLMes4SWbbZUBTo=" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="completed keyexchange" Nov 01 17:32:13 volumio go-librespot[1629]: time="2025-11-01T17:32:13Z" level=debug msg="completed challenge" Nov 01 17:32:14 volumio go-librespot[1629]: time="2025-11-01T17:32:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:16 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:16 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 457. Nov 01 17:32:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:17 volumio go-librespot[1650]: go-librespot daemon starting... Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="app state loaded" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=info msg="zeroconf server listening on port 46031" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="obtained new client token: AABcRLcXrBQgdtkSPYJ43FWdRofiJX56mzkiHbwus6fLl8t+NE8vdabi/QH82ioXUSLXDPH1/OatwQwQ6XL5x56pjmPIqWgIwdUO3TSsPjrvAU3Vq6weatMgk+Ux4Wta0ysLaruD5kp+qJU6WwHTbALeW4f9VEN/3ftKzXzs1Lm07eEWZRI4eIdwGeHPpfWSoiY+uEaFW+JZwTolUOJQKRlBznyp5J8q+xZIM8YwuZ6zzA6wxUDFofE=" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="completed keyexchange" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=debug msg="completed challenge" Nov 01 17:32:17 volumio go-librespot[1651]: time="2025-11-01T17:32:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:19 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:19 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 458. Nov 01 17:32:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:20 volumio go-librespot[1658]: go-librespot daemon starting... Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="app state loaded" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=info msg="zeroconf server listening on port 44027" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="obtained new client token: AAAtkT6M2zDwEMYxg3ooUuFrcxSJHnO0zW2CVAGdl7wUgBIYXPliLNigppgTnJ66C2Afatg7QSvz3mFhClCzwPXX+5VvcMnbIqW0rCTX80PJvFLMqn/S3+IRCfAC/DRDoqZC2h+fr2jpQ0a2LFM6gZW/x56QnV4Mb4/O+0e57swJmaKBfAtfYpbY32SzgmL31qil1LzAi9KRFi8ZENmV6TnXeRqD/yesDyB6mUJaut2jEC+ghXwlcnc=" Nov 01 17:32:20 volumio go-librespot[1659]: time="2025-11-01T17:32:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:21 volumio go-librespot[1659]: time="2025-11-01T17:32:21Z" level=debug msg="completed keyexchange" Nov 01 17:32:21 volumio go-librespot[1659]: time="2025-11-01T17:32:21Z" level=debug msg="completed challenge" Nov 01 17:32:21 volumio go-librespot[1659]: time="2025-11-01T17:32:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:22 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:22 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 459. Nov 01 17:32:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:24 volumio go-librespot[1667]: go-librespot daemon starting... Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="app state loaded" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=info msg="zeroconf server listening on port 36543" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="obtained new client token: AACSjeSpWqTP4Y/NyK/DI2If7cPEdexLmj7djAvwKlafqK8pDnQ9dFfGeii5AfgzJkSeKWfEOiACOmvtExXLn7XFii02dXHT2fVjJzZ/O9pS40zZsi6dHicnultDzecLm5mNwpJMtPnh8piCwVc84Fqkyqb/bauwnw6xf/aGvs8xoodSiG8T+o3+hmgaxYiFsMuWVB3ZGfavFXHPG+oOjGJf+WJ5JBmXTdKgm4YjZiKjQRFd2XWqzA0=" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="completed keyexchange" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=debug msg="completed challenge" Nov 01 17:32:24 volumio go-librespot[1668]: time="2025-11-01T17:32:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:25 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:25 volumio volumio[377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 460. Nov 01 17:32:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:27 volumio go-librespot[1689]: go-librespot daemon starting... Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="app state loaded" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=info msg="zeroconf server listening on port 39399" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="obtained new client token: AACKUyAO7s8Mk8fgbSPibSf0BZ6ECoWhujTD/PKNDQHlSRFdI9YcBODTq/BKzacnu9yXv6Zvhmbb5eSZKO77KeukTMWMLmUbVZfK37ywW+RjL/F26WffkgRbu3WNcRwo7Kd3FoJAoQmFC9aR0hNEKGfzUNcJPuL0qipWtMmdR02HAVb26MQRsOzVYSG1z1PkRLfe2GSpwn4X4yqwtcTrOD3HfXI4hnyWMpIfmO9SJW5GBP4qcPOwDUo=" Nov 01 17:32:27 volumio go-librespot[1690]: time="2025-11-01T17:32:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:28 volumio go-librespot[1690]: time="2025-11-01T17:32:28Z" level=debug msg="completed keyexchange" Nov 01 17:32:28 volumio go-librespot[1690]: time="2025-11-01T17:32:28Z" level=debug msg="completed challenge" Nov 01 17:32:28 volumio volumio[377]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:28 volumio volumio[377]: info: Connection to go-librespot Websocket established Nov 01 17:32:28 volumio go-librespot[1690]: time="2025-11-01T17:32:28Z" level=debug msg="new websocket client" Nov 01 17:32:28 volumio go-librespot[1690]: time="2025-11-01T17:32:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:28 volumio volumio[377]: info: Connection to go-librespot Websocket closed Nov 01 17:32:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:31 volumio volumio[377]: info: Getting Spotify volume Nov 01 17:32:31 volumio volumio[377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 17:32:31 volumio volumio[377]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:31 volumio volumio[377]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 01 17:32:31 volumio volumio[377]: errno: -111, Nov 01 17:32:31 volumio volumio[377]: code: 'ECONNREFUSED', Nov 01 17:32:31 volumio volumio[377]: syscall: 'connect', Nov 01 17:32:31 volumio volumio[377]: address: '127.0.0.1', Nov 01 17:32:31 volumio volumio[377]: port: 9879, Nov 01 17:32:31 volumio volumio[377]: response: undefined Nov 01 17:32:31 volumio volumio[377]: } Nov 01 17:32:31 volumio volumio[377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 17:32:31 volumio sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 17:31' Nov 01 17:32:31 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:31 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:31 volumio volumio-remote-updater[874]: [2025-11-01 17:32:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 01 17:32:31 volumio volumio-remote-updater[874]: [2025-11-01 17:32:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 01 17:32:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 461. Nov 01 17:32:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:32 volumio go-librespot[1719]: go-librespot daemon starting... Nov 01 17:32:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 17:32:32 volumio systemd[1]: volumio.service: Consumed 8.025s CPU time. Nov 01 17:32:32 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="app state loaded" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=info msg="zeroconf server listening on port 37397" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="obtained new client token: AAA8lJ9B2d+p3loU4x8AvBkFMEQ2eJhA8R/SOX2GV6iRQ+WTARTjLpXMEuF+LB0CJRzxOLVo/gYZBAb6IPjYcOLGW3wOQM7dGILYbkeDePToxl3xevFS4uUGRm01NnoB2g+VTYDiaYpwNvzAdjmmk3qpflSbmJoNL7QJCJKu/TtfePZKS0o52OOIc1Y9DbcWUk8folzY9WQi7mF9/6D87NxO5fqVtTyhLDGCcQTBJAQMo67nhlJf" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 01 17:32:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4788. Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="completed keyexchange" Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=debug msg="completed challenge" Nov 01 17:32:32 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 01 17:32:32 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 01 17:32:32 volumio systemd[1]: volumio.service: Consumed 8.025s CPU time. Nov 01 17:32:32 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Nov 01 17:32:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 01 17:32:32 volumio go-librespot[1728]: time="2025-11-01T17:32:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:32 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:32 volumio volumio[1744]: info: ----- Volumio3 ---- Nov 01 17:32:32 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:32 volumio volumio[1744]: info: ----- System startup ---- Nov 01 17:32:32 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:32 volumio volumio[1744]: info: MYVOLUMIO Environment detected Nov 01 17:32:32 volumio volumio[1744]: info: Plugin folders cleanup Nov 01 17:32:32 volumio volumio[1744]: info: Scanning into folder /volumio/app/plugins/ Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category audio_interface Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category miscellanea Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category music_service Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category plugins.json Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category system_controller Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category user_interface Nov 01 17:32:32 volumio volumio[1744]: info: Scanning into folder /data/plugins/ Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category music_service Nov 01 17:32:32 volumio volumio[1744]: info: Scanning category user_interface Nov 01 17:32:32 volumio volumio[1744]: info: Plugin folders cleanup completed Nov 01 17:32:32 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:32 volumio volumio[1744]: info: ----- Core plugins startup ---- Nov 01 17:32:32 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:32 volumio volumio[1744]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 17:32:32 volumio volumio[1744]: info: Adding plugin upnp to MyMusic Plugins Nov 01 17:32:32 volumio volumio[1744]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 17:32:32 volumio volumio[1744]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 17:32:32 volumio volumio[1744]: info: Loading plugins from folder /data/plugins/ Nov 01 17:32:32 volumio volumio[1744]: info: Loading plugin "system"... Nov 01 17:32:32 volumio volumio[1744]: info: Loading plugin "appearance"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "network"... Nov 01 17:32:33 volumio volumio[1744]: info: Refreshing Cached IP Addresses Nov 01 17:32:33 volumio sudo[1771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 17:32:33 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "services"... Nov 01 17:32:33 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "alsa_controller"... Nov 01 17:32:33 volumio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 17:32:33 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:33 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:33 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "wizard"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "networkfs"... Nov 01 17:32:33 volumio volumio[1744]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 01 17:32:33 volumio volumio[1744]: info: Starting Udev Watcher for removable devices Nov 01 17:32:33 volumio volumio[1744]: info: Ignoring mount for partition: boot Nov 01 17:32:33 volumio volumio[1744]: info: Ignoring mount for partition: volumio Nov 01 17:32:33 volumio volumio[1744]: info: Ignoring mount for partition: volumio_data Nov 01 17:32:33 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "volumio_command_line_client"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "upnp"... Nov 01 17:32:33 volumio volumio[1744]: info: [1762018353055] Starting Upmpd Daemon Nov 01 17:32:33 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "my_music"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "mpd"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "upnp_browser"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "alarm-clock"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "airplay_emulation"... Nov 01 17:32:33 volumio volumio[1744]: info: Starting Shairport Sync Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "last_100"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "webradio"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "i2s_dacs"... Nov 01 17:32:33 volumio volumio[1744]: info: I2S DAC not set, start Auto-detection Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "volumiodiscovery"... Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** For more information see Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 17:32:33 volumio volumio[1744]: *** WARNING *** For more information see Nov 01 17:32:33 volumio node[1744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 17:32:33 volumio node[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 17:32:33 volumio node[1744]: *** WARNING *** For more information see Nov 01 17:32:33 volumio node[1744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 17:32:33 volumio node[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 17:32:33 volumio node[1744]: *** WARNING *** For more information see Nov 01 17:32:33 volumio volumio[1744]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 17:32:33 volumio volumio[1744]: info: Discovery: Started advertising with name: Volumio Nov 01 17:32:33 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "spop"... Nov 01 17:32:33 volumio volumio[1744]: info: Loading plugin "ytcr"... Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "outputs"... Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "albumart"... Nov 01 17:32:34 volumio volumio[1744]: info: Plugin example_plugin is not enabled Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "inputs"... Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "updater_comm"... Nov 01 17:32:34 volumio volumio[1744]: info: Plugin mpdemulation is not enabled Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "rest_api"... Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "websocket"... Nov 01 17:32:34 volumio volumio[1744]: info: Starting Socket.io Server version 1.7.4 Nov 01 17:32:34 volumio volumio[1744]: info: Loading plugin "Systeminfo"... Nov 01 17:32:34 volumio volumio[1744]: info: Loading i18n strings for locale en Nov 01 17:32:34 volumio volumio[1744]: Updating browse sources language Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 17:32:34 volumio volumio[1803]: Forking 3 albumart workers Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::initPlayerControls Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: Express server listening on port 3000 Nov 01 17:32:34 volumio volumio[1744]: [Metrics] WebUI: 1s 934.81ms Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::resetVolumioState Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::getcurrentVolume Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: Cannot read play queue from file Nov 01 17:32:34 volumio volumio[1744]: info: Volumio Network Manager: Network status updated: 1 Nov 01 17:32:34 volumio volumio[1744]: info: VolumeController:: Volume=33 Mute =false Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::pushState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::updateTrackBlock Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrackBlock Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::setRepeat null single undefined Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::pushState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::setRandom null Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::pushState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Nov 01 17:32:34 volumio volumio[1744]: info: Setting Device type: Raspberry PI Nov 01 17:32:34 volumio volumio[1744]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 01 17:32:34 volumio volumio[1744]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b04170 Nov 01 17:32:34 volumio volumio[1744]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 01 17:32:34 volumio volumio[1744]: info: VolumeController:: Volume=33 Mute =false Nov 01 17:32:34 volumio volumio[1744]: info: CoreStateMachine::pushState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Nov 01 17:32:34 volumio volumio[1744]: info: Completed loading Core Plugins Nov 01 17:32:34 volumio volumio[1744]: info: Preparing to generate the ALSA configuration file Nov 01 17:32:34 volumio volumio[1744]: info: Discovery: adding 70853f74-8210-4683-a3fa-e1217517bd40 Nov 01 17:32:34 volumio volumio[1744]: info: Discovery: Found device Volumio Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: Discovery: this is already registered, 70853f74-8210-4683-a3fa-e1217517bd40 Nov 01 17:32:34 volumio volumio[1744]: info: Discovery: Found device Volumio Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:32:34 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:34 volumio volumio[1744]: info: Asound.conf file unchanged, so no further update is needed Nov 01 17:32:34 volumio volumio[1744]: info: Output device has changed, restarting MPD Nov 01 17:32:34 volumio sudo[1859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 17:32:34 volumio sudo[1859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:34 volumio volumio[1744]: info: Output device has changed, restarting Shairport Sync Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:34 volumio sudo[1859]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:34 volumio sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 17:32:34 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 01 17:32:34 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 01 17:32:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 01 17:32:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 01 17:32:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 01 17:32:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 01 17:32:34 volumio volumio[1744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 17:32:34 volumio volumio[1744]: info: ___________ START PLUGINS ___________ Nov 01 17:32:34 volumio volumio[1744]: info: ControllerMpd::onStart: Initializing MPD Nov 01 17:32:34 volumio volumio[1744]: info: Creating MPD Configuration file Nov 01 17:32:34 volumio sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 17:32:34 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:34 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 17:32:34 volumio volumio[1744]: info: [1762018354794] CoreMusicLibrary::Adding element Media Servers Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 17:32:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:34 volumio sudo[1873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 17:32:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 01 17:32:34 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:34 volumio volumio[1744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:34 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 01 17:32:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 01 17:32:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 01 17:32:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 01 17:32:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 01 17:32:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 01 17:32:34 volumio volumio[1744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 17:32:34 volumio volumio[1744]: info: [1762018354851] CoreMusicLibrary::Adding element Last_100 Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 17:32:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 17:32:34 volumio volumio[1744]: info: [1762018354852] CoreMusicLibrary::Adding element Webradio Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 17:32:34 volumio volumio[1744]: info: Initializing BBC Radios Nov 01 17:32:34 volumio volumio[1815]: Starting albumart workers Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:34 volumio volumio[1744]: info: Creating Spotify config file Nov 01 17:32:34 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:34 volumio volumio[1814]: Starting albumart workers Nov 01 17:32:34 volumio sudo[1885]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 17:32:34 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 01 17:32:34 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:34 volumio volumio[1813]: Starting albumart workers Nov 01 17:32:35 volumio volumio[1744]: info: Volumio Calling Home Nov 01 17:32:35 volumio volumio[1744]: info: MPD Permissions set Nov 01 17:32:35 volumio volumio[1744]: info: MPD Permissions set Nov 01 17:32:35 volumio volumio[1744]: info: Spotify config file written Nov 01 17:32:35 volumio sudo[1924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 01 17:32:35 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:35 volumio volumio[1744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 01 17:32:35 volumio volumio[1744]: info: Volumio called home Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:32:35 volumio volumio[1744]: info: No need to fix Spotify hosts Nov 01 17:32:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:35 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio go-librespot[1936]: go-librespot daemon starting... Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="app state loaded" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:35 volumio volumio[1744]: info: Starting Shairport Sync Nov 01 17:32:35 volumio volumio[1744]: info: Starting Shairport Sync Nov 01 17:32:35 volumio volumio[1744]: info: Starting Shairport Sync Nov 01 17:32:35 volumio sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 17:32:35 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:35 volumio sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 17:32:35 volumio sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 01 17:32:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Nov 01 17:32:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 01 17:32:35 volumio systemd[1]: shairport-sync.service: Consumed 1.677s CPU time. Nov 01 17:32:35 volumio sudo[1946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 17:32:35 volumio sudo[1946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 01 17:32:35 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 01 17:32:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Nov 01 17:32:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 01 17:32:35 volumio sudo[1948]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 01 17:32:35 volumio sudo[1946]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio volumio[1744]: info: Shairport-Sync Started Nov 01 17:32:35 volumio volumio[1744]: Error adding Membership: Error: addMembership EINVAL Nov 01 17:32:35 volumio volumio[1744]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 01 17:32:35 volumio volumio[1744]: SPOTIFY: BQDsDxqUKfVS6SrfuxQkiduptfbfqVwiwD064XHAfcq891BKNBRTX_9ibU3q-vlPmkatvwEWGw0hMAGVi8Ce-M56G8tbqx56Sxwxm-02YlaQH1KCdrhX_5Ia94PFZ8qlWjCp4ibGvYA_Ub24YrhqUUv85GzG40dtUUKhRc_NjFSlqYcCEObs8g9a2ExK8JMScTfr-B5lpw_c9r0F8pcReKntpSMI2vjcRk7wQBqB6dXNuBAXMNXjH4WNGYdN19tt0mPq0sR6D93Bj013vexNcgWSd2btxWcXXrXFFOrrLNsCxvE2_Bg Nov 01 17:32:35 volumio volumio[1744]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 01 17:32:35 volumio volumio[1744]: info: New Spotify access token = BQDsDxqUKfVS6SrfuxQkiduptfbfqVwiwD064XHAfcq891BKNBRTX_9ibU3q-vlPmkatvwEWGw0hMAGVi8Ce-M56G8tbqx56Sxwxm-02YlaQH1KCdrhX_5Ia94PFZ8qlWjCp4ibGvYA_Ub24YrhqUUv85GzG40dtUUKhRc_NjFSlqYcCEObs8g9a2ExK8JMScTfr-B5lpw_c9r0F8pcReKntpSMI2vjcRk7wQBqB6dXNuBAXMNXjH4WNGYdN19tt0mPq0sR6D93Bj013vexNcgWSd2btxWcXXrXFFOrrLNsCxvE2_Bg Nov 01 17:32:35 volumio volumio[1744]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 01 17:32:35 volumio mpd[1900]: 2025-11-01T17:32:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 17:32:35 volumio systemd[1]: Started mpd.service - Music Player Daemon. Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:32:35 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:35 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:35 volumio volumio[1744]: info: Shairport-Sync Started Nov 01 17:32:35 volumio volumio[1744]: info: Shairport-Sync Started Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=info msg="zeroconf server listening on port 41607" Nov 01 17:32:35 volumio volumio[1744]: error: MPD error: The expression evaluated to a falsy value: Nov 01 17:32:35 volumio volumio[1744]: assert.ok(self.idling) Nov 01 17:32:35 volumio volumio[1744]: error: The expression evaluated to a falsy value: Nov 01 17:32:35 volumio volumio[1744]: assert.ok(self.idling) Nov 01 17:32:35 volumio volumio[1744]: error: updateQueue error: null Nov 01 17:32:35 volumio volumio[1744]: info: MPD running with PID1900 Nov 01 17:32:35 volumio volumio[1744]: ,establishing connection Nov 01 17:32:35 volumio volumio[1744]: error: updateQueue error: null Nov 01 17:32:35 volumio volumio[1744]: SPOTIFY: User informations: {"country":"DE","display_name":"lapman","email":"ylm19961214@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31hyzsop5elyyfayaiyk5tmkxmky"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31hyzsop5elyyfayaiyk5tmkxmky","id":"31hyzsop5elyyfayaiyk5tmkxmky","images":[],"product":"free","type":"user","uri":"spotify:user:31hyzsop5elyyfayaiyk5tmkxmky"} Nov 01 17:32:35 volumio volumio[1744]: info: Spotify Successfully logged in Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 17:32:35 volumio volumio[1744]: info: [1762018355600] CoreMusicLibrary::Adding element Spotify Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 17:32:35 volumio volumio[1744]: Cannot find translation for source Spotify Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="obtained new client token: AAAe09UCK3Y8hJORVT84G59ewJ3wpyR/hZLQwIiQoYxxnXyuD5/8P/wIH/x/uJupkwdGJXMG4zS1SDB3v144Uk+iAdKG/ppa1wSKhGEx5IO52/mVlwJPlUjwqBFjJfrjMllm8SMux1x35NPfIXsp/KoCZ/EX1Sd+LDuvHvsDASK9jsq3pkuo3/cCpp31nPeLH7ZXqT0VPa6+s1SHnaoxWrA2WBPym4FnQ6Jc/a2AhKDBECk2kwBcpQk=" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="completed keyexchange" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=debug msg="completed challenge" Nov 01 17:32:35 volumio go-librespot[1937]: time="2025-11-01T17:32:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:35 volumio volumio[1744]: info: [yt-cast-receiver] DIAL server listening on port 8098 Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 17:32:35 volumio volumio[1744]: info: VolumeController:: Volume=33 Mute =false Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:32:35 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:35 volumio volumio[1744]: info: CoreStateMachine::pushState Nov 01 17:32:35 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:35 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Nov 01 17:32:36 volumio volumio[1744]: info: Completed starting Core Plugins Nov 01 17:32:36 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:36 volumio volumio[1744]: info: ----- MyVolumio plugins startup ---- Nov 01 17:32:36 volumio volumio[1744]: info: ------------------------------------------- Nov 01 17:32:36 volumio volumio[1744]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 01 17:32:36 volumio volumio-remote-updater[874]: [2025-11-01 17:32:36] [connect] Successful connection Nov 01 17:32:36 volumio volumio-remote-updater[874]: [2025-11-01 17:32:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1762018356 101 Nov 01 17:32:36 volumio volumio[1744]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Nov 01 17:32:38 volumio volumio[1744]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 01 17:32:38 volumio volumio[1744]: info: go-librespot daemon successfully initialized Nov 01 17:32:38 volumio sudo[1977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 01 17:32:38 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:38 volumio sudo[1979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 01 17:32:38 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:38 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 01 17:32:38 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 01 17:32:38 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 01 17:32:38 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 01 17:32:38 volumio mpd_monitor.sh[1982]: MPD Monitor Service: Starting MPD Monitor Service Nov 01 17:32:38 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:38 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 01 17:32:38 volumio volumio[1744]: info: Successfully started MPD Monitor Nov 01 17:32:38 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 01 17:32:38 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 01 17:32:38 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 01 17:32:38 volumio mpd_monitor.sh[1985]: MPD Monitor Service: Starting MPD Monitor Service Nov 01 17:32:38 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:38 volumio volumio[1744]: info: Successfully started MPD Monitor Nov 01 17:32:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 462. Nov 01 17:32:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:38 volumio go-librespot[1989]: go-librespot daemon starting... Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="app state loaded" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=info msg="zeroconf server listening on port 42495" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="obtained new client token: AAAm0nDzoaFI2bkNwSXQRmxHkBFhgmyD/PU+Yw0ZddSUlvYYiEy88yol3dU/ksbGDCBPXVze41ZneCE9D4B5Ft3YslMayzOoTrCUfagT6NWDFvqdesPpoGMJLUcHMflOXYjnTmHDgTBB9YxcvSb3MzWbnZKulifDOiVRIytqERlh5GGehd/pqyXfsV5DuXt5rI8w2wYLOcZlVqPgVSbPKjdIrhG3NT6Jt9svzx1vK5kOmpMesjiSyyQ=" Nov 01 17:32:38 volumio go-librespot[1990]: time="2025-11-01T17:32:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:39 volumio go-librespot[1990]: time="2025-11-01T17:32:39Z" level=debug msg="completed keyexchange" Nov 01 17:32:39 volumio go-librespot[1990]: time="2025-11-01T17:32:39Z" level=debug msg="completed challenge" Nov 01 17:32:39 volumio go-librespot[1990]: time="2025-11-01T17:32:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:40 volumio volumio[1744]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 01 17:32:41 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:41 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 463. Nov 01 17:32:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:42 volumio go-librespot[1998]: go-librespot daemon starting... Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="app state loaded" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=info msg="zeroconf server listening on port 43043" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="obtained new client token: AABtnbr2bjUOEZxFj5A9eHoGqBAEWti7Bd86SOmEIdwq442tn3Fwfj33i8tGV/caQvDP8uCYskxjPSC8bN0r8ggmwDGpHcrPILvlfk0hpb5MLMIq4CHvZ1WJ7j8Dtcfv5kyGmqHAH2TH202LM3/xhSEQM6rKIZYT5omd4W/uwITohMiclMAFf5f+LZP1JZBpPY6fbIHq0DnwB5US5ZNY1Nld+AlyANLD+V1RVImuWP4mHt6HoArjCIU=" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="completed keyexchange" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=debug msg="completed challenge" Nov 01 17:32:42 volumio go-librespot[1999]: time="2025-11-01T17:32:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:43 volumio volumio[1744]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 01 17:32:43 volumio sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 17:32:43 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:43 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:43 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 17:32:43 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:43 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:43 volumio sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 01 17:32:43 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:43 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:43 volumio volumio[1744]: info: Upmpdcli Daemon Started Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin bluetooth to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin multiroom to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin metavolumio to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin cd_controller to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 01 17:32:44 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:44 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:44 volumio volumio[1744]: info: Starting MyVolumio Remote Streaming Endpoints Nov 01 17:32:44 volumio volumio[1744]: info: MyVolumio login type: Token Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 01 17:32:44 volumio volumio[1744]: info: Streaming services startup Nov 01 17:32:44 volumio volumio[1744]: info: Starting Streaming Daemon Nov 01 17:32:44 volumio sudo[2031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 17:32:44 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:44 volumio volumio[1744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 01 17:32:44 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:44 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:44 volumio volumio[1744]: error: Cannot start Volumio Streaming Daemon Nov 01 17:32:44 volumio volumio[1744]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 01 17:32:44 volumio volumio[1744]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 01 17:32:44 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:45 volumio volumio[1744]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 01 17:32:45 volumio volumio[1744]: info: MyVolumio token set successfully Nov 01 17:32:45 volumio volumio[1744]: info: MYVOLUMIO: Adding device Nov 01 17:32:45 volumio volumio[1744]: info: MYVOLUMIO: Evaluating Server Nov 01 17:32:45 volumio volumio[1744]: info: MyVolumio status changed Nov 01 17:32:45 volumio volumio[1744]: info: Streaming services startup Nov 01 17:32:45 volumio volumio[1744]: info: Starting Streaming Daemon Nov 01 17:32:45 volumio volumio[1744]: info: Removing browser output: myVolumio user plan is not superstar Nov 01 17:32:45 volumio volumio[1744]: info: Removing audio output: Nov 01 17:32:45 volumio volumio[1744]: info: Stoppping Tunnel 1 Nov 01 17:32:45 volumio sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 17:32:45 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:45 volumio sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 01 17:32:45 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 17:32:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 464. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:45 volumio volumio[1744]: error: Cannot start Volumio Streaming Daemon Nov 01 17:32:45 volumio volumio[1744]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 01 17:32:45 volumio volumio[1744]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 01 17:32:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:45 volumio go-librespot[2060]: go-librespot daemon starting... Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 01 17:32:45 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="app state loaded" Nov 01 17:32:45 volumio volumio[1744]: info: Remote SSH Stopped Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=info msg="zeroconf server listening on port 33453" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="obtained new client token: AABGJ7su8W65z4oa+AqxM52EZvLSoJwu4tusgm1+LPGVaQMqLG/M6zMF8rJn29lVrY7WGJR9aedQvJ5zqg5C5exBdy7bQHmlovETF3Ffa68NAcf7Q8hpG1QggU13yK2kKioKsMejQW15OV3aoDegNDktjaL2mzqREjwLpo/EitkRuNL5BEOv7LyDVpzupPG85iWS8EDflbB3/Ks5+u8V1nvLga3lioAV3ZrB+FKpxNCb097OrCFNzcU=" Nov 01 17:32:45 volumio go-librespot[2061]: time="2025-11-01T17:32:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:46 volumio go-librespot[2061]: time="2025-11-01T17:32:46Z" level=debug msg="completed keyexchange" Nov 01 17:32:46 volumio go-librespot[2061]: time="2025-11-01T17:32:46Z" level=debug msg="completed challenge" Nov 01 17:32:46 volumio volumio[1744]: info: Setting Geolocation for MyVolumio to eu5 Nov 01 17:32:46 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:46 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:46 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:46 volumio go-librespot[2061]: time="2025-11-01T17:32:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:46 volumio volumio[1744]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 01 17:32:47 volumio volumio[1744]: info: Updating MyVolumio device info Nov 01 17:32:47 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:47 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:47 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:47 volumio volumio[1744]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 01 17:32:47 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:47 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:48 volumio volumio[1744]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 01 17:32:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 465. Nov 01 17:32:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:49 volumio go-librespot[2068]: go-librespot daemon starting... Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="app state loaded" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=info msg="zeroconf server listening on port 46297" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="obtained new client token: AAAIP823EQ4vnAB8JQ/LksWwZaQwxLqOT04Q/BaTSAiedo0rAVpYZbtiSBQeFHjKzf7953cXov7DMVTWsXc1G3dyBXb3lRm1JGAzEA0vUrr28Oig3/YsXCxaGUL09fxkcoUOuETw3qUNn5dp2PlnS5ZGjcj9UNAcaDJep4vLjHcJvDrjfABpWI4Wv+o0psV2/pO42DiJ68LZlCqTVepZoFzIG+3G2Rfvg8ClET/J2bn3fptCbJMSKBQ=" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="completed keyexchange" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=debug msg="completed challenge" Nov 01 17:32:49 volumio go-librespot[2069]: time="2025-11-01T17:32:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:50 volumio volumio[1744]: info: MYVOLUMIO: Adding device Nov 01 17:32:50 volumio volumio[1744]: info: MYVOLUMIO: Evaluating Server Nov 01 17:32:50 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:50 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:51 volumio volumio[1744]: info: Setting Geolocation for MyVolumio to eu2 Nov 01 17:32:51 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:51 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:51 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:52 volumio volumio[1744]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 01 17:32:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 466. Nov 01 17:32:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:52 volumio volumio[1744]: info: Updating MyVolumio device info Nov 01 17:32:52 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:52 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:52 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:32:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:52 volumio go-librespot[2091]: go-librespot daemon starting... Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=debug msg="app state loaded" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=info msg="zeroconf server listening on port 42027" Nov 01 17:32:52 volumio go-librespot[2092]: time="2025-11-01T17:32:52Z" level=debug msg="obtained new client token: AAAKmYrekw/yR5rFJV2KEN2n9c3cA6IBrXuCEZ97tA1/YkcCYC3PEC0e2mCZSLl8InuUQfvJ48ByFJ8R7fDeVgYvXykFYR1hB7bICTPuFGOtaukOKjNXwFPZCvtyw25icx3jzJBKmsfYFcGcA1SwW5gdSOYbLKxhn5iz3rnJfpQcvePEOY+5hHdWGz8H8tFeQXXf4txJEQMASQ/bcv0/1dWE3d6pJxqBseyumBOaOJ1S/vwRS+wo/LM=" Nov 01 17:32:53 volumio go-librespot[2092]: time="2025-11-01T17:32:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:53 volumio volumio[1744]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 01 17:32:53 volumio go-librespot[2092]: time="2025-11-01T17:32:53Z" level=debug msg="completed keyexchange" Nov 01 17:32:53 volumio go-librespot[2092]: time="2025-11-01T17:32:53Z" level=debug msg="completed challenge" Nov 01 17:32:53 volumio volumio[1744]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 01 17:32:53 volumio go-librespot[2092]: time="2025-11-01T17:32:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:53 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:53 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 467. Nov 01 17:32:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:56 volumio go-librespot[2113]: go-librespot daemon starting... Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="app state loaded" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=info msg="zeroconf server listening on port 34983" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="obtained new client token: AAAGqX3ewLP4bAZooaMQdL5iojRIQ9+0GOAg5tmqALdCu8x1P+VS8dMTLqSYuzu0fH8HddoHZzKWPMlozcMEoe0bdHWWpX0b+36JMzHHIxkOk4XXkUch3A/GO0FY18LoggEKuzh2i84sMAovA76RYRs5SU3NkewUvCcEu7WD5W0X7wq8NIpec8ZNhaIE3yU4BISoBKs+mJxAFDH3iydPuC0tV4wiP9lYyegfTEkgTlcfgj/01UTH1MY=" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="completed keyexchange" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=debug msg="completed challenge" Nov 01 17:32:56 volumio go-librespot[2114]: time="2025-11-01T17:32:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:32:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:32:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:32:56 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:56 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:59 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 17:32:59 volumio volumio[1744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 01 17:32:59 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:32:59 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:32:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 468. Nov 01 17:32:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:59 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:32:59 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:32:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:32:59 volumio go-librespot[2124]: go-librespot daemon starting... Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="app state loaded" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=info msg="zeroconf server listening on port 35911" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="obtained new client token: AACYMxQ0RYIHDLRClBRBfAwlfxXcc20tJwnV/P9+BZYyKvwS4nxYTOlaZIrdXvUBlM0OiELaPHHX+x/+GcdWoxSt5NCzf77CfuONCoWk9ck5VvCYvabaGrapR9EOPy3WSZPHBlAj91Xz+XeJrFW5foz4DhdBkTvmPeAlggYdFne+wVDVYZeiDU0ZK4UDWVItdCGHd0FvWKS5/pYOFCfesDfbaOT/SHU2nEomIZjPe7YGO5/v6cbcv4k=" Nov 01 17:32:59 volumio go-librespot[2125]: time="2025-11-01T17:32:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:00 volumio go-librespot[2125]: time="2025-11-01T17:33:00Z" level=debug msg="completed keyexchange" Nov 01 17:33:00 volumio go-librespot[2125]: time="2025-11-01T17:33:00Z" level=debug msg="completed challenge" Nov 01 17:33:00 volumio go-librespot[2125]: time="2025-11-01T17:33:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:02 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:02 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:33:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 469. Nov 01 17:33:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:03 volumio go-librespot[2148]: go-librespot daemon starting... Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="app state loaded" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=info msg="zeroconf server listening on port 44469" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="obtained new client token: AADoGVO4UZsei9co30YJwEuJd8Scdc7cBn7nulhV7HmmBLuenycSp4Bt4oP9GnFKItDxfJCgpKXQKSJNQyTATX7riB9DqJbikEaOM2wFi8Tbibloxj0mx5SnMPUI2O36cqU/K6ksx8aWGxoCM1MtKl6Q995OkaTYyPaa4HOHjmL5T3R2CfTryi4NzGEx1pzlFaec3T0Jj+ll9fpOsscwDngaxqMmugStsJ8ZXBK5UJ81IPbqvsZp9ZE=" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="completed keyexchange" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=debug msg="completed challenge" Nov 01 17:33:03 volumio go-librespot[2149]: time="2025-11-01T17:33:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 17:33:04 volumio volumio[1744]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 01 17:33:04 volumio volumio[1744]: info: Completed starting MyVolumio Plugin Nov 01 17:33:04 volumio volumio[1744]: [Metrics] CommandRouter: 32s 90.41ms Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::volumiosetStartupVolume Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::Close All Modals sent Nov 01 17:33:04 volumio volumio[1744]: info: CoreCommandRouter::Close All Modals sent Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 17:33:05 volumio volumio-remote-updater[874]: Test mode disabled Nov 01 17:33:05 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 01 17:33:05 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 01 17:33:05 volumio volumio[1744]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 17:33:05 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 17:33:05 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:05 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:33:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 470. Nov 01 17:33:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:06 volumio go-librespot[2166]: go-librespot daemon starting... Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="app state loaded" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=info msg="zeroconf server listening on port 35041" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="obtained new client token: AAD1oH9qjKIzeWBKl7+jglTqNwuYeHPnYiPe9FtQm5AV9DBJ9kYa7vpXGxi0uGJJd3nLkZ88G2mrkefawJWPZdQdZqpE25YuA2d4RQQljkfe3X6aWcv2oE0aAdJ1pKVOCAdK2jYUs/QkEyI9uT8IlTmBevswzhqjTd0TGHH44knqL/NNdeSWFKsioUonQmTXp9z4TazZA2Qgqy/7Iw2q12zpPF1991NVY+nRUode907Ny3Bw/adwXBk=" Nov 01 17:33:06 volumio go-librespot[2167]: time="2025-11-01T17:33:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:07 volumio go-librespot[2167]: time="2025-11-01T17:33:07Z" level=debug msg="completed keyexchange" Nov 01 17:33:07 volumio go-librespot[2167]: time="2025-11-01T17:33:07Z" level=debug msg="completed challenge" Nov 01 17:33:07 volumio go-librespot[2167]: time="2025-11-01T17:33:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:08 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:08 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:33:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 471. Nov 01 17:33:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:10 volumio go-librespot[2174]: go-librespot daemon starting... Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="app state loaded" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=info msg="zeroconf server listening on port 43599" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="obtained new client token: AACXT27SH6EwK8xC39N4udvvugbhP6a0/pkNrfhg32Etcm1l11INH4wiBY2ObWbW3d6OCfHqKONRPYT1YxO4GPVUsIdWqZGAk/MAqksAGll3gDpjEhkOvDaEqBpIXy9jEtdB2gIGndHn8+vYiotg4Rc9qIYgZfzN7ellJoQ/B5TV55e9m8cniASgB6+XOeg2aqHbNdpFOjMM0N8KwKYHmL+gCkc6J/GFOAxp7PULVjc6IQOh0mW63w0=" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="completed keyexchange" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=debug msg="completed challenge" Nov 01 17:33:10 volumio go-librespot[2175]: time="2025-11-01T17:33:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:11 volumio volumio[1744]: info: BOOT COMPLETED Nov 01 17:33:11 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:11 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:33:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 472. Nov 01 17:33:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:13 volumio go-librespot[2196]: go-librespot daemon starting... Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="app state loaded" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=info msg="zeroconf server listening on port 41313" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="obtained new client token: AAC5FE5GGSO0nNLcVTVCt/MvnomtmMRhEawGnBPrngoCxZDiNqdrQHL3hxA8OKAnmdDx2iP52VS2iPmtBNSn2ieVn96SZ538Q6jfoZlVmB19Y+0M/7b2v5ftWP/MtRG4KK7EX94kgv17kidRrB8c6TgcKpRM189VYtf0a0+0XlBI57oYQO2uBuzexVGStH/5CBXcAUpUf8y0k2U2fa37TZn3NbV0Au/g4SLTj8U3rB0Zy9/P1nBRYFk=" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="completed keyexchange" Nov 01 17:33:13 volumio go-librespot[2197]: time="2025-11-01T17:33:13Z" level=debug msg="completed challenge" Nov 01 17:33:14 volumio go-librespot[2197]: time="2025-11-01T17:33:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:14 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:14 volumio volumio[1744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 17:33:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 473. Nov 01 17:33:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 17:33:17 volumio go-librespot[2204]: go-librespot daemon starting... Nov 01 17:33:17 volumio go-librespot[2205]: time="2025-11-01T17:33:17Z" level=info msg="running go-librespot 0.4.0" Nov 01 17:33:17 volumio go-librespot[2205]: time="2025-11-01T17:33:17Z" level=debug msg="app state loaded" Nov 01 17:33:17 volumio go-librespot[2205]: time="2025-11-01T17:33:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 17:33:17 volumio volumio[1744]: info: Initializing connection to go-librespot Websocket Nov 01 17:33:17 volumio volumio[1744]: info: Connection to go-librespot Websocket established Nov 01 17:33:17 volumio go-librespot[2205]: time="2025-11-01T17:33:17Z" level=debug msg="new websocket client" Nov 01 17:33:20 volumio volumio[1744]: info: Getting Spotify volume Nov 01 17:33:20 volumio volumio[1744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 01 17:33:20 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:33:20 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:33:20 volumio volumio[1744]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Nov 01 17:33:20 volumio volumio[1744]: SPOTIFY: SPOTIFY VOLUME undefined Nov 01 17:33:20 volumio volumio[1744]: SPOTIFY: VOLUMIO VOLUME 33 Nov 01 17:33:20 volumio volumio[1744]: info: Aligning Spotify Volume to Volumio Volume Nov 01 17:33:20 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Nov 01 17:33:20 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Nov 01 17:33:20 volumio volumio[1744]: info: Setting Spotify Volume from Volumio: 33 Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=info msg="zeroconf server listening on port 43381" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="obtained new client token: AABeBNdaSZ/ggFKgBJ149OaZZR+VadJQ+1xehIvXAApCzC2SX6cloM4zs0+xmijXnAX3B37ldnWaIBb/d6l3sx1T4sJ73zJOW/k5jUFwgG/vy/V8zZ7epzkM1HH35P7/p4+3yig7N+/A14gv8Z+bvs/cx9y9BSHFJZS75dExTb66E1XTC3QWnDYL+hz7JRkbrbNwPtZdlVwaXd7hGQMl/Qz3mXp1eGvrl9Qtl/LkWTTyhAYdYZdc" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 17:33:22 volumio volumio[1744]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Nov 01 17:33:22 volumio volumio[1744]: info: Sending Spotify command with payload to local API: /player/volume Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="completed keyexchange" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=debug msg="completed challenge" Nov 01 17:33:22 volumio go-librespot[2205]: time="2025-11-01T17:33:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 17:33:22 volumio volumio[1744]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Nov 01 17:33:22 volumio volumio[1744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 17:33:22 volumio volumio[1744]: Error: socket hang up Nov 01 17:33:22 volumio volumio[1744]: at connResetException (node:internal/errors:720:14) Nov 01 17:33:22 volumio volumio[1744]: at Socket.socketOnEnd (node:_http_client:519:23) Nov 01 17:33:22 volumio volumio[1744]: at Socket.emit (node:events:526:35) Nov 01 17:33:22 volumio volumio[1744]: at endReadableNT (node:internal/streams/readable:1376:12) Nov 01 17:33:22 volumio volumio[1744]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Nov 01 17:33:22 volumio volumio[1744]: code: 'ECONNRESET', Nov 01 17:33:22 volumio volumio[1744]: response: undefined Nov 01 17:33:22 volumio volumio[1744]: } Nov 01 17:33:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 17:33:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 17:33:22 volumio volumio[1744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 17:33:22 volumio sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 17:32' Nov 01 17:33:22 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"