-- Logs begin at Thu 2024-10-31 08:46:14 -03, end at Thu 2024-10-31 15:17:27 -03. -- Oct 31 15:16:24 volumio go-librespot[1562]: time="2024-10-31T15:16:24-03:00" level=error msg="did not receive last pong from dealer, 210s passed" Oct 31 15:16:29 volumio sudo[24017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 15:16:29 volumio sudo[24017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 15:16:29 volumio sudo[24017]: pam_unix(sudo:session): session closed for user root Oct 31 15:16:29 volumio sudo[24019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 15:16:29 volumio sudo[24019]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 15:16:29 volumio sudo[24019]: pam_unix(sudo:session): session closed for user root Oct 31 15:16:29 volumio volumio[995]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.49 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 31 15:16:29 volumio volumio[995]: info: Received Get System Info Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 15:16:29 volumio volumio[995]: info: Discovery: Getting this device information Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:29 volumio volumio[995]: info: Listing playlists Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 31 15:16:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 31 15:16:30 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:30 volumio ntpd[22366]: ntpd exiting on signal 15 (Terminated) Oct 31 15:16:30 volumio ntpd[22366]: 194.27.156.207 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio ntpd[22366]: 194.27.222.5 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio ntpd[22366]: 162.159.200.123 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio ntpd[22366]: 89.252.135.27 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio systemd[1]: Stopping Network Time Service... Oct 31 15:16:30 volumio ntpd[22366]: 162.159.200.1 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio ntpd[22366]: 85.199.214.98 local addr 192.168.1.47 -> Oct 31 15:16:30 volumio systemd[1]: ntp.service: Succeeded. Oct 31 15:16:30 volumio systemd[1]: Stopped Network Time Service. Oct 31 15:16:30 volumio systemd[1]: Starting Network Time Service... Oct 31 15:16:30 volumio ntpd[24053]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 31 15:16:30 volumio ntpd[24053]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 31 15:16:30 volumio systemd[1]: Started Network Time Service. Oct 31 15:16:30 volumio ntpd[24059]: proto: precision = 0.185 usec (-22) Oct 31 15:16:30 volumio ntpd[24059]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 31 15:16:30 volumio ntpd[24059]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 31 15:16:30 volumio ntpd[24059]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 674 days ago Oct 31 15:16:30 volumio ntpd[24059]: Listen and drop on 0 v6wildcard [::]:123 Oct 31 15:16:30 volumio ntpd[24059]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 31 15:16:30 volumio ntpd[24059]: Listen normally on 2 lo 127.0.0.1:123 Oct 31 15:16:30 volumio ntpd[24059]: Listen normally on 3 eth0 192.168.1.47:123 Oct 31 15:16:30 volumio ntpd[24059]: Listening on routing socket on fd #20 for interface updates Oct 31 15:16:30 volumio ntpd[24059]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 31 15:16:30 volumio ntpd[24059]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 15:16:31 volumio volumio[995]: info: Received Get System Info Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 15:16:31 volumio volumio[995]: info: Discovery: Getting this device information Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 15:16:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 31 15:16:31 volumio ntpd[24059]: Soliciting pool server 37.187.145.181 Oct 31 15:16:32 volumio ntpd[24059]: Soliciting pool server 85.199.214.98 Oct 31 15:16:34 volumio systemd[1]: Stopping Network Time Service... Oct 31 15:16:34 volumio ntpd[24059]: ntpd exiting on signal 15 (Terminated) Oct 31 15:16:34 volumio ntpd[24059]: 37.187.145.181 local addr 192.168.1.47 -> Oct 31 15:16:34 volumio systemd[1]: ntp.service: Succeeded. Oct 31 15:16:34 volumio systemd[1]: Stopped Network Time Service. Oct 31 15:16:34 volumio systemd[1]: Starting Network Time Service... Oct 31 15:16:34 volumio ntpd[24093]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 31 15:16:34 volumio ntpd[24093]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 31 15:16:34 volumio systemd[1]: Started Network Time Service. Oct 31 15:16:34 volumio ntpd[24099]: proto: precision = 0.180 usec (-22) Oct 31 15:16:34 volumio ntpd[24099]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 31 15:16:34 volumio ntpd[24099]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 31 15:16:34 volumio ntpd[24099]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 674 days ago Oct 31 15:16:34 volumio ntpd[24099]: Listen and drop on 0 v6wildcard [::]:123 Oct 31 15:16:34 volumio ntpd[24099]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 31 15:16:34 volumio ntpd[24099]: Listen normally on 2 lo 127.0.0.1:123 Oct 31 15:16:34 volumio ntpd[24099]: Listen normally on 3 eth0 192.168.1.47:123 Oct 31 15:16:34 volumio ntpd[24099]: Listening on routing socket on fd #20 for interface updates Oct 31 15:16:34 volumio ntpd[24099]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 31 15:16:34 volumio ntpd[24099]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 31 15:16:35 volumio ntpd[24099]: Soliciting pool server 37.187.145.181 Oct 31 15:16:36 volumio ntpd[24099]: Soliciting pool server 85.199.214.98 Oct 31 15:16:39 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 31 15:16:42 volumio ntpd[24099]: Soliciting pool server 89.252.135.27 Oct 31 15:16:42 volumio ntpd[24099]: Soliciting pool server 45.136.155.37 Oct 31 15:16:43 volumio volumio[995]: info: CoreCommandRouter::volumioNext Oct 31 15:16:43 volumio volumio[995]: info: CoreStateMachine::next Oct 31 15:16:43 volumio volumio[995]: info: Spotify next Oct 31 15:16:43 volumio volumio[995]: info: Sending Spotify command to local API: /player/next Oct 31 15:16:43 volumio ntpd[24099]: Soliciting pool server 212.154.83.90 Oct 31 15:16:43 volumio ntpd[24099]: Soliciting pool server 178.79.155.116 Oct 31 15:16:44 volumio ntpd[24099]: Soliciting pool server 93.115.79.15 Oct 31 15:16:44 volumio ntpd[24099]: Soliciting pool server 162.159.200.1 Oct 31 15:16:45 volumio ntpd[24099]: Soliciting pool server 194.27.222.5 Oct 31 15:16:52 volumio ntpd[24099]: Soliciting pool server 176.235.250.150 Oct 31 15:16:54 volumio go-librespot[1562]: time="2024-10-31T15:16:54-03:00" level=error msg="did not receive last pong from dealer, 240s passed" Oct 31 15:17:21 volumio volumio[995]: info: CoreCommandRouter::volumioNext Oct 31 15:17:21 volumio volumio[995]: info: CoreStateMachine::next Oct 31 15:17:21 volumio volumio[995]: info: Spotify next Oct 31 15:17:21 volumio volumio[995]: info: Sending Spotify command to local API: /player/next Oct 31 15:17:24 volumio go-librespot[1562]: time="2024-10-31T15:17:24-03:00" level=error msg="did not receive last pong from dealer, 270s passed" Oct 31 15:17:26 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 15:17:26 volumio volumio[995]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 31 15:17:26 volumio volumio[995]: code: 'auth/network-request-failed', Oct 31 15:17:26 volumio volumio[995]: a: null Oct 31 15:17:26 volumio volumio[995]: } Oct 31 15:17:26 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 15:17:27 volumio sudo[24163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-31 15:16 Oct 31 15:17:27 volumio sudo[24163]: 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"