-- Logs begin at Sun 2024-09-01 04:53:46 WAT, end at Sun 2024-09-01 07:29:45 WAT. -- Sep 01 07:28:00 volumio volumio[19722]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 07:28:00 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 07:28:01 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 07:28:03 volumio volumio[19722]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 01 07:28:03 volumio volumio[19722]: info: Completed starting MyVolumio Plugin Sep 01 07:28:03 volumio volumio[19722]: info: BOOT COMPLETED Sep 01 07:28:03 volumio volumio[19722]: [Metrics] CommandRouter: 65s 397.75ms Sep 01 07:28:03 volumio volumio[19722]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 07:28:03 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 07:28:03 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:03 volumio volumio[19722]: info: CoreCommandRouter::Close All Modals sent Sep 01 07:28:03 volumio volumio[19722]: info: CoreCommandRouter::Close All Modals sent Sep 01 07:28:04 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 07:28:04 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 07:28:04 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 07:28:04 volumio volumio[19722]: info: MyVolumio token set successfully Sep 01 07:28:04 volumio volumio[19722]: info: MYVOLUMIO: Adding device Sep 01 07:28:04 volumio volumio[19722]: info: MYVOLUMIO: Evaluating Server Sep 01 07:28:05 volumio volumio[19722]: info: MyVolumio status changed Sep 01 07:28:05 volumio volumio[19722]: info: Streaming services startup Sep 01 07:28:05 volumio volumio[19722]: info: Starting Streaming Daemon Sep 01 07:28:05 volumio volumio[19722]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 07:28:05 volumio volumio[19722]: info: Removing audio output: Sep 01 07:28:05 volumio volumio[19722]: info: Stoppping Tunnel 1 Sep 01 07:28:05 volumio sudo[20134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 07:28:05 volumio sudo[20134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 07:28:05 volumio sudo[20134]: pam_unix(sudo:session): session closed for user root Sep 01 07:28:05 volumio volumio[19722]: error: Cannot start Volumio Streaming Daemon Sep 01 07:28:05 volumio volumio[19722]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 07:28:05 volumio volumio[19722]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 07:28:05 volumio sudo[20137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 07:28:05 volumio sudo[20137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 07:28:05 volumio sudo[20137]: pam_unix(sudo:session): session closed for user root Sep 01 07:28:05 volumio volumio[19722]: info: Remote SSH Stopped Sep 01 07:28:10 volumio volumio[19722]: info: Setting Geolocation for MyVolumio to eu6 Sep 01 07:28:10 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:10 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:10 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:11 volumio volumio[19722]: info: Updating MyVolumio device info Sep 01 07:28:11 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:16 volumio volumio[19722]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 07:28:17 volumio volumio[19722]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 07:28:20 volumio volumio[19722]: info: MYVOLUMIO: Adding device Sep 01 07:28:20 volumio volumio[19722]: info: MYVOLUMIO: Evaluating Server Sep 01 07:28:26 volumio volumio[19722]: info: Setting Geolocation for MyVolumio to eu6 Sep 01 07:28:26 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:27 volumio volumio[19722]: info: Updating MyVolumio device info Sep 01 07:28:27 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:27 volumio volumio[19722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 07:28:32 volumio volumio[19722]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 07:28:35 volumio volumio[19722]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 07:29:32 volumio kernel: usb 1-2: USB disconnect, device number 42 Sep 01 07:29:32 volumio wpa_supplicant[1161]: wlan0: CTRL-EVENT-DISCONNECTED bssid=9c:9d:7e:c1:35:32 reason=3 locally_generated=1 Sep 01 07:29:32 volumio kernel: wlan0: deauthenticating from 9c:9d:7e:c1:35:32 by local choice (Reason: 3=DEAUTH_LEAVING) Sep 01 07:29:32 volumio dhcpcd[770]: wlan0: carrier lost Sep 01 07:29:32 volumio avahi-daemon[694]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 01 07:29:32 volumio avahi-daemon[694]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.178. Sep 01 07:29:32 volumio avahi-daemon[694]: Withdrawing address record for 192.168.31.178 on wlan0. Sep 01 07:29:32 volumio volumio[19722]: info: Discovery: A device disappeared from network Sep 01 07:29:32 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to set IPv4 unicast in multicast filter Sep 01 07:29:32 volumio volumio[19722]: info: Discovery: Device volumio disappeared from network Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to set IPv4 unicast in multicast filter Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Sep 01 07:29:32 volumio wpa_supplicant[1161]: nl80211: Failed to set IPv4 unicast in multicast filter Sep 01 07:29:32 volumio systemd[1]: Stopping ifup for wlan0... Sep 01 07:29:32 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Sep 01 07:29:32 volumio dhcpcd[770]: wlan0: deleting route to 192.168.31.0/24 Sep 01 07:29:32 volumio dhcpcd[770]: wlan0: deleting default route via 192.168.31.1 Sep 01 07:29:32 volumio kernel: usb 1-2: new high-speed USB device number 43 using xhci_hcd Sep 01 07:29:32 volumio dhcpcd[770]: wlan0: removing interface Sep 01 07:29:32 volumio systemd[1]: ifup@wlan0.service: Succeeded. Sep 01 07:29:32 volumio systemd[1]: Stopped ifup for wlan0. Sep 01 07:29:32 volumio kernel: usb 1-2: New USB device found, idVendor=148f, idProduct=5370, bcdDevice= 1.01 Sep 01 07:29:32 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 01 07:29:32 volumio kernel: usb 1-2: Product: 802.11 n WLAN Sep 01 07:29:32 volumio kernel: usb 1-2: Manufacturer: Ralink Sep 01 07:29:32 volumio kernel: usb 1-2: SerialNumber: 1.0 Sep 01 07:29:32 volumio kernel: usb 1-2: reset high-speed USB device number 43 using xhci_hcd Sep 01 07:29:33 volumio kernel: ieee80211 phy39: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected Sep 01 07:29:33 volumio kernel: ieee80211 phy39: rt2x00_set_rf: Info - RF chipset 5370 detected Sep 01 07:29:33 volumio kernel: ieee80211 phy39: Selected rate control algorithm 'minstrel_ht' Sep 01 07:29:33 volumio kernel: ieee80211 phy39: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin' Sep 01 07:29:33 volumio kernel: ieee80211 phy39: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36 Sep 01 07:29:33 volumio systemd-udevd[20206]: Using default interface naming scheme 'v240'. Sep 01 07:29:33 volumio systemd-udevd[20206]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 01 07:29:33 volumio dhcpcd[770]: wlan0: waiting for carrier Sep 01 07:29:33 volumio dhcpcd[770]: wlan0: carrier acquired Sep 01 07:29:33 volumio dhcpcd[770]: wlan0: IAID 4a:2e:e5:50 Sep 01 07:29:33 volumio dhcpcd[770]: wlan0: adding address fe80::a6:4cc2:885f:ddb5 Sep 01 07:29:33 volumio dhcpcd[770]: ipv6_addaddr1: Permission denied Sep 01 07:29:33 volumio dhcpcd[770]: wlan0: carrier lost Sep 01 07:29:33 volumio systemd[1]: Found device RT5370 Wireless Adapter. Sep 01 07:29:33 volumio systemd[1]: Started ifup for wlan0. Sep 01 07:29:33 volumio sh[20311]: wlan0=wlan0 Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: SME: Trying to authenticate with 9c:9d:7e:c1:35:32 (SSID='rt24' freq=2462 MHz) Sep 01 07:29:34 volumio kernel: wlan0: authenticate with 9c:9d:7e:c1:35:32 Sep 01 07:29:34 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Sep 01 07:29:34 volumio kernel: wlan0: send auth to 9c:9d:7e:c1:35:32 (try 1/3) Sep 01 07:29:34 volumio kernel: wlan0: send auth to 9c:9d:7e:c1:35:32 (try 2/3) Sep 01 07:29:34 volumio kernel: wlan0: send auth to 9c:9d:7e:c1:35:32 (try 3/3) Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: Trying to associate with 9c:9d:7e:c1:35:32 (SSID='rt24' freq=2462 MHz) Sep 01 07:29:34 volumio kernel: wlan0: authenticated Sep 01 07:29:34 volumio kernel: wlan0: associate with 9c:9d:7e:c1:35:32 (try 1/3) Sep 01 07:29:34 volumio kernel: wlan0: RX AssocResp from 9c:9d:7e:c1:35:32 (capab=0x431 status=0 aid=1) Sep 01 07:29:34 volumio kernel: wlan0: associated Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: Associated with 9c:9d:7e:c1:35:32 Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: WPA: Key negotiation completed with 9c:9d:7e:c1:35:32 [PTK=CCMP GTK=CCMP] Sep 01 07:29:34 volumio wpa_supplicant[1161]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:9d:7e:c1:35:32 completed [id=0 id_str=] Sep 01 07:29:34 volumio dhcpcd[770]: wlan0: carrier acquired Sep 01 07:29:34 volumio dhcpcd[770]: wlan0: IAID 4a:2e:e5:50 Sep 01 07:29:35 volumio dhcpcd[770]: wlan0: soliciting an IPv6 router Sep 01 07:29:35 volumio dhcpcd[770]: wlan0: rebinding lease of 192.168.31.178 Sep 01 07:29:35 volumio ntpd[19808]: Deleting interface #3 wlan0, 192.168.31.178#123, interface stats: received=59, sent=61, dropped=3, active_time=153 secs Sep 01 07:29:35 volumio ntpd[19808]: 54.38.114.34 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 37.59.63.125 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 82.67.71.253 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 51.255.95.80 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 146.59.35.38 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 80.67.184.129 local addr 192.168.31.178 -> Sep 01 07:29:35 volumio ntpd[19808]: 57.128.198.110 local addr 192.168.31.178 -> Sep 01 07:29:38 volumio systemd[1]: systemd-rfkill.service: Succeeded. Sep 01 07:29:40 volumio dhcpcd[770]: wlan0: probing address 192.168.31.178/24 Sep 01 07:29:45 volumio volumio[19722]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 07:29:45 volumio volumio[19722]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 01 07:29:45 volumio volumio[19722]: at doSend (dgram.js:714:16) Sep 01 07:29:45 volumio volumio[19722]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Sep 01 07:29:45 volumio volumio[19722]: at afterDns (dgram.js:660:5) Sep 01 07:29:45 volumio volumio[19722]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Sep 01 07:29:45 volumio volumio[19722]: errno: -101, Sep 01 07:29:45 volumio volumio[19722]: code: 'ENETUNREACH', Sep 01 07:29:45 volumio volumio[19722]: syscall: 'send', Sep 01 07:29:45 volumio volumio[19722]: address: '255.255.255.255', Sep 01 07:29:45 volumio volumio[19722]: port: 3483 Sep 01 07:29:45 volumio volumio[19722]: } Sep 01 07:29:45 volumio volumio[19722]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 07:29:45 volumio dhcpcd[770]: wlan0: leased 192.168.31.178 for 43200 seconds Sep 01 07:29:45 volumio dhcpcd[770]: wlan0: adding route to 192.168.31.0/24 Sep 01 07:29:45 volumio avahi-daemon[694]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.178. Sep 01 07:29:45 volumio avahi-daemon[694]: New relevant interface wlan0.IPv4 for mDNS. Sep 01 07:29:45 volumio avahi-daemon[694]: Registering new address record for 192.168.31.178 on wlan0.IPv4. Sep 01 07:29:45 volumio dhcpcd[770]: wlan0: adding default route via 192.168.31.1 Sep 01 07:29:45 volumio systemd[1]: Stopping Network Time Service... Sep 01 07:29:45 volumio ntpd[19808]: ntpd exiting on signal 15 (Terminated) Sep 01 07:29:45 volumio systemd[1]: ntp.service: Succeeded. Sep 01 07:29:45 volumio systemd[1]: Stopped Network Time Service. Sep 01 07:29:45 volumio systemd[1]: Starting Network Time Service... Sep 01 07:29:45 volumio ntpd[20398]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 07:29:45 volumio ntpd[20398]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 07:29:45 volumio systemd[1]: Started Network Time Service. Sep 01 07:29:45 volumio ntpd[20404]: proto: precision = 0.277 usec (-22) Sep 01 07:29:45 volumio ntpd[20404]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 07:29:45 volumio ntpd[20404]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 07:29:45 volumio ntpd[20404]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 614 days ago Sep 01 07:29:45 volumio ntpd[20404]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 07:29:45 volumio ntpd[20404]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 07:29:45 volumio ntpd[20404]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 07:29:45 volumio ntpd[20404]: Listen normally on 3 wlan0 192.168.31.178:123 Sep 01 07:29:45 volumio ntpd[20404]: Listening on routing socket on fd #20 for interface updates Sep 01 07:29:45 volumio ntpd[20404]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 01 07:29:45 volumio ntpd[20404]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 01 07:29:45 volumio sudo[20407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 07:28 Sep 01 07:29:45 volumio sudo[20407]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"