-- Logs begin at Sat 2025-04-26 11:19:31 CEST, end at Sat 2025-04-26 20:00:48 CEST. -- Apr 26 19:59:39 volumio ntpd[1279]: Soliciting pool server 93.94.88.50 Apr 26 19:59:45 volumio ntpd[1279]: Soliciting pool server 93.94.88.50 Apr 26 19:59:57 volumio ntpd[1279]: Soliciting pool server 95.110.254.234 Apr 26 20:00:01 volumio ntpd[1279]: Soliciting pool server 2a00:6d41:200:2::13 Apr 26 20:00:22 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Apr 26 20:00:22 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Apr 26 20:00:25 volumio volumio[1239]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 20:00:25 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 20:00:25 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 26 20:00:25 volumio sudo[10840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 26 20:00:25 volumio sudo[10840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10840]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio sudo[10846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 20:00:25 volumio sudo[10846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10846]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio sudo[10855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 20:00:25 volumio sudo[10855]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10855]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio sudo[10860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 20:00:25 volumio sudo[10860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10860]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio sudo[10866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 20:00:25 volumio sudo[10866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10866]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio sudo[10869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 20:00:25 volumio sudo[10869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:25 volumio sudo[10869]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:25 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 26 20:00:25 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 26 20:00:25 volumio sudo[10872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 20:00:25 volumio sudo[10872]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 26 20:00:30 volumio volumio[1239]: info: Saving new wireless network Apr 26 20:00:30 volumio sudo[10876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Apr 26 20:00:30 volumio sudo[10876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio sudo[10876]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:30 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 20:00:30 volumio volumio[1239]: info: Discovery: Restarting Advertising due to device name change Apr 26 20:00:30 volumio sudo[10879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 20:00:30 volumio sudo[10879]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio systemd[1]: Stopping Wireless Services... Apr 26 20:00:30 volumio dhcpcd[773]: wlan0: carrier lost Apr 26 20:00:30 volumio kernel: wlan0: deauthenticating from 10:3c:59:85:0c:b3 by local choice (Reason: 3=DEAUTH_LEAVING) Apr 26 20:00:30 volumio avahi-daemon[729]: Withdrawing address record for 192.168.0.123 on wlan0. Apr 26 20:00:30 volumio avahi-daemon[729]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. Apr 26 20:00:30 volumio wpa_supplicant[1151]: wlan0: CTRL-EVENT-DISCONNECTED bssid=10:3c:59:85:0c:b3 reason=3 locally_generated=1 Apr 26 20:00:30 volumio avahi-daemon[729]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 26 20:00:30 volumio volumio[1239]: info: Discovery: A device disappeared from network Apr 26 20:00:30 volumio volumio[1239]: info: Discovery: Device volumio disappeared from network Apr 26 20:00:30 volumio dhcpcd[773]: eth0: adding default route Apr 26 20:00:30 volumio dhcpcd[773]: wlan0: deleting route to 192.168.0.0/24 Apr 26 20:00:30 volumio dhcpcd[773]: wlan0: deleting default route via 192.168.0.1 Apr 26 20:00:30 volumio wpa_supplicant[1151]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 26 20:00:30 volumio wpa_supplicant[1151]: wlan0: CTRL-EVENT-TERMINATING Apr 26 20:00:30 volumio sudo[10872]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:30 volumio systemd[1]: wireless.service: Succeeded. Apr 26 20:00:30 volumio systemd[1]: Stopped Wireless Services. Apr 26 20:00:30 volumio volumio[1239]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 26 20:00:30 volumio volumio[1239]: wlan0 Failed to read scan data : Network is down Apr 26 20:00:30 volumio volumio[1239]: info: Cannot use regular scanning, forcing with ap-force Apr 26 20:00:30 volumio systemd[1]: Starting Wireless Services... Apr 26 20:00:30 volumio sudo[10909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 26 20:00:30 volumio sudo[10909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio sudo[10909]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:30 volumio volumio[1239]: command failed: Network is down (-100) Apr 26 20:00:30 volumio volumio[1239]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 26 20:00:30 volumio volumio[1239]: command failed: Network is down (-100) Apr 26 20:00:30 volumio wireless.js[10907]: Cleaning previous... Apr 26 20:00:30 volumio sudo[10925]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 20:00:30 volumio sudo[10925]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio sudo[10925]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:30 volumio sudo[10927]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 20:00:30 volumio sudo[10927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:30 volumio sudo[10927]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:30 volumio wireless.js[10907]: Stopped aP Apr 26 20:00:30 volumio sudo[10934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 20:00:30 volumio sudo[10934]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:31 volumio sudo[10934]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:31 volumio sudo[10936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 26 20:00:31 volumio sudo[10936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:31 volumio sudo[10936]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:31 volumio sudo[10943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 20:00:31 volumio sudo[10943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:31 volumio sudo[10943]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:31 volumio sudo[10945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 26 20:00:31 volumio sudo[10945]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:31 volumio volumio[1239]: info: Discovery: A device disappeared from network Apr 26 20:00:32 volumio sudo[10945]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:32 volumio wireless.js[10907]: Start wireless flow Apr 26 20:00:32 volumio wireless.js[10907]: Stopped hotspot (if there).. Apr 26 20:00:32 volumio sudo[10950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 20:00:32 volumio sudo[10950]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:32 volumio sudo[10950]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:32 volumio sudo[10952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 20:00:32 volumio sudo[10952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:32 volumio sudo[10952]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:32 volumio wireless.js[10907]: DHCP IP Apr 26 20:00:32 volumio wireless.js[10907]: Start ap Apr 26 20:00:32 volumio sudo[10955]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 26 20:00:32 volumio sudo[10955]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:32 volumio wpa_supplicant[10954]: Successfully initialized wpa_supplicant Apr 26 20:00:32 volumio dhcpcd[10956]: sending commands to master dhcpcd process Apr 26 20:00:32 volumio dhcpcd[773]: control command: /sbin/dhcpcd Apr 26 20:00:32 volumio sudo[10955]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:33 volumio wireless.js[10907]: trying... Apr 26 20:00:33 volumio sudo[10959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:33 volumio sudo[10959]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:33 volumio sudo[10959]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:33 volumio kernel: wlan0: authenticate with 10:3c:59:85:0c:b3 Apr 26 20:00:33 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: SME: Trying to authenticate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) Apr 26 20:00:33 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 1/3) Apr 26 20:00:33 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 2/3) Apr 26 20:00:33 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 3/3) Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: Trying to associate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) Apr 26 20:00:33 volumio kernel: wlan0: authenticated Apr 26 20:00:33 volumio kernel: wlan0: associate with 10:3c:59:85:0c:b3 (try 1/3) Apr 26 20:00:33 volumio kernel: wlan0: RX AssocResp from 10:3c:59:85:0c:b3 (capab=0x1431 status=0 aid=3) Apr 26 20:00:33 volumio kernel: usb 2-1.1: rtl8xxxu_bss_info_changed: HT supported Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: Associated with 10:3c:59:85:0c:b3 Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Apr 26 20:00:33 volumio kernel: wlan0: associated Apr 26 20:00:33 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 10:3c:59:85:0c:b3 Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: WPA: Key negotiation completed with 10:3c:59:85:0c:b3 [PTK=CCMP GTK=CCMP] Apr 26 20:00:33 volumio wpa_supplicant[10957]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:3c:59:85:0c:b3 completed [id=0 id_str=] Apr 26 20:00:33 volumio dhcpcd[773]: wlan0: carrier acquired Apr 26 20:00:33 volumio dhcpcd[773]: wlan0: IAID 43:b1:1a:48 Apr 26 20:00:33 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Apr 26 20:00:33 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Apr 26 20:00:34 volumio dhcpcd[773]: wlan0: soliciting an IPv6 router Apr 26 20:00:34 volumio wireless.js[10907]: trying... Apr 26 20:00:34 volumio sudo[10963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:34 volumio sudo[10963]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:34 volumio sudo[10963]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:34 volumio dhcpcd[773]: wlan0: rebinding lease of 192.168.0.123 Apr 26 20:00:34 volumio dhcpcd[773]: wlan0: probing address 192.168.0.123/24 Apr 26 20:00:35 volumio wireless.js[10907]: trying... Apr 26 20:00:35 volumio sudo[10966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:35 volumio sudo[10966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:35 volumio sudo[10966]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:35 volumio volumio[1239]: info: Discovery: Started advertising with name: Volumio Apr 26 20:00:35 volumio ntpd[1279]: Deleting interface #3 wlan0, 192.168.0.123#123, interface stats: received=4, sent=11, dropped=0, active_time=31252 secs Apr 26 20:00:36 volumio wireless.js[10907]: trying... Apr 26 20:00:36 volumio sudo[10970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:36 volumio sudo[10970]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:36 volumio sudo[10970]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:37 volumio wireless.js[10907]: trying... Apr 26 20:00:37 volumio sudo[10974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:37 volumio sudo[10974]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:37 volumio sudo[10974]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:38 volumio wireless.js[10907]: trying... Apr 26 20:00:38 volumio sudo[10977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:38 volumio sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:38 volumio sudo[10977]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:39 volumio dhcpcd[773]: wlan0: leased 192.168.0.123 for 86400 seconds Apr 26 20:00:39 volumio dhcpcd[773]: wlan0: adding route to 192.168.0.0/24 Apr 26 20:00:39 volumio dhcpcd[773]: wlan0: adding default route via 192.168.0.1 Apr 26 20:00:39 volumio avahi-daemon[729]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. Apr 26 20:00:39 volumio dhcpcd[773]: eth0: deleting default route Apr 26 20:00:39 volumio avahi-daemon[729]: New relevant interface wlan0.IPv4 for mDNS. Apr 26 20:00:39 volumio avahi-daemon[729]: Registering new address record for 192.168.0.123 on wlan0.IPv4. Apr 26 20:00:39 volumio dhcpcd[1529]: eth0: deleted default route Apr 26 20:00:39 volumio ntpd[1279]: ntpd exiting on signal 15 (Terminated) Apr 26 20:00:39 volumio ntpd[1279]: 162.159.200.123 local addr 169.254.93.118 -> Apr 26 20:00:39 volumio ntpd[1279]: 31.14.133.122 local addr 169.254.93.118 -> Apr 26 20:00:39 volumio ntpd[1279]: 93.94.88.51 local addr 169.254.93.118 -> Apr 26 20:00:39 volumio ntpd[1279]: 89.46.74.148 local addr 169.254.93.118 -> Apr 26 20:00:39 volumio systemd[1]: Stopping Network Time Service... Apr 26 20:00:39 volumio systemd[1]: ntp.service: Succeeded. Apr 26 20:00:39 volumio systemd[1]: Stopped Network Time Service. Apr 26 20:00:39 volumio systemd[1]: Starting Network Time Service... Apr 26 20:00:39 volumio ntpd[11012]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 26 20:00:39 volumio ntpd[11012]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 26 20:00:39 volumio systemd[1]: Started Network Time Service. Apr 26 20:00:39 volumio ntpd[11018]: proto: precision = 0.147 usec (-23) Apr 26 20:00:39 volumio ntpd[11018]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 26 20:00:39 volumio ntpd[11018]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 26 20:00:39 volumio ntpd[11018]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 851 days ago Apr 26 20:00:39 volumio ntpd[11018]: Listen and drop on 0 v6wildcard [::]:123 Apr 26 20:00:39 volumio ntpd[11018]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 26 20:00:39 volumio ntpd[11018]: Listen normally on 2 lo 127.0.0.1:123 Apr 26 20:00:39 volumio ntpd[11018]: Listen normally on 3 eth0 169.254.93.118:123 Apr 26 20:00:39 volumio ntpd[11018]: Listen normally on 4 wlan0 192.168.0.123:123 Apr 26 20:00:39 volumio ntpd[11018]: Listening on routing socket on fd #21 for interface updates Apr 26 20:00:39 volumio ntpd[11018]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Apr 26 20:00:39 volumio ntpd[11018]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Apr 26 20:00:39 volumio wireless.js[10907]: trying... Apr 26 20:00:39 volumio sudo[11023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 26 20:00:39 volumio sudo[11023]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:39 volumio sudo[11023]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:39 volumio wireless.js[10907]: Connected to: ----5GPowerWiFi-850CB3 Apr 26 20:00:39 volumio wireless.js[10907]: ---- Apr 26 20:00:39 volumio sudo[11026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 20:00:39 volumio sudo[11026]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:39 volumio sudo[11026]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:39 volumio wireless.js[10907]: ... joined AP, wlan0 IPv4 is 192.168.0.123, ipV6 is undefined Apr 26 20:00:39 volumio wireless.js[10907]: It's done! AP Apr 26 20:00:39 volumio iwconfig[11030]: Error for wireless request "Set Power Management" (8B2C) : Apr 26 20:00:39 volumio iwconfig[11030]: SET failed on device wlan0 ; Operation not supported. Apr 26 20:00:39 volumio systemd[1]: Started Wireless Services. Apr 26 20:00:39 volumio sudo[10879]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:40 volumio ntpd[11018]: Soliciting pool server 185.19.184.35 Apr 26 20:00:41 volumio ntpd[11018]: Soliciting pool server 85.199.214.99 Apr 26 20:00:41 volumio ntpd[11018]: Soliciting pool server 93.94.88.50 Apr 26 20:00:41 volumio volumio[1239]: info: Discovery: adding 793c398d-970c-4969-89f7-2a99c140c182 Apr 26 20:00:41 volumio volumio[1239]: info: Discovery: Found device Volumio Apr 26 20:00:41 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Apr 26 20:00:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Apr 26 20:00:41 volumio volumio[1239]: info: Discovery: this is already registered, 793c398d-970c-4969-89f7-2a99c140c182 Apr 26 20:00:41 volumio volumio[1239]: info: Discovery: Found device Volumio Apr 26 20:00:41 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Apr 26 20:00:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Apr 26 20:00:42 volumio ntpd[11018]: Soliciting pool server 95.110.254.234 Apr 26 20:00:42 volumio ntpd[11018]: Soliciting pool server 185.157.229.254 Apr 26 20:00:42 volumio ntpd[11018]: Soliciting pool server 162.159.200.123 Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 20:00:42 volumio volumio[1239]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 20:00:43 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 20:00:43 volumio volumio[1239]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 20:00:43 volumio volumio[1239]: info: MyVolumio login type: Token Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 20:00:43 volumio ntpd[11018]: Soliciting pool server 109.116.201.149 Apr 26 20:00:43 volumio ntpd[11018]: Soliciting pool server 195.32.70.195 Apr 26 20:00:43 volumio ntpd[11018]: Soliciting pool server 89.46.74.148 Apr 26 20:00:43 volumio ntpd[11018]: Soliciting pool server 81.56.230.156 Apr 26 20:00:43 volumio volumio[1239]: info: Starting Streaming Service Transparent Proxy Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 20:00:43 volumio volumio[1239]: info: Streaming services startup Apr 26 20:00:43 volumio volumio[1239]: info: Starting Streaming Daemon Apr 26 20:00:43 volumio sudo[11089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 20:00:43 volumio sudo[11089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:43 volumio sudo[11089]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:43 volumio volumio[1239]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 26 20:00:43 volumio volumio[1239]: error: Cannot start Volumio Streaming Daemon Apr 26 20:00:43 volumio volumio[1239]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 20:00:43 volumio volumio[1239]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 20:00:44 volumio ntpd[11018]: Soliciting pool server 93.44.243.48 Apr 26 20:00:44 volumio ntpd[11018]: Soliciting pool server 162.159.200.1 Apr 26 20:00:44 volumio volumio[1239]: STREAMING PROXY: Starting server on port 3245 Apr 26 20:00:44 volumio volumio[1239]: Node JS runtime: 14 Apr 26 20:00:44 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 26 20:00:44 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 26 20:00:44 volumio volumio[1239]: error: MyVolumio Custom Token format not valid, refreshing it Apr 26 20:00:44 volumio sudo[11096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 20:00:44 volumio sudo[11096]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:44 volumio sudo[11096]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:44 volumio sudo[11099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 20:00:44 volumio sudo[11099]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:44 volumio sudo[11099]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:45 volumio ntpd[11018]: Soliciting pool server 212.45.144.3 Apr 26 20:00:45 volumio ntpd[11018]: Soliciting pool server 2a00:6d41:200:2::15 Apr 26 20:00:45 volumio volumio[1239]: info: MyVolumio login type: Token Apr 26 20:00:46 volumio volumio[1239]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 26 20:00:47 volumio volumio[1239]: info: MyVolumio token set successfully Apr 26 20:00:47 volumio volumio[1239]: info: MYVOLUMIO: Adding device Apr 26 20:00:47 volumio volumio[1239]: info: MYVOLUMIO: Evaluating Server Apr 26 20:00:47 volumio volumio[1239]: info: MyVolumio Plan changed: premium Apr 26 20:00:47 volumio volumio[1239]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 26 20:00:47 volumio volumio[1239]: info: Removing browser output: myVolumio user plan is not superstar Apr 26 20:00:47 volumio volumio[1239]: info: Removing audio output: Apr 26 20:00:47 volumio volumio[1239]: info: MYVOLUMIO: Adding device Apr 26 20:00:47 volumio volumio[1239]: info: MYVOLUMIO: Evaluating Server Apr 26 20:00:47 volumio volumio[1239]: info: Remote config written successfully Apr 26 20:00:47 volumio volumio[1239]: info: Starting Tunnel 1 Apr 26 20:00:47 volumio volumio[1239]: info: Starting Tunnel Connection Checker Apr 26 20:00:48 volumio volumio[1239]: info: MYVolumio Device enabled Apr 26 20:00:48 volumio volumio[1239]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 26 20:00:48 volumio volumio[1239]: info: MyVolumio status changed Apr 26 20:00:48 volumio volumio[1239]: info: Streaming services startup Apr 26 20:00:48 volumio volumio[1239]: info: Starting Streaming Daemon Apr 26 20:00:48 volumio sudo[11137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 20:00:48 volumio sudo[11137]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 20:00:47 volumio ntpd[11018]: receive: Unexpected origin timestamp 0xebb79dd0.2c4165be does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xebb79dcf.4a2e4d1d Apr 26 20:00:47 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 26 20:00:47 volumio volumio[1239]: error: [MyVolumio PluginManager] Cache data is invalid! Apr 26 20:00:47 volumio volumio[1239]: info: Setting Geolocation for MyVolumio to eu5 Apr 26 20:00:47 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 20:00:47 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 20:00:47 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 20:00:47 volumio systemd[1]: Starting Daily apt download activities... Apr 26 20:00:47 volumio sudo[11137]: pam_unix(sudo:session): session closed for user root Apr 26 20:00:47 volumio volumio[1239]: error: Cannot start Volumio Streaming Daemon Apr 26 20:00:47 volumio volumio[1239]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 20:00:47 volumio volumio[1239]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 20:00:47 volumio volumio[1239]: error: Failed to ping endpoint eu7.myvolumio.org : unknown error Apr 26 20:00:47 volumio volumio[1239]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 20:00:47 volumio volumio[1239]: Error: Unable to resolve or reject the same promise twice Apr 26 20:00:47 volumio volumio[1239]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 26 20:00:47 volumio volumio[1239]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Apr 26 20:00:47 volumio volumio[1239]: at Socket.emit (events.js:412:35) Apr 26 20:00:47 volumio volumio[1239]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 26 20:00:47 volumio volumio[1239]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 26 20:00:47 volumio volumio[1239]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 20:00:47 volumio sudo[11181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 19:59 Apr 26 20:00:47 volumio sudo[11181]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"