-- 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"