-- Logs begin at Sun 2024-04-07 12:32:57 UTC, end at Fri 2024-04-12 09:47:40 UTC. -- Apr 12 09:46:03 d50 volumio[830]: info: [jellyfin-poller] Polled https://media.fumok.dns.navy/: offline Apr 12 09:46:27 d50 dhcpcd[696]: eth0: carrier lost Apr 12 09:46:27 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Apr 12 09:46:27 d50 avahi-daemon[492]: Withdrawing address record for 192.168.178.33 on eth0. Apr 12 09:46:27 d50 avahi-daemon[492]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.33. Apr 12 09:46:27 d50 avahi-daemon[492]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 12 09:46:27 d50 dhcpcd[696]: eth0: deleting route to 192.168.178.0/24 Apr 12 09:46:27 d50 dhcpcd[696]: eth0: deleting default route via 192.168.178.1 Apr 12 09:46:27 d50 dhcpcd[696]: eth0: dhcp_envoption 81.0/3: Operation not supported Apr 12 09:46:27 d50 volumio[830]: info: Discovery: A device disappeared from network Apr 12 09:46:27 d50 volumio[830]: info: Discovery: Device d50 disappeared from network Apr 12 09:46:27 d50 ifplugd(eth0)[734]: Link beat lost. Apr 12 09:46:28 d50 ntpd[726]: Deleting interface #3 eth0, 192.168.178.33#123, interface stats: received=9966, sent=10022, dropped=0, active_time=422420 secs Apr 12 09:46:28 d50 ntpd[726]: 144.76.43.40 local addr 192.168.178.33 -> Apr 12 09:46:28 d50 ntpd[726]: 85.199.214.99 local addr 192.168.178.33 -> Apr 12 09:46:28 d50 ntpd[726]: 178.63.166.157 local addr 192.168.178.33 -> Apr 12 09:46:28 d50 ntpd[726]: 176.9.44.212 local addr 192.168.178.33 -> Apr 12 09:46:28 d50 ntpd[726]: 212.18.3.18 local addr 192.168.178.33 -> Apr 12 09:46:28 d50 ntpd[726]: 162.159.200.1 local addr 192.168.178.33 -> Apr 12 09:46:33 d50 volumio[830]: info: [jellyfin-poller] Polled https://media.fumok.dns.navy/: offline Apr 12 09:46:37 d50 ifplugd(eth0)[734]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Apr 12 09:46:37 d50 ifplugd(eth0)[734]: client: sending signal ALRM to pid 696 Apr 12 09:46:37 d50 dhcpcd[28576]: sending signal ALRM to pid 696 Apr 12 09:46:37 d50 dhcpcd[696]: received SIGALRM, releasing Apr 12 09:46:37 d50 dhcpcd[696]: eth0: removing interface Apr 12 09:46:37 d50 ifplugd(eth0)[734]: client: waiting for pid 696 to exit Apr 12 09:46:37 d50 dhcpcd[28576]: waiting for pid 696 to exit Apr 12 09:46:40 d50 dhcpcd[696]: dhcpcd exited Apr 12 09:46:40 d50 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Apr 12 09:46:40 d50 ifplugd(eth0)[734]: Program executed successfully. Apr 12 09:46:40 d50 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Apr 12 09:46:40 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Apr 12 09:46:42 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 12 09:46:42 d50 ifplugd(eth0)[734]: Link beat detected. Apr 12 09:46:42 d50 ifplugd(eth0)[734]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 12 09:46:42 d50 dhcpcd[28617]: DUID 00:01:00:01:29:aa:89:1c:b8:27:eb:f9:08:51 Apr 12 09:46:42 d50 dhcpcd[28617]: eth0: IAID eb:f9:08:51 Apr 12 09:46:42 d50 dhcpcd[28617]: eth0: adding address fe80::ba27:ebff:fef9:851 Apr 12 09:46:42 d50 dhcpcd[28617]: ipv6_addaddr1: Permission denied Apr 12 09:46:42 d50 ifplugd(eth0)[734]: client: DUID 00:01:00:01:29:aa:89:1c:b8:27:eb:f9:08:51 Apr 12 09:46:42 d50 ifplugd(eth0)[734]: client: eth0: IAID eb:f9:08:51 Apr 12 09:46:42 d50 ifplugd(eth0)[734]: client: eth0: adding address fe80::ba27:ebff:fef9:851 Apr 12 09:46:42 d50 ifplugd(eth0)[734]: client: ipv6_addaddr1: Permission denied Apr 12 09:46:42 d50 dhcpcd[28617]: eth0: soliciting an IPv6 router Apr 12 09:46:42 d50 ifplugd(eth0)[734]: client: eth0: soliciting an IPv6 router Apr 12 09:46:43 d50 dhcpcd[28617]: eth0: rebinding lease of 192.168.178.33 Apr 12 09:46:43 d50 ifplugd(eth0)[734]: client: eth0: rebinding lease of 192.168.178.33 Apr 12 09:46:44 d50 ifplugd(eth0)[734]: client: eth0: carrier lost Apr 12 09:46:44 d50 dhcpcd[28617]: eth0: carrier lost Apr 12 09:46:44 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Apr 12 09:46:46 d50 dhcpcd[28617]: eth0: carrier acquired Apr 12 09:46:46 d50 ifplugd(eth0)[734]: client: eth0: carrier acquired Apr 12 09:46:46 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 12 09:46:46 d50 dhcpcd[28617]: eth0: IAID eb:f9:08:51 Apr 12 09:46:46 d50 ifplugd(eth0)[734]: client: eth0: IAID eb:f9:08:51 Apr 12 09:46:46 d50 dhcpcd[28617]: eth0: rebinding lease of 192.168.178.33 Apr 12 09:46:46 d50 ifplugd(eth0)[734]: client: eth0: rebinding lease of 192.168.178.33 Apr 12 09:46:46 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Apr 12 09:46:47 d50 dhcpcd[28617]: eth0: soliciting an IPv6 router Apr 12 09:46:47 d50 ifplugd(eth0)[734]: client: eth0: soliciting an IPv6 router Apr 12 09:46:47 d50 dhcpcd[28617]: eth0: carrier lost Apr 12 09:46:47 d50 ifplugd(eth0)[734]: client: eth0: carrier lost Apr 12 09:47:00 d50 dhcpcd[28617]: eth0: carrier acquired Apr 12 09:47:00 d50 ifplugd(eth0)[734]: client: eth0: carrier acquired Apr 12 09:47:00 d50 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 12 09:47:00 d50 dhcpcd[28617]: eth0: IAID eb:f9:08:51 Apr 12 09:47:00 d50 ifplugd(eth0)[734]: client: eth0: IAID eb:f9:08:51 Apr 12 09:47:01 d50 dhcpcd[28617]: eth0: soliciting an IPv6 router Apr 12 09:47:01 d50 ifplugd(eth0)[734]: client: eth0: soliciting an IPv6 router Apr 12 09:47:01 d50 dhcpcd[28617]: eth0: rebinding lease of 192.168.178.33 Apr 12 09:47:01 d50 ifplugd(eth0)[734]: client: eth0: rebinding lease of 192.168.178.33 Apr 12 09:47:03 d50 volumio[830]: info: [jellyfin-poller] Polled https://media.fumok.dns.navy/: offline Apr 12 09:47:04 d50 dhcpcd[28617]: eth0: probing address 192.168.178.33/24 Apr 12 09:47:04 d50 ifplugd(eth0)[734]: client: eth0: probing address 192.168.178.33/24 Apr 12 09:47:10 d50 dhcpcd[28617]: eth0: leased 192.168.178.33 for 7200 seconds Apr 12 09:47:10 d50 ifplugd(eth0)[734]: client: eth0: leased 192.168.178.33 for 7200 seconds Apr 12 09:47:10 d50 avahi-daemon[492]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.33. Apr 12 09:47:10 d50 avahi-daemon[492]: New relevant interface eth0.IPv4 for mDNS. Apr 12 09:47:10 d50 dhcpcd[28617]: eth0: adding route to 192.168.178.0/24 Apr 12 09:47:10 d50 avahi-daemon[492]: Registering new address record for 192.168.178.33 on eth0.IPv4. Apr 12 09:47:10 d50 dhcpcd[28617]: eth0: adding default route via 192.168.178.1 Apr 12 09:47:10 d50 dhcpcd[28617]: eth0: dhcp_envoption 81.0/3: Operation not supported Apr 12 09:47:10 d50 ifplugd(eth0)[734]: client: eth0: adding route to 192.168.178.0/24 Apr 12 09:47:10 d50 ifplugd(eth0)[734]: client: eth0: adding default route via 192.168.178.1 Apr 12 09:47:10 d50 ifplugd(eth0)[734]: client: eth0: dhcp_envoption 81.0/3: Operation not supported Apr 12 09:47:10 d50 dhcpcd[28617]: forked to background, child pid 28710 Apr 12 09:47:10 d50 ifplugd(eth0)[734]: client: forked to background, child pid 28710 Apr 12 09:47:10 d50 ifplugd(eth0)[734]: Program executed successfully. Apr 12 09:47:12 d50 ntpd[726]: Listen normally on 4 eth0 192.168.178.33:123 Apr 12 09:47:12 d50 ntpd[726]: new interface(s) found: waking up resolver Apr 12 09:47:29 d50 volumio[830]: info: Tunnel connection is inactive, restarting it Apr 12 09:47:29 d50 volumio[830]: info: Starting Tunnel 1 Apr 12 09:47:29 d50 volumio[830]: info: Starting Tunnel Connection Checker Apr 12 09:47:29 d50 sudo[28741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 12 09:47:29 d50 sudo[28741]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 09:47:29 d50 systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 09:47:29 d50 sudo[28741]: pam_unix(sudo:session): session closed for user root Apr 12 09:47:29 d50 volumio[830]: info: Remote SSH Started Apr 12 09:47:29 d50 autossh[28744]: port set to 0, monitoring disabled Apr 12 09:47:29 d50 autossh[28744]: starting ssh (count 1) Apr 12 09:47:29 d50 autossh[28744]: ssh child pid is 28747 Apr 12 09:47:30 d50 volumiossh-tunnel[28743]: ssh: connect to host eu7.myvolumio.org port 2222: Network is unreachable Apr 12 09:47:30 d50 autossh[28744]: ssh exited prematurely with status 255; autossh exiting Apr 12 09:47:30 d50 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 12 09:47:30 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:30 d50 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Apr 12 09:47:30 d50 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Apr 12 09:47:30 d50 systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 12 09:47:30 d50 systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 09:47:30 d50 autossh[28749]: port set to 0, monitoring disabled Apr 12 09:47:30 d50 autossh[28749]: starting ssh (count 1) Apr 12 09:47:30 d50 autossh[28749]: ssh child pid is 28752 Apr 12 09:47:31 d50 volumiossh-tunnel[28748]: ssh: connect to host eu7.myvolumio.org port 2222: Network is unreachable Apr 12 09:47:31 d50 autossh[28749]: ssh exited prematurely with status 255; autossh exiting Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Apr 12 09:47:31 d50 systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 12 09:47:31 d50 systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 09:47:31 d50 autossh[28755]: port set to 0, monitoring disabled Apr 12 09:47:31 d50 autossh[28755]: starting ssh (count 1) Apr 12 09:47:31 d50 autossh[28755]: ssh child pid is 28758 Apr 12 09:47:31 d50 volumiossh-tunnel[28753]: ssh: connect to host eu7.myvolumio.org port 2222: Network is unreachable Apr 12 09:47:31 d50 autossh[28755]: ssh exited prematurely with status 255; autossh exiting Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 12 09:47:31 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Apr 12 09:47:32 d50 systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 12 09:47:32 d50 systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 09:47:32 d50 autossh[28760]: port set to 0, monitoring disabled Apr 12 09:47:32 d50 autossh[28760]: starting ssh (count 1) Apr 12 09:47:32 d50 autossh[28760]: ssh child pid is 28763 Apr 12 09:47:32 d50 volumiossh-tunnel[28759]: ssh: connect to host eu7.myvolumio.org port 2222: Network is unreachable Apr 12 09:47:32 d50 autossh[28760]: ssh exited prematurely with status 255; autossh exiting Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Apr 12 09:47:32 d50 systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 12 09:47:32 d50 systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 09:47:32 d50 autossh[28765]: port set to 0, monitoring disabled Apr 12 09:47:32 d50 autossh[28765]: starting ssh (count 1) Apr 12 09:47:32 d50 autossh[28765]: ssh child pid is 28768 Apr 12 09:47:32 d50 volumiossh-tunnel[28764]: ssh: connect to host eu7.myvolumio.org port 2222: Network is unreachable Apr 12 09:47:32 d50 autossh[28765]: ssh exited prematurely with status 255; autossh exiting Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Apr 12 09:47:32 d50 systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Start request repeated too quickly. Apr 12 09:47:32 d50 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 12 09:47:32 d50 systemd[1]: Failed to start MyVolumio SSH Tunnel. Apr 12 09:47:39 d50 ntpd[726]: Soliciting pool server 162.159.200.123 Apr 12 09:47:39 d50 volumio[830]: info: Discovery: adding 2e38a4e4-a3e0-4aeb-95bc-aabf816e472d Apr 12 09:47:39 d50 volumio[830]: info: Discovery: Found device d50 Apr 12 09:47:39 d50 volumio[830]: info: CoreCommandRouter::volumioGetState Apr 12 09:47:39 d50 volumio[830]: info: CorePlayQueue::getTrack 0 Apr 12 09:47:39 d50 volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 09:47:39 d50 volumio[830]: Error: The value NaN is not a number Apr 12 09:47:39 d50 volumio[830]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 12 09:47:39 d50 volumio[830]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 12 09:47:39 d50 volumio[830]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 12 09:47:39 d50 volumio[830]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 12 09:47:39 d50 volumio[830]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 12 09:47:39 d50 volumio[830]: at Browser.emit (events.js:315:20) Apr 12 09:47:39 d50 volumio[830]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 12 09:47:39 d50 volumio[830]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 12 09:47:39 d50 volumio[830]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 12 09:47:39 d50 volumio[830]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 12 09:47:39 d50 volumio[830]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 12 09:47:39 d50 volumio[830]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 12 09:47:39 d50 volumio[830]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 12 09:47:39 d50 volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 09:47:40 d50 sudo[28778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 09:46 Apr 12 09:47:40 d50 sudo[28778]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"