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