-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-11-23 20:54:04 CET. -- Nov 23 20:53:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1341. Nov 23 20:53:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 625. Nov 23 20:53:01 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:01 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:01 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:01 volumio go-librespot[19909]: Librespot-go daemon starting... Nov 23 20:53:01 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=info msg="generated new device id: 5d3175e1c99f2c16d8c893d5b876957eff412910" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:01 volumio volumio[19911]: Traceback (most recent call last): Nov 23 20:53:01 volumio volumio[19911]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:01 volumio volumio[19911]: from aiohttp import web Nov 23 20:53:01 volumio volumio[19911]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="zeroconf server listening on port 36301" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="obtained new client token: AABgWoJktjvpQcCeMldb1ewEIcV5cw8eil4nEnRE37ZRCd8vC8A5cD9cSlQb+9K54n2sD3Dcx6E23CcB3PQBDkcD8c+AaHya2qI6yCCGTDDNc6hWF+Gjm3hqeWB96uq57hRLahdagyyysFmEpJiKrPbxyFsywk4ATsOZnlXpZvvxrwLmAF9qmhVJqmeA5HLM9TbU4w3JDbxty96cJ+Evpd2fY6Adx9Y0SwrjLuVRj75OL+lo3FyR4e0Izy3v" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:01 volumio go-librespot[19909]: time="2024-11-23T20:53:01+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:02 volumio go-librespot[19909]: time="2024-11-23T20:53:02+01:00" level=debug msg="completed challenge" Nov 23 20:53:02 volumio go-librespot[19909]: time="2024-11-23T20:53:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:02 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:02 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1342. Nov 23 20:53:03 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:03 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:03 volumio volumio[19919]: Traceback (most recent call last): Nov 23 20:53:03 volumio volumio[19919]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:03 volumio volumio[19919]: from aiohttp import web Nov 23 20:53:03 volumio volumio[19919]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:05 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:05 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 626. Nov 23 20:53:05 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:05 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:05 volumio go-librespot[19934]: Librespot-go daemon starting... Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=info msg="generated new device id: 1bfdda81729e6bf1f4f3e266d3b71af5f0640ef4" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="zeroconf server listening on port 41403" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="obtained new client token: AAAt4JONoKJeKNZ7m438hg5aQuUku/UNBMI7XWlA9DB++MLPWu2UJxAfKAVqVU9VBRZmB4GpPmo5ZHOM7yu5Kf/BO0+PIgZP4VK6hYKgQbvFktGRveysAv1n9ssMNUKQXm3B0/SgXCC8GSlcPHa9y0WMi8vw8Jp0bTzyNfBWI/PXnyUPq8szbROeo9E+PD85plCVqQXl7bMwO9yAVuBfEOaXlRxAcyIaS21oOCLNIxQeSCqJUDh1aOLwKz93" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:05 volumio go-librespot[19934]: time="2024-11-23T20:53:05+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1343. Nov 23 20:53:05 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:05 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:05 volumio volumio[19942]: Traceback (most recent call last): Nov 23 20:53:05 volumio volumio[19942]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:05 volumio volumio[19942]: from aiohttp import web Nov 23 20:53:05 volumio volumio[19942]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:06 volumio go-librespot[19934]: time="2024-11-23T20:53:06+01:00" level=debug msg="completed challenge" Nov 23 20:53:06 volumio go-librespot[19934]: time="2024-11-23T20:53:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1344. Nov 23 20:53:08 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:08 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:08 volumio volumio[19943]: Traceback (most recent call last): Nov 23 20:53:08 volumio volumio[19943]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:08 volumio volumio[19943]: from aiohttp import web Nov 23 20:53:08 volumio volumio[19943]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:08 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:08 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:08 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:53:08 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:53:08 volumio volumio[833]: info: Discovery: Getting this device information Nov 23 20:53:08 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Nov 23 20:53:08 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:53:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 627. Nov 23 20:53:09 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:09 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:09 volumio go-librespot[19944]: Librespot-go daemon starting... Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=info msg="generated new device id: 09b9a306c4395a9344324475085ca37dba8d5bf3" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="zeroconf server listening on port 42487" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="obtained new client token: AADJY5ENwD4k3f+asthbXtuU+Ll4qSuMsJDgN3cDo2oPpjaEGn7GqmRJJTA3EbeZsaXbE78KKJbtGStYJ1q0B4khJlXysbp7ssZyTFIIcxxABLeFTqLKYGe040NzDaHh6NeXdETPnVuec3LpcTLnRdX8vI2ZPOdOOw8NeiTnBPkht/h8lnD31Af46YJZXz6dBBFnjqp81Ye0Nkk/tNvYt55z64CbAEduu6KCjJ1i0T2OdAXDKuK76NnXa7Zu" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:09 volumio go-librespot[19944]: time="2024-11-23T20:53:09+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:10 volumio go-librespot[19944]: time="2024-11-23T20:53:10+01:00" level=debug msg="completed challenge" Nov 23 20:53:10 volumio go-librespot[19944]: time="2024-11-23T20:53:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1345. Nov 23 20:53:10 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:10 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:10 volumio volumio[19955]: Traceback (most recent call last): Nov 23 20:53:10 volumio volumio[19955]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:10 volumio volumio[19955]: from aiohttp import web Nov 23 20:53:10 volumio volumio[19955]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:11 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:11 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1346. Nov 23 20:53:12 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:12 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:12 volumio volumio[19956]: Traceback (most recent call last): Nov 23 20:53:12 volumio volumio[19956]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:12 volumio volumio[19956]: from aiohttp import web Nov 23 20:53:12 volumio volumio[19956]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 628. Nov 23 20:53:13 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:13 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:13 volumio go-librespot[19957]: Librespot-go daemon starting... Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=info msg="generated new device id: 1dae8740b8759f91ce62a2daa6c539d76b0c347d" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="zeroconf server listening on port 42349" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="obtained new client token: AADwCtPzrqJirverXjzHAEsawnoPKzhqSAyLVhiC+U7IXNUSOHzuBf2Y/YDi+auN3t8YOFWq1Vb88/OYKmIWYmJOiyVwtkpREKALY8sss7UnBnUXzX3F+09zczV1bidb3DFU65JP0me+fFmDBGp2OGWxb/ml1onreF9RJEBupJxg8AUfYErO/v0CI2Sw8lvnRf4xEP1p5vYrTJafhGy0eRuyDErsMoQcxhe2/04A0dCBUky0434oHYQ80fMJ" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:13 volumio go-librespot[19957]: time="2024-11-23T20:53:13+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:14 volumio go-librespot[19957]: time="2024-11-23T20:53:14+01:00" level=debug msg="completed challenge" Nov 23 20:53:14 volumio go-librespot[19957]: time="2024-11-23T20:53:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:14 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:14 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:14 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1347. Nov 23 20:53:14 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:14 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:14 volumio volumio[19979]: Traceback (most recent call last): Nov 23 20:53:14 volumio volumio[19979]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:14 volumio volumio[19979]: from aiohttp import web Nov 23 20:53:14 volumio volumio[19979]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1348. Nov 23 20:53:16 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:16 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:16 volumio volumio[19980]: Traceback (most recent call last): Nov 23 20:53:16 volumio volumio[19980]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:16 volumio volumio[19980]: from aiohttp import web Nov 23 20:53:16 volumio volumio[19980]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:17 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:17 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 629. Nov 23 20:53:17 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:17 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:17 volumio go-librespot[19981]: Librespot-go daemon starting... Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=info msg="generated new device id: c04b1ee4f221fa115bac9b9d7083620ca958161c" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="zeroconf server listening on port 46843" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="obtained new client token: AAAi6j8DXFaKDzSzLIkIke5ISKKmyk0wbMaqP5AXekGmkE82LEaDPQQ9eVAF8N3oq7jtPJpJCKFz8U2azA0NdZ8xT2RzxovVnGYl3c+FU0lz0Zz9mhwRK37aKYJ4/Zcf1hAYRZytvUdF7AyZlX0KrfCGUchoCbKqCX3aJrDwDRlEWeIHJB3Jnn9qYO4IUPvIyLK40IQpozl1vMC1EOYeLrWPCRK94SdNQOhAl+9Yvwi9SdU90cvQNCbsfbCT" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:17 volumio go-librespot[19981]: time="2024-11-23T20:53:17+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:18 volumio go-librespot[19981]: time="2024-11-23T20:53:18+01:00" level=debug msg="completed challenge" Nov 23 20:53:18 volumio go-librespot[19981]: time="2024-11-23T20:53:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1349. Nov 23 20:53:19 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:19 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:19 volumio volumio[19989]: Traceback (most recent call last): Nov 23 20:53:19 volumio volumio[19989]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:19 volumio volumio[19989]: from aiohttp import web Nov 23 20:53:19 volumio volumio[19989]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:20 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:20 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1350. Nov 23 20:53:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 630. Nov 23 20:53:21 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:21 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:21 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:21 volumio go-librespot[19993]: Librespot-go daemon starting... Nov 23 20:53:21 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=info msg="generated new device id: 8a273405f4388946d4e94705534e6b9bc6d4c671" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:21 volumio volumio[19995]: Traceback (most recent call last): Nov 23 20:53:21 volumio volumio[19995]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:21 volumio volumio[19995]: from aiohttp import web Nov 23 20:53:21 volumio volumio[19995]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="zeroconf server listening on port 40479" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="obtained new client token: AABTFvHHOKlhGj+usO3rhho3DPC2JcTCuD03Vml1+Wl/0Fq3eHmMFyT2IP0i4TocojcxthlC7a7Dlbf49qIaEPeozZl8+SOm9WIB9pdF4wJnIxEEkmZwL/Xg63vLkgDrY1rbZ00BDzho0TVadkh8UKn+aClQ9liPiH0HOtzPdTfLsbb0Lw8hYiGNhFrauRXrT4tSU6IJZmCZlSrkUdkTDfSU2xbL7DZbLREutRsMF7nHZ0BsMVcmyMBcBvnv" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:21 volumio go-librespot[19993]: time="2024-11-23T20:53:21+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:22 volumio go-librespot[19993]: time="2024-11-23T20:53:22+01:00" level=debug msg="completed challenge" Nov 23 20:53:22 volumio go-librespot[19993]: time="2024-11-23T20:53:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:22 volumio nmbd[727]: [2024/11/23 20:53:22.465681, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 23 20:53:22 volumio nmbd[727]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.128 for name WORKGROUP<1d>. Nov 23 20:53:22 volumio nmbd[727]: This response was from IP 192.168.0.152, reporting an IP address of 192.168.0.152. Nov 23 20:53:23 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:23 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1351. Nov 23 20:53:23 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:23 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:23 volumio volumio[20003]: Traceback (most recent call last): Nov 23 20:53:23 volumio volumio[20003]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:23 volumio volumio[20003]: from aiohttp import web Nov 23 20:53:23 volumio volumio[20003]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:23 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631. Nov 23 20:53:25 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:25 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:25 volumio go-librespot[20018]: Librespot-go daemon starting... Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=info msg="generated new device id: b706aaa5f5be0b3aa9f158782ced2bfe5f4545aa" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="zeroconf server listening on port 42435" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="obtained new client token: AACO/BUt9qJ6gIILnr+SQEyCCQ2F5eSJEpwBV94hniwZlOldGdTQpvS1bIBkujUPmS3EgwlJAri9kBpsSksraPH3aLmle1GXHG2YmRH9Vdx2sf55AVwE/NYlAN8SCLWUt79NONUXX8aP3maxCiLxpWtYSaitJYMHlBU3UrZpfYpHZPNn8C5khkXiorTcBj+aax6dLX1/dhDvvNr/CTtWjgo6FXjeQorYv/HBvQcru2b4o7ITCWRZGe9QZLgT" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:25 volumio go-librespot[20018]: time="2024-11-23T20:53:25+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:25 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1352. Nov 23 20:53:25 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:25 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:25 volumio volumio[20026]: Traceback (most recent call last): Nov 23 20:53:25 volumio volumio[20026]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:25 volumio volumio[20026]: from aiohttp import web Nov 23 20:53:25 volumio volumio[20026]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:26 volumio go-librespot[20018]: time="2024-11-23T20:53:26+01:00" level=debug msg="completed challenge" Nov 23 20:53:26 volumio go-librespot[20018]: time="2024-11-23T20:53:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:26 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:26 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1353. Nov 23 20:53:28 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:28 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:28 volumio volumio[20028]: Traceback (most recent call last): Nov 23 20:53:28 volumio volumio[20028]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:28 volumio volumio[20028]: from aiohttp import web Nov 23 20:53:28 volumio volumio[20028]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:29 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:29 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 632. Nov 23 20:53:29 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:29 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:29 volumio go-librespot[20029]: Librespot-go daemon starting... Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=info msg="generated new device id: b202fef755230db25e352480ab9eae1d6880e382" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="zeroconf server listening on port 35725" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="obtained new client token: AAApgtaVZzdqWx3kAIJcABUBUf+eGqTjUJTnzrf2ZnP6GELlaBWUBT7T3Au8LJ6CEgXKtyDJwiWigNUwdBpMu01c/8DG5SHUP8AtwV+pkqxzSewDlWpvwTQUx7r3RJz17VLgxfXHkeIkN75dzju08La9ekQgDWo5noP03tItXXkztHtmVpFxrSJhzAOGZ/PJFhtCVENz0EpH2ngw9M1LthpyxDLWZ+Rq8FhrkcinL9/GqH4Mye2xPfCQMQVj" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Nov 23 20:53:29 volumio go-librespot[20029]: time="2024-11-23T20:53:29+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:30 volumio go-librespot[20029]: time="2024-11-23T20:53:30+01:00" level=debug msg="completed challenge" Nov 23 20:53:30 volumio go-librespot[20029]: time="2024-11-23T20:53:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1354. Nov 23 20:53:30 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:30 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:30 volumio volumio[20040]: Traceback (most recent call last): Nov 23 20:53:30 volumio volumio[20040]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:30 volumio volumio[20040]: from aiohttp import web Nov 23 20:53:30 volumio volumio[20040]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:32 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:32 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:32 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1355. Nov 23 20:53:32 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:32 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:32 volumio volumio[20041]: Traceback (most recent call last): Nov 23 20:53:32 volumio volumio[20041]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:32 volumio volumio[20041]: from aiohttp import web Nov 23 20:53:32 volumio volumio[20041]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:32 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:32 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 633. Nov 23 20:53:33 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:33 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:33 volumio go-librespot[20042]: Librespot-go daemon starting... Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=info msg="generated new device id: 4956ed4983cf46db15c78e1944df82b1fabcc9bb" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="zeroconf server listening on port 36181" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="obtained new client token: AADj7PdfqQHq6+T0NPqt0JHTVK3groJt43YL2UbLj8DKcG0QtcR/u3JgxwM9dcs1UFNyCtxcqVYW6g0J1Um57DKAfm9ws9LxTIWFllP7LNnuC8vi0aWEKnN2NBbbQpi75ElElIxRNAkW7afy4I/DLjy9JyOZmoWmdUNzlpGsL4W+P4rhHnrM62GG3gPK9u0bkJklW1Gsm1yj1z0llE2quCIJgtGwLoToFF0Z6/9P5XvmKG5jb6mk1lAWDZSN" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:33 volumio go-librespot[20042]: time="2024-11-23T20:53:33+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:34 volumio go-librespot[20042]: time="2024-11-23T20:53:34+01:00" level=debug msg="completed challenge" Nov 23 20:53:34 volumio go-librespot[20042]: time="2024-11-23T20:53:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:34 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1356. Nov 23 20:53:34 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:34 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:34 volumio volumio[20064]: Traceback (most recent call last): Nov 23 20:53:34 volumio volumio[20064]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:34 volumio volumio[20064]: from aiohttp import web Nov 23 20:53:34 volumio volumio[20064]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:35 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:35 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:37 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1357. Nov 23 20:53:37 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:37 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:37 volumio volumio[20065]: Traceback (most recent call last): Nov 23 20:53:37 volumio volumio[20065]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:37 volumio volumio[20065]: from aiohttp import web Nov 23 20:53:37 volumio volumio[20065]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:37 volumio volumio[833]: info: Nov 23 20:53:37 volumio volumio[833]: ---------------------------- MPD announces system playlist update Nov 23 20:53:37 volumio volumio[833]: info: Ignoring MPD Status Update Nov 23 20:53:37 volumio volumio[833]: info: Nov 23 20:53:37 volumio volumio[833]: ---------------------------- MPD announces state update: player Nov 23 20:53:37 volumio volumio[833]: info: ControllerMpd::getState Nov 23 20:53:37 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:53:37 volumio volumio[833]: info: ------------------------------ 3ms Nov 23 20:53:37 volumio volumio[833]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:53:37 volumio volumio[833]: verbose: ControllerMpd::parseState Nov 23 20:53:37 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:53:37 volumio volumio[833]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 20:53:37 volumio volumio[833]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:53:37 volumio volumio[833]: info: ControllerMpd::pushState Nov 23 20:53:37 volumio volumio[833]: info: CoreCommandRouter::servicePushState Nov 23 20:53:37 volumio volumio[833]: info: CorePlayQueue::getTrack 0 Nov 23 20:53:37 volumio volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":218582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Kurt Rosenwinkel - Fall","artist":"Jazz24","album":null,"uri":"https://live.amperwave.net/direct/ppm-jazz24mp3-ibc1","trackType":""} Nov 23 20:53:37 volumio volumio[833]: verbose: CURRENT POSITION 0 Nov 23 20:53:37 volumio volumio[833]: info: CoreStateMachine::syncState stateService play Nov 23 20:53:37 volumio volumio[833]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:53:37 volumio volumio[833]: info: Received an update from plugin. extracting info from payload Nov 23 20:53:37 volumio volumio[833]: info: CoreStateMachine::pushState Nov 23 20:53:37 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:53:37 volumio volumio[833]: info: CoreCommandRouter::volumioPushState Nov 23 20:53:37 volumio volumio[833]: info: CoreStateMachine::pushState Nov 23 20:53:37 volumio volumio[833]: info: CoreCommandRouter::volumioPushState Nov 23 20:53:37 volumio volumio[833]: info: ------------------------------ 22ms Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - volume level for loudness 63 gain applied 0.00 Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - Loudness is ON true Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - volume level for loudness 63 gain applied 0.00 Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - Loudness is ON true Nov 23 20:53:37 volumio volumio[833]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Nov 23 20:53:37 volumio volumio[833]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 20:53:37 volumio volumio[833]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 20:53:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 634. Nov 23 20:53:37 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:37 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:37 volumio go-librespot[20068]: Librespot-go daemon starting... Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=info msg="generated new device id: a02a7b17a25fcb080cebb460ae199b784f5b26f2" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="zeroconf server listening on port 41909" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="obtained new client token: AAB7nb7gdDBibiUzM2PiHOz0Vza2P/Z4hdbmKBtndmaj0jPSoca0njJA7vzjo1JT5qHwolV/gD8wrPR1Q0L8ZSOA5fJ4QJNzD8qtQsirC1KEuRtWMrxUT7d8hLxmCDqKhqEf6n4iCDk75oNa0YFjfTdc30fgtLDz4u6joiy4FtkLLuN4C7fUUR2pErCFjwwEGf375paONPu5CdgZG8lQq0A/sXz8iNCb1txu+dueqUBHqeaIPEbnZDxuBItJ" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:37 volumio go-librespot[20068]: time="2024-11-23T20:53:37+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:38 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:38 volumio go-librespot[20068]: time="2024-11-23T20:53:38+01:00" level=debug msg="new websocket client" Nov 23 20:53:38 volumio volumio[833]: info: Connection to go-librespot Websocket established Nov 23 20:53:38 volumio go-librespot[20068]: time="2024-11-23T20:53:38+01:00" level=debug msg="completed challenge" Nov 23 20:53:38 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:53:38 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:53:38 volumio volumio[833]: info: Discovery: Getting this device information Nov 23 20:53:38 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Nov 23 20:53:38 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:53:38 volumio go-librespot[20068]: time="2024-11-23T20:53:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:38 volumio volumio[833]: info: Connection to go-librespot Websocket closed Nov 23 20:53:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1358. Nov 23 20:53:39 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:39 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:39 volumio volumio[20076]: Traceback (most recent call last): Nov 23 20:53:39 volumio volumio[20076]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:39 volumio volumio[20076]: from aiohttp import web Nov 23 20:53:39 volumio volumio[20076]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:41 volumio volumio[833]: info: Getting Spotify volume Nov 23 20:53:41 volumio volumio[833]: (node:833) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:41 volumio volumio[833]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Nov 23 20:53:41 volumio volumio[833]: (node:833) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 47) Nov 23 20:53:41 volumio volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 23 20:53:41 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Nov 23 20:53:41 volumio volumio[833]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Nov 23 20:53:41 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:41 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:41 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1359. Nov 23 20:53:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 635. Nov 23 20:53:41 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:41 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:41 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:41 volumio go-librespot[20080]: Librespot-go daemon starting... Nov 23 20:53:41 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=info msg="generated new device id: de2c11cf1bf2354f20d96aa95f279434c2829665" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:41 volumio volumio[20082]: Traceback (most recent call last): Nov 23 20:53:41 volumio volumio[20082]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:41 volumio volumio[20082]: from aiohttp import web Nov 23 20:53:41 volumio volumio[20082]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="zeroconf server listening on port 44509" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="obtained new client token: AACCMC8FsP5kfnd+qPs56sHe/31ZWUrZtb/KZ7YcHhxkpv9IoNF+lL2HCQ4gp+xOA+f2HvwnuZaefZJjTs6fthm7o27Vjkdy2R/J5fXf+bmw/CkB0MO8XuxdFcAp9aCm0O2gi64t2cobafOqIh4DmgBqPHG2LuYsvOd2YHJs0vU9DxsVUiDw+f082WYkoA1/KetXQyw4y7797T1SzrptdBbMWqgyvRIbe0Ac3z75oKXtQH79mWm0k6GgW/YO" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:41 volumio go-librespot[20080]: time="2024-11-23T20:53:41+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:42 volumio go-librespot[20080]: time="2024-11-23T20:53:42+01:00" level=debug msg="completed challenge" Nov 23 20:53:42 volumio go-librespot[20080]: time="2024-11-23T20:53:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:43 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1360. Nov 23 20:53:43 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:43 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:43 volumio volumio[20127]: Traceback (most recent call last): Nov 23 20:53:43 volumio volumio[20127]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:43 volumio volumio[20127]: from aiohttp import web Nov 23 20:53:43 volumio volumio[20127]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:43 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:43 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:44 volumio volumio[833]: error: error Nov 23 20:53:44 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:44 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 636. Nov 23 20:53:45 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:45 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:45 volumio go-librespot[20143]: Librespot-go daemon starting... Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=info msg="generated new device id: 0490993526f980689ff6f390427346c4c755e0b1" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="zeroconf server listening on port 36981" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="obtained new client token: AAAfz/gCfhmjmOVFvFXR6eK7f8zACDkFwD9AHEx2dqKBZJMc4pl7U5fB5XGv1KojIajE3qZgQr47z4hkOE/9KxvYrzNTVwG7MG03ojABEWtPxMw0qcTsctSjDxF8VyX1QDPGE+8jyhmY5jEL2IevOL3ugQh+89YVVnXFjLgVBF+ySlqR9DruKo+Z7G2bsoNEGk7m5G/mafFqaaD68qi7jYu9NkL4MgMsHEE0SnfcW9RftlxuPR72Kf1biyml" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:45 volumio go-librespot[20143]: time="2024-11-23T20:53:45+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1361. Nov 23 20:53:46 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:46 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:46 volumio volumio[20151]: Traceback (most recent call last): Nov 23 20:53:46 volumio volumio[20151]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:46 volumio volumio[20151]: from aiohttp import web Nov 23 20:53:46 volumio volumio[20151]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:46 volumio go-librespot[20143]: time="2024-11-23T20:53:46+01:00" level=debug msg="completed challenge" Nov 23 20:53:46 volumio go-librespot[20143]: time="2024-11-23T20:53:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:47 volumio volumio[833]: error: error Nov 23 20:53:47 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:47 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1362. Nov 23 20:53:48 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:48 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:48 volumio volumio[20152]: Traceback (most recent call last): Nov 23 20:53:48 volumio volumio[20152]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:48 volumio volumio[20152]: from aiohttp import web Nov 23 20:53:48 volumio volumio[20152]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 637. Nov 23 20:53:49 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:49 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:49 volumio go-librespot[20156]: Librespot-go daemon starting... Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=info msg="generated new device id: 42a3fb71e871b4ffcfcab487ff703fbc9951303b" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="zeroconf server listening on port 36647" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="obtained new client token: AACk9mPmYRe9qCaPtgyiBNa03/tvQtasZGaeaEG3WMO1rfaTX08qpnNq+VsZwrb9jj7SveZ9hrStRle3nnA/cFaY2FQ1E+mj8+HoyC7xqR8r8bXJcUEC7Fv0jty/JCmsHct6tw1U5b0wQykFG4gA21tyu06Xxu/sOOOhOsINs0iFLwaUEgtmZM28rG2F4Wg0rE/8VLEl+4Iau+tuzsT3ORx+vlOvJ2ltClIDc0y/C/rXrTq7bSJyJLgQOdy7" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Nov 23 20:53:49 volumio go-librespot[20156]: time="2024-11-23T20:53:49+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:50 volumio go-librespot[20156]: time="2024-11-23T20:53:50+01:00" level=debug msg="completed challenge" Nov 23 20:53:50 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:50 volumio go-librespot[20156]: time="2024-11-23T20:53:50+01:00" level=debug msg="new websocket client" Nov 23 20:53:50 volumio volumio[833]: info: Connection to go-librespot Websocket established Nov 23 20:53:50 volumio go-librespot[20156]: time="2024-11-23T20:53:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:50 volumio volumio[833]: info: Connection to go-librespot Websocket closed Nov 23 20:53:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1363. Nov 23 20:53:50 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:50 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:50 volumio volumio[20164]: Traceback (most recent call last): Nov 23 20:53:50 volumio volumio[20164]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:50 volumio volumio[20164]: from aiohttp import web Nov 23 20:53:50 volumio volumio[20164]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:52 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1364. Nov 23 20:53:52 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:52 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:52 volumio volumio[20165]: Traceback (most recent call last): Nov 23 20:53:52 volumio volumio[20165]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:52 volumio volumio[20165]: from aiohttp import web Nov 23 20:53:52 volumio volumio[20165]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:52 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:52 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:53 volumio volumio[833]: info: Getting Spotify volume Nov 23 20:53:53 volumio volumio[833]: (node:833) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:53 volumio volumio[833]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Nov 23 20:53:53 volumio volumio[833]: (node:833) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 48) Nov 23 20:53:53 volumio volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 23 20:53:53 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:53 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:53 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Nov 23 20:53:53 volumio volumio[833]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Nov 23 20:53:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 638. Nov 23 20:53:53 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:53 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:53 volumio go-librespot[20166]: Librespot-go daemon starting... Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=info msg="generated new device id: 2dc14b0bcfeb73591a261d1a0043df915dc5d447" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="zeroconf server listening on port 44229" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="obtained new client token: AAB7X1HWM+fnVLENOvtM25bRnWciY//l5b091LLPmyvDH5RU5jGvNVepvtzpXJphtW1DDeZkIUc4ui5i7KMq4Bzf2hqOfMh/GP/kjDJEvPfCJfGcWQP/ppwYqgxpzQzPmwfiIlHZsI+/iWGdulHaTQQ/0uU/eNyz/VZGqftPWFqECzoIoIaFveuClANHk74kq33gxto4AycQWX1GPK49ZJTYfXqsAElMGAlPdnJ5l9CC0HJG/rcYyKY/X11u" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:53 volumio go-librespot[20166]: time="2024-11-23T20:53:53+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:54 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:53:54 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:53:54 volumio volumio[833]: info: Discovery: Getting this device information Nov 23 20:53:54 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Nov 23 20:53:54 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:53:54 volumio go-librespot[20166]: time="2024-11-23T20:53:54+01:00" level=debug msg="completed challenge" Nov 23 20:53:54 volumio go-librespot[20166]: time="2024-11-23T20:53:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1365. Nov 23 20:53:55 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:55 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:55 volumio volumio[20188]: Traceback (most recent call last): Nov 23 20:53:55 volumio volumio[20188]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:55 volumio volumio[20188]: from aiohttp import web Nov 23 20:53:55 volumio volumio[20188]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:56 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:56 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1366. Nov 23 20:53:57 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:57 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:57 volumio volumio[20189]: Traceback (most recent call last): Nov 23 20:53:57 volumio volumio[20189]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:57 volumio volumio[20189]: from aiohttp import web Nov 23 20:53:57 volumio volumio[20189]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:53:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:53:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 639. Nov 23 20:53:57 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:53:57 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:53:57 volumio go-librespot[20190]: Librespot-go daemon starting... Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=info msg="generated new device id: b7aa62c372afec9e456ec918c7dc41de20998253" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="zeroconf server listening on port 40013" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="obtained new client token: AADYCFanzkDzO8yPpcSF/KNOQXhZrHhD0WmRCJTCS3H8iQBFmAYSVQ6j3PZsBDTQkFad1mNOhVWvUXIvLD6pezWseqSxZCboe+eELhsJFeNL8M2GbZiAXCW9J9R5/0GTzWcCtHOKjfJKTnlppHo/Y5ucV2qzeQfmjHWneANWSPACIKkRHyCrxlDc4YJxuaMvsKV7J/Ff6i+wOkuuO8/ttGLu4nhYv51pLt9t7LtIbRrCM/PQ5qXAbwO4aEGQ" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:53:57 volumio go-librespot[20190]: time="2024-11-23T20:53:57+01:00" level=debug msg="completed keyexchange" Nov 23 20:53:58 volumio go-librespot[20190]: time="2024-11-23T20:53:58+01:00" level=debug msg="completed challenge" Nov 23 20:53:58 volumio go-librespot[20190]: time="2024-11-23T20:53:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:53:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:53:59 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:53:59 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:53:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:53:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1367. Nov 23 20:53:59 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:53:59 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:53:59 volumio volumio[20201]: Traceback (most recent call last): Nov 23 20:53:59 volumio volumio[20201]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:53:59 volumio volumio[20201]: from aiohttp import web Nov 23 20:53:59 volumio volumio[20201]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:53:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:53:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:54:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:54:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 640. Nov 23 20:54:01 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:54:01 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:54:01 volumio go-librespot[20202]: Librespot-go daemon starting... Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=info msg="generated new device id: 743871e7c56ac153ea0460aa089a7eb19cca564c" Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="stored credentials found for kamil72" Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="zeroconf server listening on port 45413" Nov 23 20:54:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:54:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1368. Nov 23 20:54:01 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="obtained new client token: AADMcyF/I0qtcAc8nbiS3X1u87Pg9tb+cr1vwZQ5kVxK0omI6fR0ZQ/F1scCXEOxmQPjmJjnl7aaddk6lb1Z8dyKKGU1UoSSvz/0GsZISaBfbaBoBxPY1U2cNb4NBhONTz7TBBKVvRABCCOdOJ/1MRFAh9URrTtFLhyD72DAQa+ddM5I6PQlKFIwHWRZKguqEoyRy/T96i1itjaBRS+hld/MNBYGln6BQ2PVuIiHE5iHA3q+mPe1KazaMgvu" Nov 23 20:54:01 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:54:01 volumio volumio[20210]: Traceback (most recent call last): Nov 23 20:54:01 volumio volumio[20210]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:54:01 volumio volumio[20210]: from aiohttp import web Nov 23 20:54:01 volumio volumio[20210]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:54:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:54:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:54:01 volumio go-librespot[20202]: time="2024-11-23T20:54:01+01:00" level=debug msg="completed keyexchange" Nov 23 20:54:01 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 20:54:01 volumio volumio[833]: info: Preload queue cleared Nov 23 20:54:02 volumio go-librespot[20202]: time="2024-11-23T20:54:02+01:00" level=debug msg="completed challenge" Nov 23 20:54:02 volumio go-librespot[20202]: time="2024-11-23T20:54:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Nov 23 20:54:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:54:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:54:02 volumio volumio[833]: info: Initializing connection to go-librespot Websocket Nov 23 20:54:02 volumio volumio[833]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:54:03 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 20:54:03 volumio volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:54:03 volumio volumio[833]: TypeError: Cannot read property 'length' of undefined Nov 23 20:54:03 volumio volumio[833]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 23 20:54:03 volumio volumio[833]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 23 20:54:03 volumio volumio[833]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 23 20:54:03 volumio volumio[833]: at Parser.emit (events.js:315:20) Nov 23 20:54:03 volumio volumio[833]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 23 20:54:03 volumio volumio[833]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 23 20:54:03 volumio volumio[833]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 23 20:54:03 volumio volumio[833]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 23 20:54:03 volumio volumio[833]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 23 20:54:03 volumio volumio[833]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 23 20:54:03 volumio volumio[833]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 23 20:54:03 volumio volumio[833]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 23 20:54:03 volumio volumio[833]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 23 20:54:03 volumio volumio[833]: at IncomingMessage.emit (events.js:327:22) Nov 23 20:54:03 volumio volumio[833]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 23 20:54:03 volumio volumio[833]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 23 20:54:03 volumio volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:54:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Nov 23 20:54:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1369. Nov 23 20:54:04 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 23 20:54:04 volumio systemd[1]: Started FusionDsp Daemon. Nov 23 20:54:04 volumio volumio[20234]: Traceback (most recent call last): Nov 23 20:54:04 volumio volumio[20234]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Nov 23 20:54:04 volumio volumio[20234]: from aiohttp import web Nov 23 20:54:04 volumio volumio[20234]: ModuleNotFoundError: No module named 'aiohttp' Nov 23 20:54:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Nov 23 20:54:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Nov 23 20:54:04 volumio sudo[20236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 20:53 Nov 23 20:54:04 volumio sudo[20236]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"