-- Logs begin at Sun 2024-10-13 15:56:00 CEST, end at Sun 2024-10-13 15:58:48 CEST. -- Oct 13 15:57:18 volumioalrum volumio[1026]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=d1746440935241e3ca8e6b2ea71fa945" http://updates.volumio.org/downloader-v1/track-device Oct 13 15:57:18 volumioalrum volumio[1026]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 15:57:18 volumioalrum volumio[1026]: Dload Upload Total Spent Left Speed Oct 13 15:57:18 volumioalrum volumio[1026]: [1.6K blob data] Oct 13 15:57:18 volumioalrum volumio[1026]: retrying in 5 seconds, trial 1 Oct 13 15:57:18 volumioalrum volumio[1026]: info: Volumio Calling Home Oct 13 15:57:28 volumioalrum volumio[1026]: info: Discovery: adding d016a4a5-e68e-44ec-998d-33514b24f501 Oct 13 15:57:28 volumioalrum volumio[1026]: info: Discovery: Found device VolumioAlrum Oct 13 15:57:28 volumioalrum volumio[1026]: info: CoreCommandRouter::volumioGetState Oct 13 15:57:28 volumioalrum volumio[1026]: info: CorePlayQueue::getTrack 0 Oct 13 15:57:33 volumioalrum dhcpcd[2052]: eth0: offered 192.168.1.101 from 192.168.1.1 Oct 13 15:57:33 volumioalrum dhcpcd[1446]: eth0: offered 192.168.1.101 from 192.168.1.1 Oct 13 15:57:33 volumioalrum dhcpcd[2052]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:57:33 volumioalrum dhcpcd[1446]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:57:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:57:43+02:00" level=error msg="did not receive last pong from dealer, 30s passed" Oct 13 15:57:48 volumioalrum volumio[1026]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=d1746440935241e3ca8e6b2ea71fa945" http://updates.volumio.org/downloader-v1/track-device Oct 13 15:57:48 volumioalrum volumio[1026]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 15:57:48 volumioalrum volumio[1026]: Dload Upload Total Spent Left Speed Oct 13 15:57:48 volumioalrum volumio[1026]: [1.6K blob data] Oct 13 15:57:48 volumioalrum volumio[1026]: retrying in 5 seconds, trial 2 Oct 13 15:57:48 volumioalrum volumio[1026]: info: Volumio Calling Home Oct 13 15:57:51 volumioalrum ntpd[1972]: Soliciting pool server 2a09:5e41:d10:93f0:dabb:c1ff:fe08:ac6e Oct 13 15:57:58 volumioalrum dhcpcd[1446]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:57:58 volumioalrum dhcpcd[2052]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:58:09 volumioalrum ntpd[1972]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 13 15:58:10 volumioalrum dhcpcd[1446]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:58:10 volumioalrum dhcpcd[2052]: eth0: ignoring offer of 192.168.1.101 from 192.168.1.1 Oct 13 15:58:13 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:13+02:00" level=error msg="did not receive last pong from dealer, 60s passed" Oct 13 15:58:29 volumioalrum ntpd[1972]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 13 15:58:35 volumioalrum dhcpcd[2052]: eth0: probing address 192.168.1.101/24 Oct 13 15:58:35 volumioalrum dhcpcd[1446]: eth0: probing address 192.168.1.101/24 Oct 13 15:58:39 volumioalrum dhcpcd[1446]: eth0: leased 192.168.1.101 for 86400 seconds Oct 13 15:58:39 volumioalrum dhcpcd[1446]: eth0: adding route to 192.168.1.0/24 Oct 13 15:58:39 volumioalrum dhcpcd[1446]: eth0: changing default route via 192.168.1.1 Oct 13 15:58:39 volumioalrum avahi-daemon[781]: Registering new address record for 192.168.1.101 on eth0.IPv4. Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: deleted default route Oct 13 15:58:39 volumioalrum ntpd[1972]: ntpd exiting on signal 15 (Terminated) Oct 13 15:58:39 volumioalrum ntpd[1972]: 83.89.248.209 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 193.200.91.90 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 192.36.143.130 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 162.159.200.123 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 185.181.223.169 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 80.209.68.118 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 162.159.200.1 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 213.5.39.34 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum systemd[1]: Stopping Network Time Service... Oct 13 15:58:39 volumioalrum ntpd[1972]: 86.52.112.177 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 95.154.26.34 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 217.198.219.102 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum ntpd[1972]: 213.32.246.229 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum systemd[1]: ntp.service: Succeeded. Oct 13 15:58:39 volumioalrum ntpd[1972]: 89.150.131.83 local addr 169.254.251.118 -> Oct 13 15:58:39 volumioalrum systemd[1]: Stopped Network Time Service. Oct 13 15:58:39 volumioalrum systemd[1]: Starting Network Time Service... Oct 13 15:58:39 volumioalrum ntpd[2203]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 13 15:58:39 volumioalrum ntpd[2203]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 13 15:58:39 volumioalrum systemd[1]: Started Network Time Service. Oct 13 15:58:39 volumioalrum ntpd[2209]: proto: precision = 0.200 usec (-22) Oct 13 15:58:39 volumioalrum ntpd[2209]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 13 15:58:39 volumioalrum avahi-daemon[781]: Withdrawing address record for 169.254.251.118 on eth0. Oct 13 15:58:39 volumioalrum avahi-daemon[781]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.251.118. Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: deleted route to 169.254.0.0/16 Oct 13 15:58:39 volumioalrum ntpd[2209]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 13 15:58:39 volumioalrum ntpd[2209]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 656 days ago Oct 13 15:58:39 volumioalrum dhcpcd[1446]: eth0: deleting route to 169.254.0.0/16 Oct 13 15:58:39 volumioalrum ntpd[2209]: Listen and drop on 0 v6wildcard [::]:123 Oct 13 15:58:39 volumioalrum ntpd[2209]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: adding route to 169.254.0.0/16 Oct 13 15:58:39 volumioalrum dhcpcd[2052]: if_route (ADD): Invalid argument Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: adding default route Oct 13 15:58:39 volumioalrum dhcpcd[2052]: if_route (ADD): Invalid argument Oct 13 15:58:39 volumioalrum avahi-daemon[781]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.101. Oct 13 15:58:39 volumioalrum ntpd[2209]: Listen normally on 2 lo 127.0.0.1:123 Oct 13 15:58:39 volumioalrum ntpd[2209]: Listen normally on 3 eth0 192.168.1.101:123 Oct 13 15:58:39 volumioalrum ntpd[2209]: Listening on routing socket on fd #20 for interface updates Oct 13 15:58:39 volumioalrum ntpd[2209]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 15:58:39 volumioalrum ntpd[2209]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: leased 192.168.1.101 for 86400 seconds Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: adding route to 192.168.1.0/24 Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: adding default route via 192.168.1.1 Oct 13 15:58:39 volumioalrum dhcpcd[2052]: eth0: adding route to 169.254.0.0/16 Oct 13 15:58:39 volumioalrum dhcpcd[2052]: if_route (ADD): Invalid argument Oct 13 15:58:40 volumioalrum ntpd[2209]: ntpd exiting on signal 15 (Terminated) Oct 13 15:58:40 volumioalrum systemd[1]: Stopping Network Time Service... Oct 13 15:58:40 volumioalrum systemd[1]: ntp.service: Succeeded. Oct 13 15:58:40 volumioalrum systemd[1]: Stopped Network Time Service. Oct 13 15:58:40 volumioalrum systemd[1]: Starting Network Time Service... Oct 13 15:58:40 volumioalrum ntpd[2256]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 13 15:58:40 volumioalrum ntpd[2256]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 13 15:58:40 volumioalrum systemd[1]: Started Network Time Service. Oct 13 15:58:40 volumioalrum ntpd[2262]: proto: precision = 0.200 usec (-22) Oct 13 15:58:40 volumioalrum ntpd[2262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 13 15:58:40 volumioalrum ntpd[2262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 13 15:58:40 volumioalrum ntpd[2262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 656 days ago Oct 13 15:58:40 volumioalrum ntpd[2262]: Listen and drop on 0 v6wildcard [::]:123 Oct 13 15:58:40 volumioalrum ntpd[2262]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 13 15:58:40 volumioalrum ntpd[2262]: Listen normally on 2 lo 127.0.0.1:123 Oct 13 15:58:40 volumioalrum ntpd[2262]: Listen normally on 3 eth0 192.168.1.101:123 Oct 13 15:58:40 volumioalrum ntpd[2262]: Listening on routing socket on fd #20 for interface updates Oct 13 15:58:40 volumioalrum ntpd[2262]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 15:58:40 volumioalrum ntpd[2262]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 15:58:41 volumioalrum ntpd[2262]: Soliciting pool server 162.159.200.123 Oct 13 15:58:42 volumioalrum ntpd[2262]: Soliciting pool server 86.52.112.177 Oct 13 15:58:42 volumioalrum ntpd[2262]: Soliciting pool server 213.5.39.34 Oct 13 15:58:42 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:42+02:00" level=error msg="failed put state after volume change" error="put state request failed with status 400: Require at least one of callbackUrl or X-Spotify-Connection-Id" Oct 13 15:58:42 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:42+02:00" level=trace msg="emitting websocket event: volume" Oct 13 15:58:42 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:42+02:00" level=debug msg="received connection id: NzEzYzY5MTgtZGNiNC00NDM0LTliOTItYmE5ZDRhMDVlODgxK2RlYWxlcit0Y3A6Ly8wYWNhNThmNC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjUwNDQ1NkUyOTVBODBBNUY3RDhFRkQ2QTQ3RTI1RDU1RTZDOTg0MTFFNDNDOUYzQTBGNkExMDEyQjQ1ODQyMQ==" Oct 13 15:58:42 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:42+02:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Oct 13 15:58:42 volumioalrum volumio[1026]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Oct 13 15:58:42 volumioalrum volumio[1026]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 13 15:58:43 volumioalrum volumio[1026]: info: Spotify volume: 86 Oct 13 15:58:43 volumioalrum ntpd[2262]: Soliciting pool server 217.198.219.102 Oct 13 15:58:43 volumioalrum ntpd[2262]: Soliciting pool server 83.89.248.209 Oct 13 15:58:43 volumioalrum ntpd[2262]: Soliciting pool server 212.99.225.86 Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="re-established dealer connection" Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="received connection id: NDUzMGE4NGYtZTc3Mi00Y2M4LThjNDYtNTEzMDVmODNhZGE0K2RlYWxlcit0Y3A6Ly8wYWNiZGI1Ny5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArQUU2MDc2MzBEN0FENENDRTM3RkJFMTQ2MzQ2REM5MDU1NjZEQkJENUE5Q0JCOTNCRDIwMDk5OTRGMzJBRUFFOA==" Oct 13 15:58:43 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:43+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 15:58:43 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 13 15:58:44 volumioalrum ntpd[2262]: Soliciting pool server 192.36.143.130 Oct 13 15:58:44 volumioalrum ntpd[2262]: Soliciting pool server 193.200.91.90 Oct 13 15:58:44 volumioalrum ntpd[2262]: Soliciting pool server 89.150.131.83 Oct 13 15:58:44 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 15:58:44 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 15:58:44 volumioalrum volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 15:58:44 volumioalrum volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 15:58:44 volumioalrum volumio[1026]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 15:58:44 volumioalrum volumio[1026]: info: MyVolumio login type: Token Oct 13 15:58:44 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 15:58:44 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 15:58:45 volumioalrum ntpd[2262]: Soliciting pool server 80.209.68.118 Oct 13 15:58:45 volumioalrum ntpd[2262]: Soliciting pool server 185.181.223.169 Oct 13 15:58:45 volumioalrum ntpd[2262]: Soliciting pool server 162.159.200.1 Oct 13 15:58:45 volumioalrum volumio[1026]: info: Starting Streaming Service Transparent Proxy Oct 13 15:58:45 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 15:58:45 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 15:58:45 volumioalrum volumio[1026]: info: Streaming services startup Oct 13 15:58:45 volumioalrum volumio[1026]: info: Starting Streaming Daemon Oct 13 15:58:45 volumioalrum sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 15:58:45 volumioalrum sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 15:58:45 volumioalrum sudo[2306]: pam_unix(sudo:session): session closed for user root Oct 13 15:58:45 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 15:58:45 volumioalrum volumio[1026]: error: Cannot start Volumio Streaming Daemon Oct 13 15:58:45 volumioalrum volumio[1026]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 15:58:45 volumioalrum volumio[1026]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 15:58:46 volumioalrum ntpd[2262]: Soliciting pool server 213.32.246.229 Oct 13 15:58:46 volumioalrum volumio[1026]: error: MyVolumio Custom Token format not valid, refreshing it Oct 13 15:58:46 volumioalrum ntpd[2262]: Soliciting pool server 80.209.87.103 Oct 13 15:58:46 volumioalrum volumio[1026]: STREAMING PROXY: Starting server on port 3245 Oct 13 15:58:46 volumioalrum volumio[1026]: Node JS runtime: 14 Oct 13 15:58:46 volumioalrum volumio[1026]: info: MyVolumio login type: Token Oct 13 15:58:47 volumioalrum ntpd[2262]: Soliciting pool server 95.154.26.34 Oct 13 15:58:47 volumioalrum volumio[1026]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 13 15:58:48 volumioalrum volumio[1026]: info: MyVolumio token set successfully Oct 13 15:58:48 volumioalrum volumio[1026]: info: MYVOLUMIO: Adding device Oct 13 15:58:48 volumioalrum volumio[1026]: info: MYVOLUMIO: Evaluating Server Oct 13 15:58:48 volumioalrum volumio[1026]: info: MyVolumio Plan changed: premium Oct 13 15:58:48 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 13 15:58:48 volumioalrum volumio[1026]: info: Removing browser output: myVolumio user plan is not superstar Oct 13 15:58:48 volumioalrum volumio[1026]: info: Removing audio output: Oct 13 15:58:48 volumioalrum volumio[1026]: info: MYVOLUMIO: Adding device Oct 13 15:58:48 volumioalrum volumio[1026]: info: MYVOLUMIO: Evaluating Server Oct 13 15:58:48 volumioalrum volumio[1026]: info: Remote config written successfully Oct 13 15:58:48 volumioalrum volumio[1026]: info: Starting Tunnel 1 Oct 13 15:58:48 volumioalrum volumio[1026]: info: Starting Tunnel Connection Checker Oct 13 15:58:48 volumioalrum ntpd[2262]: receive: Unexpected origin timestamp 0xeab65099.2cba5caf does not match aorg 0000000000.00000000 from server@213.32.246.229 xmt 0xeab65098.55cbc20f Oct 13 15:58:48 volumioalrum ntpd[2262]: receive: Unexpected origin timestamp 0xeab65099.2cc400a9 does not match aorg 0000000000.00000000 from server@86.52.112.177 xmt 0xeab65098.565ce3d4 Oct 13 15:58:48 volumioalrum ntpd[2262]: receive: Unexpected origin timestamp 0xeab65099.2cbdff66 does not match aorg 0000000000.00000000 from server@192.36.143.130 xmt 0xeab65098.56b384a8 Oct 13 15:58:48 volumioalrum volumio[1026]: info: MYVolumio Device enabled Oct 13 15:58:48 volumioalrum volumio[1026]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 13 15:58:48 volumioalrum volumio[1026]: info: MyVolumio status changed Oct 13 15:58:48 volumioalrum volumio[1026]: info: Streaming services startup Oct 13 15:58:48 volumioalrum volumio[1026]: info: Starting Streaming Daemon Oct 13 15:58:48 volumioalrum sudo[2347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 15:58:48 volumioalrum volumio[1026]: info: Setting Geolocation for MyVolumio to eu6 Oct 13 15:58:48 volumioalrum sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 15:58:48 volumioalrum volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 15:58:48 volumioalrum volumio[1026]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Oct 13 15:58:48 volumioalrum volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 15:58:48 volumioalrum sudo[2347]: pam_unix(sudo:session): session closed for user root Oct 13 15:58:48 volumioalrum volumio[1026]: Error: Unable to resolve or reject the same promise twice Oct 13 15:58:48 volumioalrum volumio[1026]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 13 15:58:48 volumioalrum volumio[1026]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Oct 13 15:58:48 volumioalrum volumio[1026]: at Socket.emit (events.js:400:28) Oct 13 15:58:48 volumioalrum volumio[1026]: at addChunk (internal/streams/readable.js:293:12) Oct 13 15:58:48 volumioalrum volumio[1026]: at readableAddChunk (internal/streams/readable.js:267:9) Oct 13 15:58:48 volumioalrum volumio[1026]: at Socket.Readable.push (internal/streams/readable.js:206:10) Oct 13 15:58:48 volumioalrum volumio[1026]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 13 15:58:48 volumioalrum volumio[1026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 15:58:48 volumioalrum go-librespot[2014]: time="2024-10-13T15:58:48+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.101:51750->34.158.1.133:4070: read: connection reset by peer" Oct 13 15:58:48 volumioalrum sudo[2358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 15:57 Oct 13 15:58:48 volumioalrum sudo[2358]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"