-- Logs begin at Fri 2023-10-20 15:45:04 CEST, end at Fri 2023-10-20 15:51:04 CEST. -- Oct 20 15:50:38 volumio ntpd[621]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 15:50:39 volumio ntpd[621]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 15:50:41 volumio ntpd[621]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 20 15:50:42 volumio ntpd[621]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 20 15:50:43 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 20 15:50:43 volumio ifplugd(eth0)[638]: Link beat detected. Oct 20 15:50:43 volumio ifplugd(eth0)[638]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 20 15:50:44 volumio dhcpcd[1035]: DUID 00:01:00:01:2c:c5:40:e9:e4:5f:01:13:1f:47 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: IAID 01:13:1f:47 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: adding address fe80::5687:c50c:565a:f4ad Oct 20 15:50:44 volumio dhcpcd[1035]: ipv6_addaddr1: Permission denied Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: DUID 00:01:00:01:2c:c5:40:e9:e4:5f:01:13:1f:47 Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: IAID 01:13:1f:47 Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: adding address fe80::5687:c50c:565a:f4ad Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: ipv6_addaddr1: Permission denied Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: rebinding lease of 192.168.1.195 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: rebinding lease of 192.168.1.195 Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: NAK: from 192.168.1.254 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: NAK: from 192.168.1.254 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: soliciting a DHCP lease Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: soliciting a DHCP lease Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: offered 192.168.1.197 from 192.168.1.254 Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: offered 192.168.1.197 from 192.168.1.254 Oct 20 15:50:44 volumio dhcpcd[1035]: eth0: soliciting an IPv6 router Oct 20 15:50:44 volumio ifplugd(eth0)[638]: client: eth0: soliciting an IPv6 router Oct 20 15:50:46 volumio dhcpcd[1035]: eth0: probing address 192.168.1.197/24 Oct 20 15:50:46 volumio ifplugd(eth0)[638]: client: eth0: probing address 192.168.1.197/24 Oct 20 15:50:46 volumio dhcpcd[1035]: eth0: hardware address 2c:7b:a0:28:7c:fb claims 192.168.1.195 Oct 20 15:50:46 volumio ifplugd(eth0)[638]: client: eth0: hardware address 2c:7b:a0:28:7c:fb claims 192.168.1.195 Oct 20 15:50:50 volumio dhcpcd[1035]: eth0: leased 192.168.1.197 for 43200 seconds Oct 20 15:50:50 volumio ifplugd(eth0)[638]: client: eth0: leased 192.168.1.197 for 43200 seconds Oct 20 15:50:50 volumio avahi-daemon[522]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.197. Oct 20 15:50:50 volumio avahi-daemon[522]: New relevant interface eth0.IPv4 for mDNS. Oct 20 15:50:50 volumio dhcpcd[1035]: eth0: adding route to 192.168.1.0/24 Oct 20 15:50:50 volumio avahi-daemon[522]: Registering new address record for 192.168.1.197 on eth0.IPv4. Oct 20 15:50:50 volumio dhcpcd[1035]: eth0: adding default route via 192.168.1.254 Oct 20 15:50:50 volumio ifplugd(eth0)[638]: client: eth0: adding route to 192.168.1.0/24 Oct 20 15:50:50 volumio ifplugd(eth0)[638]: client: eth0: adding default route via 192.168.1.254 Oct 20 15:50:50 volumio dnsmasq[727]: reading /etc/resolv.conf Oct 20 15:50:50 volumio dnsmasq[727]: using nameserver 192.168.1.254#53 Oct 20 15:50:50 volumio dnsmasq[727]: using nameserver 208.67.222.222#53 Oct 20 15:50:50 volumio dnsmasq[727]: using nameserver 208.67.220.220#53 Oct 20 15:50:50 volumio dhcpcd[1035]: forked to background, child pid 1068 Oct 20 15:50:50 volumio ifplugd(eth0)[638]: client: forked to background, child pid 1068 Oct 20 15:50:50 volumio ifplugd(eth0)[638]: Program executed successfully. Oct 20 15:50:51 volumio volumio[736]: info: Discovery: this is already registered, 7f0976a8-a875-4f73-8c74-5654e6b33d14 Oct 20 15:50:51 volumio volumio[736]: info: Discovery: Found device Volumio Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:51 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 15:50:51 volumio volumio[736]: info: Discovery: Getting this device information Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:51 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 15:50:51 volumio volumio[736]: verbose: New Socket.io Connection to 192.168.1.197:3000 from 192.168.1.176 UA: Dart/3.4 (dart:io) Total Clients: 3 Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:51 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 20 15:50:51 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 20 15:50:51 volumio ntpd[621]: Listen normally on 4 eth0 192.168.1.197:123 Oct 20 15:50:51 volumio ntpd[621]: new interface(s) found: waking up resolver Oct 20 15:50:58 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 15:50:58 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 15:50:58 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Oct 20 15:50:58 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 15:50:58 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 15:50:58 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 20 15:50:58 volumio volumio[736]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.176 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 4 Oct 20 15:50:58 volumio sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 15:50:58 volumio sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 15:50:58 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Oct 20 15:50:58 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 15:50:58 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 15:50:58 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Oct 20 15:50:59 volumio volumio[736]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.176 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 5 Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:59 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 20 15:50:59 volumio volumio[736]: info: Received Get System Info Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 15:50:59 volumio volumio[736]: info: Discovery: Getting this device information Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:59 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:50:59 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:50:59 volumio volumio[736]: info: Listing playlists Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 15:50:59 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 20 15:51:00 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 15:51:00 volumio volumio[736]: info: Received Get System Info Oct 20 15:51:00 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 15:51:00 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 15:51:00 volumio volumio[736]: info: Discovery: Getting this device information Oct 20 15:51:00 volumio volumio[736]: info: CoreCommandRouter::volumioGetState Oct 20 15:51:00 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:51:00 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 15:51:01 volumio volumio[736]: info: Airplay started streaming, receiving metadatas Oct 20 15:51:01 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:51:01 volumio volumio[736]: info: CoreCommandRouter::volumioStop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::stop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: Airplay started streaming, receiving metadatas Oct 20 15:51:01 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:51:01 volumio volumio[736]: info: CoreCommandRouter::volumioStop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::stop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: Airplay started streaming, receiving metadatas Oct 20 15:51:01 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:51:01 volumio volumio[736]: info: CoreCommandRouter::volumioStop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::stop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio shairport-sync[924]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 20 15:51:01 volumio shairport-sync[924]: Dload Upload Total Spent Left Speed Oct 20 15:51:01 volumio volumio[736]: info: Oct 20 15:51:01 volumio volumio[736]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 20 15:51:01 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 20 15:51:01 volumio volumio[736]: info: Airplay playback start Oct 20 15:51:01 volumio volumio[736]: info: CorePlayQueue::getTrack 0 Oct 20 15:51:01 volumio volumio[736]: info: CoreCommandRouter::volumioStop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::stop Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio volumio[736]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 15:51:01 volumio shairport-sync[924]: [158B blob data] Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:02 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:02 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:02 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:02 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:02 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:03 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:03 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::servicePushState Oct 20 15:51:04 volumio volumio[736]: info: CoreStateMachine::pushState Oct 20 15:51:04 volumio volumio[736]: info: CoreCommandRouter::volumioPushState Oct 20 15:51:04 volumio volumio[736]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 15:51:04 volumio volumio[736]: Error: certificate is not yet valid Oct 20 15:51:04 volumio volumio[736]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Oct 20 15:51:04 volumio volumio[736]: at TLSSocket.emit (events.js:315:20) Oct 20 15:51:04 volumio volumio[736]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Oct 20 15:51:04 volumio volumio[736]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Oct 20 15:51:04 volumio volumio[736]: code: 'CERT_NOT_YET_VALID' Oct 20 15:51:04 volumio volumio[736]: } Oct 20 15:51:04 volumio volumio[736]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 15:51:04 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-10-20 15:50 Oct 20 15:51:04 volumio sudo[1129]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"