-- Logs begin at Thu 2025-05-01 11:49:20 CEST, end at Thu 2025-05-01 11:52:09 CEST. -- May 01 11:51:10 volumio ntpd[1096]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 01 11:51:30 volumio ntpd[1096]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 01 11:51:43 volumio volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 11:51:43 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 11:51:44 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 01 11:51:44 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 01 11:51:44 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 11:51:44 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[1992]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 11:51:44 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 11:51:44 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 11:51:44 volumio sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[2013]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 11:51:44 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:44 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root May 01 11:51:44 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 01 11:51:44 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 01 11:51:44 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 11:51:44 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:45 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root May 01 11:51:46 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 01 11:51:46 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 01 11:51:46 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 11:51:46 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:47 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root May 01 11:51:49 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 01 11:51:49 volumio volumio[879]: info: Saving new wireless network May 01 11:51:49 volumio sudo[2025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 01 11:51:49 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:49 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root May 01 11:51:49 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 01 11:51:49 volumio volumio[879]: info: Discovery: Restarting Advertising due to device name change May 01 11:51:49 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 01 11:51:49 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:49 volumio systemd[1]: Starting Wireless Services... May 01 11:51:49 volumio wireless.js[2030]: Cleaning previous... May 01 11:51:50 volumio sudo[2044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 01 11:51:50 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root May 01 11:51:50 volumio sudo[2046]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 01 11:51:50 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root May 01 11:51:50 volumio wireless.js[2030]: Stopped aP May 01 11:51:50 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 01 11:51:50 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root May 01 11:51:50 volumio sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 01 11:51:50 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root May 01 11:51:50 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 01 11:51:50 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root May 01 11:51:50 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 01 11:51:50 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:50 volumio ntpd[1096]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 01 11:51:50 volumio volumio[879]: info: Discovery: A device disappeared from network May 01 11:51:50 volumio volumio[879]: info: Discovery: Device volumio disappeared from network May 01 11:51:51 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root May 01 11:51:51 volumio wireless.js[2030]: Start wireless flow May 01 11:51:51 volumio wireless.js[2030]: Stopped hotspot (if there).. May 01 11:51:51 volumio sudo[2069]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 01 11:51:51 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:51 volumio sudo[2069]: pam_unix(sudo:session): session closed for user root May 01 11:51:51 volumio sudo[2071]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 01 11:51:51 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:51 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root May 01 11:51:51 volumio wireless.js[2030]: DHCP IP May 01 11:51:51 volumio wireless.js[2030]: Start ap May 01 11:51:51 volumio wpa_supplicant[2073]: Successfully initialized wpa_supplicant May 01 11:51:51 volumio sudo[2074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 01 11:51:51 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:51 volumio dhcpcd[2075]: sending commands to master dhcpcd process May 01 11:51:51 volumio dhcpcd[767]: control command: /sbin/dhcpcd May 01 11:51:51 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root May 01 11:51:52 volumio wireless.js[2030]: trying... May 01 11:51:52 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:52 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:52 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: SME: Trying to authenticate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) May 01 11:51:52 volumio kernel: wlan0: authenticate with 10:3c:59:85:0c:b3 May 01 11:51:52 volumio kernel: wlan0: 80 MHz not supported, disabling VHT May 01 11:51:52 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 1/3) May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: Trying to associate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) May 01 11:51:52 volumio kernel: wlan0: authenticated May 01 11:51:52 volumio kernel: wlan0: associate with 10:3c:59:85:0c:b3 (try 1/3) May 01 11:51:52 volumio kernel: wlan0: RX AssocResp from 10:3c:59:85:0c:b3 (capab=0x1431 status=0 aid=4) May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: Associated with 10:3c:59:85:0c:b3 May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 01 11:51:52 volumio kernel: usb 2-1.1: rtl8xxxu_bss_info_changed: HT supported May 01 11:51:52 volumio kernel: wlan0: associated May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 01 11:51:52 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 10:3c:59:85:0c:b3 May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: WPA: Key negotiation completed with 10:3c:59:85:0c:b3 [PTK=CCMP GTK=CCMP] May 01 11:51:52 volumio wpa_supplicant[2076]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:3c:59:85:0c:b3 completed [id=0 id_str=] May 01 11:51:52 volumio dhcpcd[767]: wlan0: carrier acquired May 01 11:51:52 volumio dhcpcd[767]: wlan0: IAID 43:b1:1a:48 May 01 11:51:52 volumio dhcpcd[767]: wlan0: soliciting an IPv6 router May 01 11:51:52 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 01 11:51:52 volumio volumio[879]: info: CorePlayQueue::getTrack 0 May 01 11:51:53 volumio dhcpcd[767]: wlan0: rebinding lease of 192.168.0.123 May 01 11:51:53 volumio dhcpcd[767]: wlan0: probing address 192.168.0.123/24 May 01 11:51:53 volumio wireless.js[2030]: trying... May 01 11:51:53 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:53 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:53 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root May 01 11:51:54 volumio wireless.js[2030]: trying... May 01 11:51:54 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:54 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:54 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root May 01 11:51:54 volumio volumio[879]: info: Discovery: Started advertising with name: Volumio May 01 11:51:55 volumio wireless.js[2030]: trying... May 01 11:51:55 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:55 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:55 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root May 01 11:51:56 volumio wireless.js[2030]: trying... May 01 11:51:56 volumio sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:56 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:56 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root May 01 11:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue May 01 11:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue May 01 11:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue May 01 11:51:57 volumio wireless.js[2030]: trying... May 01 11:51:57 volumio sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:57 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:57 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root May 01 11:51:58 volumio wireless.js[2030]: trying... May 01 11:51:58 volumio sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:58 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:58 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root May 01 11:51:58 volumio dhcpcd[767]: wlan0: leased 192.168.0.123 for 86400 seconds May 01 11:51:58 volumio avahi-daemon[737]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. May 01 11:51:58 volumio dhcpcd[767]: wlan0: adding route to 192.168.0.0/24 May 01 11:51:58 volumio dhcpcd[767]: wlan0: adding default route via 192.168.0.1 May 01 11:51:58 volumio avahi-daemon[737]: New relevant interface wlan0.IPv4 for mDNS. May 01 11:51:58 volumio dhcpcd[767]: eth0: deleting default route May 01 11:51:58 volumio avahi-daemon[737]: Registering new address record for 192.168.0.123 on wlan0.IPv4. May 01 11:51:58 volumio dhcpcd[1774]: eth0: deleted default route May 01 11:51:58 volumio ntpd[1096]: ntpd exiting on signal 15 (Terminated) May 01 11:51:58 volumio systemd[1]: Stopping Network Time Service... May 01 11:51:58 volumio systemd[1]: ntp.service: Succeeded. May 01 11:51:58 volumio systemd[1]: Stopped Network Time Service. May 01 11:51:58 volumio systemd[1]: Starting Network Time Service... May 01 11:51:58 volumio ntpd[2148]: ntpd 4.2.8p12@1.3728-o (1): Starting May 01 11:51:58 volumio ntpd[2148]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 01 11:51:58 volumio systemd[1]: Started Network Time Service. May 01 11:51:58 volumio ntpd[2154]: proto: precision = 0.147 usec (-23) May 01 11:51:58 volumio ntpd[2154]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 01 11:51:58 volumio ntpd[2154]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 01 11:51:58 volumio ntpd[2154]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 856 days ago May 01 11:51:58 volumio ntpd[2154]: Listen and drop on 0 v6wildcard [::]:123 May 01 11:51:58 volumio ntpd[2154]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 01 11:51:58 volumio ntpd[2154]: Listen normally on 2 lo 127.0.0.1:123 May 01 11:51:58 volumio ntpd[2154]: Listen normally on 3 eth0 169.254.93.118:123 May 01 11:51:58 volumio ntpd[2154]: Listen normally on 4 wlan0 192.168.0.123:123 May 01 11:51:58 volumio ntpd[2154]: Listening on routing socket on fd #21 for interface updates May 01 11:51:58 volumio ntpd[2154]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 01 11:51:58 volumio ntpd[2154]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 01 11:51:59 volumio wireless.js[2030]: trying... May 01 11:51:59 volumio sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 01 11:51:59 volumio sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:59 volumio sudo[2159]: pam_unix(sudo:session): session closed for user root May 01 11:51:59 volumio wireless.js[2030]: Connected to: ----5GPowerWiFi-850CB3 May 01 11:51:59 volumio wireless.js[2030]: ---- May 01 11:51:59 volumio sudo[2162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 11:51:59 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:51:59 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root May 01 11:51:59 volumio wireless.js[2030]: ... joined AP, wlan0 IPv4 is 192.168.0.123, ipV6 is undefined May 01 11:51:59 volumio wireless.js[2030]: It's done! AP May 01 11:51:59 volumio iwconfig[2166]: Error for wireless request "Set Power Management" (8B2C) : May 01 11:51:59 volumio iwconfig[2166]: SET failed on device wlan0 ; Operation not supported. May 01 11:51:59 volumio systemd[1]: Started Wireless Services. May 01 11:51:59 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root May 01 11:51:59 volumio ntpd[2154]: Soliciting pool server 95.110.254.234 May 01 11:52:00 volumio ntpd[2154]: Soliciting pool server 162.159.200.123 May 01 11:52:00 volumio ntpd[2154]: Soliciting pool server 151.22.209.90 May 01 11:52:01 volumio ntpd[2154]: Soliciting pool server 162.159.200.1 May 01 11:52:01 volumio ntpd[2154]: Soliciting pool server 81.56.230.156 May 01 11:52:01 volumio ntpd[2154]: Soliciting pool server 89.46.74.148 May 01 11:52:02 volumio volumio[879]: info: Discovery: adding 793c398d-970c-4969-89f7-2a99c140c182 May 01 11:52:02 volumio volumio[879]: info: Discovery: Found device Volumio May 01 11:52:02 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 01 11:52:02 volumio volumio[879]: info: CorePlayQueue::getTrack 0 May 01 11:52:02 volumio volumio[879]: info: Discovery: this is already registered, 793c398d-970c-4969-89f7-2a99c140c182 May 01 11:52:02 volumio volumio[879]: info: Discovery: Found device Volumio May 01 11:52:02 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 01 11:52:02 volumio volumio[879]: info: CorePlayQueue::getTrack 0 May 01 11:52:02 volumio ntpd[2154]: Soliciting pool server 195.32.70.195 May 01 11:52:02 volumio ntpd[2154]: Soliciting pool server 93.94.88.51 May 01 11:52:02 volumio ntpd[2154]: Soliciting pool server 185.157.229.254 May 01 11:52:02 volumio ntpd[2154]: Soliciting pool server 37.247.53.178 May 01 11:52:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 11:52:02 volumio volumio[879]: info: CURURI: music-library May 01 11:52:03 volumio volumio[879]: info: Preload queue cleared May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 01 11:52:03 volumio volumio[879]: info: Adding plugin bluetooth to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: Adding plugin multiroom to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: Adding plugin metavolumio to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: Adding plugin cd_controller to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: Adding plugin smart_inputs to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: Adding plugin tidalconnect to MyMusic Plugins May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 01 11:52:03 volumio ntpd[2154]: Soliciting pool server 93.94.88.50 May 01 11:52:03 volumio ntpd[2154]: Soliciting pool server 212.6.50.243 May 01 11:52:03 volumio ntpd[2154]: Soliciting pool server 85.199.214.99 May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 01 11:52:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 11:52:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 11:52:03 volumio volumio[879]: info: Starting MyVolumio Remote Streaming Endpoints May 01 11:52:03 volumio volumio[879]: info: MyVolumio login type: Token May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 01 11:52:03 volumio volumio[879]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 01 11:52:04 volumio volumio[879]: info: Starting Streaming Service Transparent Proxy May 01 11:52:04 volumio volumio[879]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 01 11:52:04 volumio volumio[879]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 01 11:52:04 volumio volumio[879]: info: Streaming services startup May 01 11:52:04 volumio volumio[879]: info: Starting Streaming Daemon May 01 11:52:04 volumio sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 11:52:04 volumio sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:04 volumio sudo[2182]: pam_unix(sudo:session): session closed for user root May 01 11:52:04 volumio volumio[879]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 01 11:52:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 01 11:52:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 01 11:52:04 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 11:52:04 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:04 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root May 01 11:52:04 volumio volumio[879]: error: Cannot start Volumio Streaming Daemon May 01 11:52:04 volumio volumio[879]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 01 11:52:04 volumio volumio[879]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 01 11:52:04 volumio sudo[2192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 11:52:04 volumio sudo[2192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:04 volumio sudo[2192]: pam_unix(sudo:session): session closed for user root May 01 11:52:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 11:52:04 volumio volumio[879]: info: CURURI: music-library/USB May 01 11:52:04 volumio volumio[879]: info: Preload queue cleared May 01 11:52:05 volumio volumio[879]: STREAMING PROXY: Starting server on port 3245 May 01 11:52:05 volumio volumio[879]: Node JS runtime: 14 May 01 11:52:05 volumio volumio[879]: error: MyVolumio Custom Token format not valid, refreshing it May 01 11:52:05 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 11:52:05 volumio volumio[879]: info: CURURI: music-library/USB/TOSHIBA_EXT May 01 11:52:05 volumio volumio[879]: info: Preload queue cleared May 01 11:52:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 11:52:06 volumio volumio[879]: info: Received Get System Info May 01 11:52:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 11:52:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 11:52:06 volumio volumio[879]: info: Discovery: Getting this device information May 01 11:52:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 01 11:52:06 volumio volumio[879]: info: CorePlayQueue::getTrack 0 May 01 11:52:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 11:52:06 volumio volumio[879]: info: MyVolumio login type: Token May 01 11:52:07 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 11:52:07 volumio volumio[879]: info: MyVolumio login type: Token May 01 11:52:08 volumio volumio[879]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 01 11:52:09 volumio volumio[879]: info: MyVolumio token set successfully May 01 11:52:09 volumio volumio[879]: info: MYVOLUMIO: Adding device May 01 11:52:09 volumio volumio[879]: info: MYVOLUMIO: Evaluating Server May 01 11:52:09 volumio volumio[879]: info: MyVolumio Plan changed: premium May 01 11:52:09 volumio volumio[879]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 01 11:52:09 volumio volumio[879]: info: Removing browser output: myVolumio user plan is not superstar May 01 11:52:09 volumio volumio[879]: info: Removing audio output: May 01 11:52:09 volumio volumio[879]: info: MYVOLUMIO: Adding device May 01 11:52:09 volumio volumio[879]: info: MYVOLUMIO: Evaluating Server May 01 11:52:09 volumio volumio[879]: info: Remote config written successfully May 01 11:52:09 volumio volumio[879]: info: Starting Tunnel 1 May 01 11:52:09 volumio volumio[879]: info: Starting Tunnel Connection Checker May 01 11:52:09 volumio ntpd[2154]: receive: Unexpected origin timestamp 0xebbdc2c9.c7f7f4b5 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xebbdc2c9.631a065f May 01 11:52:09 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 01 11:52:09 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 01 11:52:09 volumio volumio[879]: info: Refreshing Cached IP Addresses May 01 11:52:09 volumio systemd[1]: Starting Daily apt download activities... May 01 11:52:09 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 01 11:52:09 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:09 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root May 01 11:52:09 volumio sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 11:52:09 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:09 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root May 01 11:52:09 volumio volumio[879]: info: MYVolumio Device enabled May 01 11:52:09 volumio volumio[879]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 01 11:52:09 volumio volumio[879]: info: MyVolumio status changed May 01 11:52:09 volumio volumio[879]: info: Streaming services startup May 01 11:52:09 volumio volumio[879]: info: Starting Streaming Daemon May 01 11:52:09 volumio sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 11:52:09 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:09 volumio sudo[2242]: pam_unix(sudo:session): session closed for user root May 01 11:52:09 volumio volumio[879]: error: Failed to ping endpoint au1.myvolumio.org : unknown error May 01 11:52:09 volumio volumio[879]: info: Setting Geolocation for MyVolumio to eu2 May 01 11:52:09 volumio sudo[2250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 11:52:09 volumio sudo[2250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:52:09 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 11:52:09 volumio sudo[2250]: pam_unix(sudo:session): session closed for user root May 01 11:52:09 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 11:52:09 volumio volumio[879]: Error: Unable to resolve or reject the same promise twice May 01 11:52:09 volumio volumio[879]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 01 11:52:09 volumio volumio[879]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 01 11:52:09 volumio volumio[879]: at Socket.emit (events.js:412:35) May 01 11:52:09 volumio volumio[879]: at endReadableNT (internal/streams/readable.js:1333:12) May 01 11:52:09 volumio volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 01 11:52:09 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 11:52:09 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 11:51 May 01 11:52:09 volumio sudo[2288]: 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"