-- Logs begin at Sat 2025-02-22 14:41:55 HKT, end at Sat 2025-02-22 14:47:53 HKT. -- Feb 22 14:46:02 volumio volumio-time-update[832]: volumio-time-update-util: Date not found in response Feb 22 14:46:02 volumio volumio-time-update[832]: volumio-time-update-util: Retrying in 5 seconds... Feb 22 14:46:02 volumio ntpd[1012]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 22 14:46:07 volumio volumio-time-update[832]: volumio-time-update-util: Fetching time from Volumio... Feb 22 14:46:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 22 14:46:12 volumio volumio[1154]: info: Saving new wireless network Feb 22 14:46:12 volumio sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Feb 22 14:46:12 volumio sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio sudo[3379]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 22 14:46:12 volumio volumio[1154]: info: Discovery: Restarting Advertising due to device name change Feb 22 14:46:12 volumio sudo[3382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 22 14:46:12 volumio sudo[3382]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio systemd[1]: Starting Wireless Services... Feb 22 14:46:12 volumio wireless.js[3384]: Cleaning previous... Feb 22 14:46:12 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Feb 22 14:46:12 volumio dnsmasq[1881]: exiting on receipt of SIGTERM Feb 22 14:46:12 volumio systemd[1]: dnsmasq.service: Succeeded. Feb 22 14:46:12 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Feb 22 14:46:12 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Feb 22 14:46:12 volumio dhcpcd[963]: wlan0: carrier lost Feb 22 14:46:12 volumio dhcpcd[963]: wlan0: deleting route to 169.254.0.0/16 Feb 22 14:46:12 volumio dhcpcd[963]: wlan0: deleting default route Feb 22 14:46:12 volumio avahi-daemon[840]: Withdrawing address record for 169.254.38.92 on wlan0. Feb 22 14:46:12 volumio systemd[1]: hostapd.service: Succeeded. Feb 22 14:46:12 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Feb 22 14:46:12 volumio sudo[3437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 14:46:12 volumio sudo[3437]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio avahi-daemon[840]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 22 14:46:12 volumio avahi-daemon[840]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 22 14:46:12 volumio avahi-daemon[840]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 22 14:46:12 volumio sudo[3437]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio volumio[1154]: info: Discovery: A device disappeared from network Feb 22 14:46:12 volumio sudo[3439]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 14:46:12 volumio sudo[3439]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio sudo[3439]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio wireless.js[3384]: Stopped aP Feb 22 14:46:12 volumio sudo[3446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:46:12 volumio sudo[3446]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio sudo[3446]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 22 14:46:12 volumio sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio sudo[3449]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio sudo[3456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:46:12 volumio sudo[3456]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:12 volumio sudo[3456]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:12 volumio sudo[3458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 22 14:46:12 volumio sudo[3458]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio sudo[3458]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:13 volumio wireless.js[3384]: SETTING APPROPRIATE REG DOMAIN: CN Feb 22 14:46:13 volumio sudo[3463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:46:13 volumio sudo[3463]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio sudo[3463]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:13 volumio sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Feb 22 14:46:13 volumio sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio sudo[3465]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:13 volumio wireless.js[3384]: SUCCESSFULLY SET NEW REGDOMAIN: CN Feb 22 14:46:13 volumio wireless.js[3384]: Start wireless flow Feb 22 14:46:13 volumio wireless.js[3384]: Stopped hotspot (if there).. Feb 22 14:46:13 volumio sudo[3470]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 14:46:13 volumio sudo[3470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio sudo[3470]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:13 volumio sudo[3472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 14:46:13 volumio sudo[3472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio sudo[3472]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:13 volumio wireless.js[3384]: DHCP IP Feb 22 14:46:13 volumio wireless.js[3384]: Start ap Feb 22 14:46:13 volumio wpa_supplicant[3474]: Successfully initialized wpa_supplicant Feb 22 14:46:13 volumio sudo[3475]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 22 14:46:13 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:13 volumio dhcpcd[3476]: sending commands to master dhcpcd process Feb 22 14:46:13 volumio dhcpcd[963]: control command: /sbin/dhcpcd Feb 22 14:46:13 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:14 volumio wireless.js[3384]: trying... Feb 22 14:46:14 volumio sudo[3480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:14 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:14 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: SME: Trying to authenticate with 3c:cd:57:fb:e3:5e (SSID='Faster' freq=2432 MHz) Feb 22 14:46:14 volumio kernel: wlan0: authenticate with 3c:cd:57:fb:e3:5e Feb 22 14:46:14 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Feb 22 14:46:14 volumio kernel: wlan0: send auth to 3c:cd:57:fb:e3:5e (try 1/3) Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: Trying to associate with 3c:cd:57:fb:e3:5e (SSID='Faster' freq=2432 MHz) Feb 22 14:46:14 volumio kernel: wlan0: authenticated Feb 22 14:46:14 volumio kernel: wlan0: associate with 3c:cd:57:fb:e3:5e (try 1/3) Feb 22 14:46:14 volumio kernel: wlan0: RX AssocResp from 3c:cd:57:fb:e3:5e (capab=0x1431 status=0 aid=4) Feb 22 14:46:14 volumio kernel: wlan0: associated Feb 22 14:46:14 volumio kernel: ath: EEPROM regdomain: 0x809c Feb 22 14:46:14 volumio kernel: ath: EEPROM indicates we should expect a country code Feb 22 14:46:14 volumio kernel: ath: doing EEPROM country->regdmn map search Feb 22 14:46:14 volumio kernel: ath: country maps to regdmn code: 0x52 Feb 22 14:46:14 volumio kernel: ath: Country alpha2 being used: CN Feb 22 14:46:14 volumio kernel: ath: Regpair used: 0x52 Feb 22 14:46:14 volumio kernel: ath: regdomain 0x809c dynamically updated by country element Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: Associated with 3c:cd:57:fb:e3:5e Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: WPA: Key negotiation completed with 3c:cd:57:fb:e3:5e [PTK=CCMP GTK=CCMP] Feb 22 14:46:14 volumio wpa_supplicant[3478]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:cd:57:fb:e3:5e completed [id=0 id_str=] Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: carrier acquired Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: confile file changed, expiring leases Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: IAID 87:4d:00:8b Feb 22 14:46:14 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 3c:cd:57:fb:e3:5e Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: soliciting an IPv6 router Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: soliciting a DHCP lease Feb 22 14:46:14 volumio dhcpcd[963]: wlan0: offered 192.168.28.129 from 192.168.28.1 Feb 22 14:46:15 volumio dhcpcd[963]: wlan0: probing address 192.168.28.129/24 Feb 22 14:46:15 volumio wireless.js[3384]: trying... Feb 22 14:46:15 volumio sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:15 volumio sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:15 volumio sudo[3484]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:16 volumio ntpd[1012]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=256 secs Feb 22 14:46:16 volumio ntpd[1012]: Deleting interface #4 wlan0, 169.254.38.92#123, interface stats: received=0, sent=0, dropped=0, active_time=237 secs Feb 22 14:46:16 volumio wireless.js[3384]: trying... Feb 22 14:46:16 volumio sudo[3487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:16 volumio sudo[3487]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:16 volumio sudo[3487]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:17 volumio volumio[1154]: info: Discovery: Started advertising with name: Volumio Feb 22 14:46:17 volumio volumio-time-update[832]: volumio-time-update-util: Date not found in response Feb 22 14:46:17 volumio volumio-time-update[832]: volumio-time-update-util: Retrying in 5 seconds... Feb 22 14:46:17 volumio ntpd[1012]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 22 14:46:17 volumio ntpd[1012]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 22 14:46:17 volumio ntpd[1012]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 22 14:46:17 volumio ntpd[1012]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 22 14:46:17 volumio wireless.js[3384]: trying... Feb 22 14:46:17 volumio sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:17 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:17 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:18 volumio volumio[1154]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Feb 22 14:46:18 volumio wireless.js[3384]: trying... Feb 22 14:46:18 volumio sudo[3498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:18 volumio sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:18 volumio sudo[3498]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:19 volumio wireless.js[3384]: trying... Feb 22 14:46:19 volumio sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:19 volumio sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:19 volumio sudo[3501]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio dhcpcd[963]: wlan0: leased 192.168.28.129 for 43200 seconds Feb 22 14:46:20 volumio avahi-daemon[840]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.28.129. Feb 22 14:46:20 volumio avahi-daemon[840]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 14:46:20 volumio avahi-daemon[840]: Registering new address record for 192.168.28.129 on wlan0.IPv4. Feb 22 14:46:20 volumio dhcpcd[963]: wlan0: adding route to 192.168.28.0/24 Feb 22 14:46:20 volumio dhcpcd[963]: wlan0: adding default route via 192.168.28.1 Feb 22 14:46:20 volumio ntpd[1012]: ntpd exiting on signal 15 (Terminated) Feb 22 14:46:20 volumio systemd[1]: Stopping Network Time Service... Feb 22 14:46:20 volumio systemd[1]: ntp.service: Succeeded. Feb 22 14:46:20 volumio systemd[1]: Stopped Network Time Service. Feb 22 14:46:20 volumio systemd[1]: Starting Network Time Service... Feb 22 14:46:20 volumio ntpd[3538]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 22 14:46:20 volumio ntpd[3538]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 22 14:46:20 volumio systemd[1]: Started Network Time Service. Feb 22 14:46:20 volumio ntpd[3544]: proto: precision = 0.081 usec (-23) Feb 22 14:46:20 volumio ntpd[3544]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 22 14:46:20 volumio ntpd[3544]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 22 14:46:20 volumio ntpd[3544]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 788 days ago Feb 22 14:46:20 volumio ntpd[3544]: Listen and drop on 0 v6wildcard [::]:123 Feb 22 14:46:20 volumio ntpd[3544]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 22 14:46:20 volumio ntpd[3544]: Listen normally on 2 lo 127.0.0.1:123 Feb 22 14:46:20 volumio ntpd[3544]: Listen normally on 3 wlan0 192.168.28.129:123 Feb 22 14:46:20 volumio ntpd[3544]: Listening on routing socket on fd #20 for interface updates Feb 22 14:46:20 volumio ntpd[3544]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 22 14:46:20 volumio ntpd[3544]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 22 14:46:20 volumio volumio[1154]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Feb 22 14:46:20 volumio wireless.js[3384]: trying... Feb 22 14:46:20 volumio sudo[3548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:46:20 volumio sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:20 volumio sudo[3548]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio wireless.js[3384]: Connected to: ----Faster Feb 22 14:46:20 volumio wireless.js[3384]: ---- Feb 22 14:46:20 volumio sudo[3551]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:46:20 volumio sudo[3551]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:20 volumio sudo[3551]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:20 volumio wireless.js[3384]: ... joined AP, wlan0 IPv4 is 192.168.28.129, ipV6 is undefined Feb 22 14:46:20 volumio wireless.js[3384]: It's done! AP Feb 22 14:46:20 volumio iwconfig[3555]: Error for wireless request "Set Power Management" (8B2C) : Feb 22 14:46:20 volumio iwconfig[3555]: SET failed on device wlan0 ; Operation not supported. Feb 22 14:46:20 volumio systemd[1]: Started Wireless Services. Feb 22 14:46:20 volumio sudo[3382]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:21 volumio volumio[1154]: info: Discovery: adding 7fc7f21c-f028-4e9c-bb9d-38e79f189b24 Feb 22 14:46:21 volumio volumio[1154]: info: Discovery: Found device Volumio Feb 22 14:46:21 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:21 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin bluetooth to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin multiroom to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin metavolumio to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin cd_controller to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 22 14:46:21 volumio ntpd[3544]: Soliciting pool server 119.28.206.193 Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 22 14:46:21 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:46:21 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 14:46:21 volumio volumio[1154]: info: Starting MyVolumio Remote Streaming Endpoints Feb 22 14:46:21 volumio volumio[1154]: info: MyVolumio not started Feb 22 14:46:21 volumio volumio[1154]: info: Initializing device activation check Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 22 14:46:21 volumio volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 22 14:46:22 volumio volumio[1154]: info: Starting Streaming Service Transparent Proxy Feb 22 14:46:22 volumio volumio[1154]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 22 14:46:22 volumio volumio[1154]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 22 14:46:22 volumio volumio[1154]: info: Streaming services startup Feb 22 14:46:22 volumio volumio[1154]: info: Starting Streaming Daemon Feb 22 14:46:22 volumio sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 14:46:22 volumio sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:22 volumio sudo[3576]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:22 volumio volumio[1154]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 22 14:46:22 volumio volumio[1154]: error: Cannot start Volumio Streaming Daemon Feb 22 14:46:22 volumio volumio[1154]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 14:46:22 volumio volumio[1154]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 14:46:22 volumio volumio-time-update[832]: volumio-time-update-util: Fetching time from Volumio... Feb 22 14:46:22 volumio volumio[1154]: info: Volumio Network Manager: Network status updated: 2 Feb 22 14:46:22 volumio volumio[1154]: STREAMING PROXY: Starting server on port 3245 Feb 22 14:46:22 volumio volumio[1154]: Node JS runtime: 14 Feb 22 14:46:22 volumio ntpd[3544]: Soliciting pool server 202.112.29.82 Feb 22 14:46:22 volumio ntpd[3544]: Soliciting pool server 119.28.183.184 Feb 22 14:46:22 volumio volumio-time-update[832]: volumio-time-update-util: Setting system time to: 2025-02-22 14:46:22 Feb 22 14:46:22 volumio sudo[3602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-22 14:46:22 Feb 22 14:46:22 volumio sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:22 volumio dbus-daemon[837]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=3603 comm="timedatectl set-time 2025-02-22 14:46:22 ") Feb 22 14:46:22 volumio systemd[1]: Starting Time & Date Service... Feb 22 14:46:22 volumio dbus-daemon[837]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 22 14:46:22 volumio systemd[1]: Started Time & Date Service. Feb 22 14:46:22 volumio systemd-timedated[3604]: Changed local time to Sat Feb 22 14:46:22 2025 Feb 22 14:46:22 volumio sudo[3602]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:22 volumio volumio-time-update[832]: volumio-time-update-util: System time updated successfully. Feb 22 14:46:22 volumio systemd[1]: Started Volumio Time Update Utility. Feb 22 14:46:22 volumio systemd[1]: Reached target Multi-User System. Feb 22 14:46:22 volumio systemd[1]: Reached target Graphical Interface. Feb 22 14:46:22 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 22 14:46:22 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 22 14:46:22 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 22 14:46:22 volumio systemd[1]: Startup finished in 2.446s (firmware) + 1.785s (loader) + 8.993s (kernel) + 4min 27.835s (userspace) = 4min 41.061s. Feb 22 14:46:22 volumio ntpd[3544]: Soliciting pool server 193.182.111.141 Feb 22 14:46:22 volumio ntpd[3544]: Soliciting pool server 111.230.189.174 Feb 22 14:46:22 volumio ntpd[3544]: Soliciting pool server 202.118.1.130 Feb 22 14:46:23 volumio ntpd[3544]: Soliciting pool server 116.203.151.74 Feb 22 14:46:23 volumio ntpd[3544]: Soliciting pool server 202.118.1.81 Feb 22 14:46:23 volumio ntpd[3544]: Soliciting pool server 84.16.73.33 Feb 22 14:46:24 volumio ntpd[3544]: Soliciting pool server 113.207.49.85 Feb 22 14:46:24 volumio ntpd[3544]: Soliciting pool server 117.80.112.205 Feb 22 14:46:24 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 22 14:46:24 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 22 14:46:24 volumio sudo[3609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:46:24 volumio sudo[3609]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:24 volumio sudo[3609]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:24 volumio sudo[3612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:46:24 volumio sudo[3612]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:24 volumio sudo[3612]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:25 volumio ntpd[3544]: Soliciting pool server 117.80.231.60 Feb 22 14:46:25 volumio ntpd[3544]: Soliciting pool server 193.182.111.142 Feb 22 14:46:26 volumio ntpd[3544]: Soliciting pool server 162.159.200.1 Feb 22 14:46:26 volumio ntpd[3544]: Soliciting pool server 2001:da8:9000::130 Feb 22 14:46:29 volumio ntpd[3544]: receive: Unexpected origin timestamp 0xeb63f144.8991bef7 does not match aorg 0000000000.00000000 from server@111.230.189.174 xmt 0xeb63f145.b93e3494 Feb 22 14:46:29 volumio ntpd[3544]: receive: Unexpected origin timestamp 0xeb63f144.8992c571 does not match aorg 0000000000.00000000 from server@119.28.206.193 xmt 0xeb63f145.b9d08cf0 Feb 22 14:46:29 volumio ntpd[3544]: receive: Unexpected origin timestamp 0xeb63f144.89913abb does not match aorg 0000000000.00000000 from server@202.118.1.81 xmt 0xeb63f145.bf96126c Feb 22 14:46:29 volumio ntpd[3544]: receive: Unexpected origin timestamp 0xeb63f144.89907c99 does not match aorg 0000000000.00000000 from server@84.16.73.33 xmt 0xeb63f145.d099cc10 Feb 22 14:46:29 volumio ntpd[3544]: receive: Unexpected origin timestamp 0xeb63f144.898f5993 does not match aorg 0000000000.00000000 from server@116.203.151.74 xmt 0xeb63f145.e04f2eda Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 22 14:46:31 volumio volumio[1154]: info: Refreshing Cached IP Addresses Feb 22 14:46:31 volumio sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 22 14:46:31 volumio sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3620]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:46:31 volumio sudo[3623]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3623]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:46:31 volumio sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 22 14:46:31 volumio sudo[3626]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio volumio[1154]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:31 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:46:31 volumio volumio[1154]: info: Received Get System Info Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:46:31 volumio volumio[1154]: info: Discovery: Getting this device information Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:31 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:31 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:31 volumio volumio[1154]: info: Listing playlists Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 22 14:46:31 volumio sudo[3632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 22 14:46:31 volumio sudo[3632]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3632]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:46:31 volumio sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3638]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:46:31 volumio sudo[3646]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3646]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:46:31 volumio sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3652]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:46:31 volumio sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3658]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:46:31 volumio sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:31 volumio sudo[3661]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 14:46:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 14:46:31 volumio sudo[3664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 14:46:31 volumio sudo[3664]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:46:33 volumio volumio[1154]: info: Received Get System Info Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:46:33 volumio volumio[1154]: info: Discovery: Getting this device information Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:33 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:33 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:46:34 volumio sudo[3664]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:37 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:46:37 volumio volumio[1154]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 22 14:46:37 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:37 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:46:41 volumio sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:46:41 volumio sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:41 volumio sudo[3684]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:41 volumio sudo[3687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:46:41 volumio sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:41 volumio sudo[3687]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:41 volumio sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 14:46:41 volumio sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:41 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 22 14:46:41 volumio sudo[3690]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:41 volumio volumio[1154]: info: Upmpdcli Daemon Started Feb 22 14:46:41 volumio volumio[3692]: writing RSA key Feb 22 14:46:41 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:46:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 14:46:49 volumio volumio[1154]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Feb 22 14:46:49 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Feb 22 14:46:51 volumio volumio[1154]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Feb 22 14:46:51 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Feb 22 14:46:51 volumio sudo[3741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Feb 22 14:46:51 volumio sudo[3741]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:51 volumio sudo[3741]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:51 volumio sudo[3743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Feb 22 14:46:51 volumio sudo[3743]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:51 volumio sudo[3743]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:51 volumio volumio[1154]: info: Permissions for /etc/network/interfaces set Feb 22 14:46:51 volumio volumio[1154]: info: Restarting Wired Network Feb 22 14:46:51 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 22 14:46:51 volumio volumio[1154]: info: Discovery: Restarting Advertising due to device name change Feb 22 14:46:51 volumio sudo[3746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Feb 22 14:46:51 volumio sudo[3746]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:51 volumio sudo[3746]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:51 volumio sudo[3748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Feb 22 14:46:51 volumio sudo[3748]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:51 volumio sudo[3748]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:51 volumio sudo[3750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Feb 22 14:46:51 volumio sudo[3750]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:46:51 volumio kernel: Generic FE-GE Realtek PHY r8169-0-800:00: attached PHY driver (mii_bus:phy_addr=r8169-0-800:00, irq=MAC) Feb 22 14:46:51 volumio sudo[3750]: pam_unix(sudo:session): session closed for user root Feb 22 14:46:51 volumio kernel: r8169 0000:08:00.0 eth0: Link is Down Feb 22 14:46:52 volumio volumio[1154]: info: Discovery: A device disappeared from network Feb 22 14:46:52 volumio volumio[1154]: info: Discovery: Device volumio disappeared from network Feb 22 14:46:53 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 22 14:46:56 volumio volumio[1154]: info: Discovery: Started advertising with name: Volumio Feb 22 14:46:57 volumio volumio[1154]: info: Discovery: adding 7fc7f21c-f028-4e9c-bb9d-38e79f189b24 Feb 22 14:46:57 volumio volumio[1154]: info: Discovery: Found device Volumio Feb 22 14:46:57 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:46:57 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:01 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 22 14:47:01 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 22 14:47:01 volumio volumio[1154]: info: Refreshing Cached IP Addresses Feb 22 14:47:01 volumio sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 22 14:47:01 volumio sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:01 volumio sudo[3821]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:01 volumio sudo[3824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:47:01 volumio sudo[3824]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:01 volumio sudo[3824]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:01 volumio sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:01 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 22 14:47:01 volumio sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:01 volumio sudo[3827]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio volumio[1154]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:02 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:47:02 volumio volumio[1154]: info: Received Get System Info Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:47:02 volumio volumio[1154]: info: Discovery: Getting this device information Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:02 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:02 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:02 volumio volumio[1154]: info: Listing playlists Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 22 14:47:02 volumio sudo[3846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 22 14:47:02 volumio sudo[3846]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3846]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio sudo[3851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:47:02 volumio sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3851]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio sudo[3859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:47:02 volumio sudo[3859]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3859]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:47:02 volumio sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3865]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:47:02 volumio sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 14:47:02 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 14:47:02 volumio sudo[3871]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:02 volumio sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 14:47:02 volumio sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:02 volumio sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:02 volumio sudo[3875]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:47:04 volumio volumio[1154]: info: Received Get System Info Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:47:04 volumio volumio[1154]: info: Discovery: Getting this device information Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:04 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:47:04 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:47:05 volumio sudo[3877]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:11 volumio nmbd[982]: [2025/02/22 14:47:11.303157, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 22 14:47:11 volumio nmbd[982]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Feb 22 14:47:11 volumio sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:47:11 volumio sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:11 volumio sudo[3885]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:11 volumio sudo[3888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:11 volumio sudo[3888]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:11 volumio sudo[3888]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:11 volumio sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 14:47:11 volumio sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:11 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 22 14:47:11 volumio sudo[3891]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:11 volumio volumio[1154]: info: Upmpdcli Daemon Started Feb 22 14:47:11 volumio volumio[3893]: writing RSA key Feb 22 14:47:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:47:17 volumio volumio[1154]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Feb 22 14:47:17 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Feb 22 14:47:17 volumio sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Feb 22 14:47:17 volumio sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio sudo[3938]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:17 volumio sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Feb 22 14:47:17 volumio sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio sudo[3940]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:17 volumio volumio[1154]: info: Permissions for /etc/network/interfaces set Feb 22 14:47:17 volumio volumio[1154]: info: Restarting Wireless Network Feb 22 14:47:17 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 22 14:47:17 volumio volumio[1154]: info: Discovery: Restarting Advertising due to device name change Feb 22 14:47:17 volumio sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 22 14:47:17 volumio sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio systemd[1]: Stopping Wireless Services... Feb 22 14:47:17 volumio dhcpcd[963]: wlan0: carrier lost Feb 22 14:47:17 volumio kernel: wlan0: deauthenticating from 3c:cd:57:fb:e3:5e by local choice (Reason: 3=DEAUTH_LEAVING) Feb 22 14:47:17 volumio avahi-daemon[840]: Withdrawing address record for 192.168.28.129 on wlan0. Feb 22 14:47:17 volumio avahi-daemon[840]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.28.129. Feb 22 14:47:17 volumio wpa_supplicant[3478]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:cd:57:fb:e3:5e reason=3 locally_generated=1 Feb 22 14:47:17 volumio avahi-daemon[840]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 22 14:47:17 volumio volumio[1154]: info: Discovery: A device disappeared from network Feb 22 14:47:17 volumio volumio[1154]: info: Discovery: Device volumio disappeared from network Feb 22 14:47:17 volumio dhcpcd[963]: wlan0: deleting route to 192.168.28.0/24 Feb 22 14:47:17 volumio dhcpcd[963]: wlan0: deleting default route via 192.168.28.1 Feb 22 14:47:17 volumio wpa_supplicant[3478]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 22 14:47:17 volumio wpa_supplicant[3478]: wlan0: CTRL-EVENT-TERMINATING Feb 22 14:47:17 volumio systemd[1]: wireless.service: Succeeded. Feb 22 14:47:17 volumio systemd[1]: Stopped Wireless Services. Feb 22 14:47:17 volumio systemd[1]: Starting Wireless Services... Feb 22 14:47:17 volumio wireless.js[3971]: Cleaning previous... Feb 22 14:47:17 volumio sudo[3986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 14:47:17 volumio sudo[3986]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio sudo[3986]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:17 volumio sudo[3988]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 14:47:17 volumio sudo[3988]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio sudo[3988]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:17 volumio wireless.js[3971]: Stopped aP Feb 22 14:47:17 volumio sudo[3995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:47:17 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:17 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:17 volumio sudo[3998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 22 14:47:17 volumio sudo[3998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:18 volumio sudo[3998]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:18 volumio sudo[4005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:47:18 volumio sudo[4005]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:18 volumio sudo[4005]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:18 volumio sudo[4007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 22 14:47:18 volumio sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:18 volumio sudo[4007]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:18 volumio wireless.js[3971]: SETTING APPROPRIATE REG DOMAIN: CN Feb 22 14:47:18 volumio sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 14:47:18 volumio sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:18 volumio sudo[4015]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:18 volumio sudo[4017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Feb 22 14:47:18 volumio sudo[4017]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:19 volumio sudo[4017]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:19 volumio wireless.js[3971]: SUCCESSFULLY SET NEW REGDOMAIN: CN Feb 22 14:47:19 volumio wireless.js[3971]: Start wireless flow Feb 22 14:47:19 volumio wireless.js[3971]: Stopped hotspot (if there).. Feb 22 14:47:19 volumio sudo[4022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 14:47:19 volumio sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:19 volumio sudo[4022]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:19 volumio sudo[4024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 14:47:19 volumio sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:19 volumio sudo[4024]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:19 volumio wireless.js[3971]: DHCP IP Feb 22 14:47:19 volumio wireless.js[3971]: Start ap Feb 22 14:47:19 volumio wpa_supplicant[4026]: Successfully initialized wpa_supplicant Feb 22 14:47:19 volumio sudo[4027]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 22 14:47:19 volumio sudo[4027]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:19 volumio dhcpcd[4029]: sending commands to master dhcpcd process Feb 22 14:47:19 volumio dhcpcd[963]: control command: /sbin/dhcpcd Feb 22 14:47:19 volumio sudo[4027]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:20 volumio wireless.js[3971]: trying... Feb 22 14:47:20 volumio sudo[4033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:20 volumio sudo[4033]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:20 volumio sudo[4033]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: SME: Trying to authenticate with 3c:cd:57:fb:e3:5e (SSID='Faster' freq=2432 MHz) Feb 22 14:47:20 volumio kernel: wlan0: authenticate with 3c:cd:57:fb:e3:5e Feb 22 14:47:20 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Feb 22 14:47:20 volumio kernel: wlan0: send auth to 3c:cd:57:fb:e3:5e (try 1/3) Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: Trying to associate with 3c:cd:57:fb:e3:5e (SSID='Faster' freq=2432 MHz) Feb 22 14:47:20 volumio kernel: wlan0: authenticated Feb 22 14:47:20 volumio kernel: wlan0: associate with 3c:cd:57:fb:e3:5e (try 1/3) Feb 22 14:47:20 volumio kernel: wlan0: RX AssocResp from 3c:cd:57:fb:e3:5e (capab=0x1431 status=0 aid=4) Feb 22 14:47:20 volumio kernel: wlan0: associated Feb 22 14:47:20 volumio kernel: ath: EEPROM regdomain: 0x809c Feb 22 14:47:20 volumio kernel: ath: EEPROM indicates we should expect a country code Feb 22 14:47:20 volumio kernel: ath: doing EEPROM country->regdmn map search Feb 22 14:47:20 volumio kernel: ath: country maps to regdmn code: 0x52 Feb 22 14:47:20 volumio kernel: ath: Country alpha2 being used: CN Feb 22 14:47:20 volumio kernel: ath: Regpair used: 0x52 Feb 22 14:47:20 volumio kernel: ath: regdomain 0x809c dynamically updated by country element Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: Associated with 3c:cd:57:fb:e3:5e Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 22 14:47:20 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 3c:cd:57:fb:e3:5e Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: WPA: Key negotiation completed with 3c:cd:57:fb:e3:5e [PTK=CCMP GTK=CCMP] Feb 22 14:47:20 volumio wpa_supplicant[4030]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:cd:57:fb:e3:5e completed [id=0 id_str=] Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: carrier acquired Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: confile file changed, expiring leases Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: IAID 87:4d:00:8b Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: rebinding lease of 192.168.28.129 Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: probing address 192.168.28.129/24 Feb 22 14:47:20 volumio dhcpcd[963]: wlan0: soliciting an IPv6 router Feb 22 14:47:21 volumio wireless.js[3971]: trying... Feb 22 14:47:21 volumio sudo[4037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:21 volumio sudo[4037]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:21 volumio sudo[4037]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:21 volumio ntpd[3544]: Deleting interface #3 wlan0, 192.168.28.129#123, interface stats: received=80, sent=85, dropped=0, active_time=61 secs Feb 22 14:47:21 volumio ntpd[3544]: 162.159.200.1 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 193.182.111.142 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 117.80.231.60 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 113.207.49.85 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 117.80.112.205 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 116.203.151.74 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 84.16.73.33 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 202.118.1.81 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 202.118.1.130 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 111.230.189.174 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 202.112.29.82 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 119.28.183.184 local addr 192.168.28.129 -> Feb 22 14:47:21 volumio ntpd[3544]: 119.28.206.193 local addr 192.168.28.129 -> Feb 22 14:47:22 volumio wireless.js[3971]: trying... Feb 22 14:47:22 volumio sudo[4040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:22 volumio sudo[4040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:22 volumio sudo[4040]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:22 volumio volumio[1154]: info: Discovery: Started advertising with name: Volumio Feb 22 14:47:22 volumio volumio[1154]: info: Volumio Network Manager: Network status updated: 0 Feb 22 14:47:23 volumio wireless.js[3971]: trying... Feb 22 14:47:23 volumio sudo[4058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:23 volumio sudo[4058]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:23 volumio sudo[4058]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:24 volumio wireless.js[3971]: trying... Feb 22 14:47:24 volumio sudo[4061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:24 volumio sudo[4061]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:24 volumio sudo[4061]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:25 volumio wireless.js[3971]: trying... Feb 22 14:47:25 volumio sudo[4064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:25 volumio sudo[4064]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:25 volumio sudo[4064]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:25 volumio dhcpcd[963]: wlan0: leased 192.168.28.129 for 43200 seconds Feb 22 14:47:25 volumio dhcpcd[963]: wlan0: adding route to 192.168.28.0/24 Feb 22 14:47:25 volumio dhcpcd[963]: wlan0: adding default route via 192.168.28.1 Feb 22 14:47:25 volumio avahi-daemon[840]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.28.129. Feb 22 14:47:25 volumio avahi-daemon[840]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 14:47:25 volumio avahi-daemon[840]: Registering new address record for 192.168.28.129 on wlan0.IPv4. Feb 22 14:47:26 volumio ntpd[3544]: ntpd exiting on signal 15 (Terminated) Feb 22 14:47:26 volumio systemd[1]: Stopping Network Time Service... Feb 22 14:47:26 volumio systemd[1]: ntp.service: Succeeded. Feb 22 14:47:26 volumio systemd[1]: Stopped Network Time Service. Feb 22 14:47:26 volumio systemd[1]: Starting Network Time Service... Feb 22 14:47:26 volumio ntpd[4098]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 22 14:47:26 volumio ntpd[4098]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 22 14:47:26 volumio systemd[1]: Started Network Time Service. Feb 22 14:47:26 volumio ntpd[4104]: proto: precision = 0.094 usec (-23) Feb 22 14:47:26 volumio ntpd[4104]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 22 14:47:26 volumio ntpd[4104]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 22 14:47:26 volumio ntpd[4104]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 788 days ago Feb 22 14:47:26 volumio ntpd[4104]: Listen and drop on 0 v6wildcard [::]:123 Feb 22 14:47:26 volumio ntpd[4104]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 22 14:47:26 volumio ntpd[4104]: Listen normally on 2 lo 127.0.0.1:123 Feb 22 14:47:26 volumio ntpd[4104]: Listen normally on 3 wlan0 192.168.28.129:123 Feb 22 14:47:26 volumio ntpd[4104]: Listening on routing socket on fd #20 for interface updates Feb 22 14:47:26 volumio ntpd[4104]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 22 14:47:26 volumio ntpd[4104]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 22 14:47:26 volumio wireless.js[3971]: trying... Feb 22 14:47:26 volumio sudo[4108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 14:47:26 volumio sudo[4108]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:26 volumio sudo[4108]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:26 volumio wireless.js[3971]: Connected to: ----Faster Feb 22 14:47:26 volumio wireless.js[3971]: ---- Feb 22 14:47:26 volumio sudo[4111]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:26 volumio sudo[4111]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:26 volumio sudo[4111]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:26 volumio wireless.js[3971]: ... joined AP, wlan0 IPv4 is 192.168.28.129, ipV6 is undefined Feb 22 14:47:26 volumio wireless.js[3971]: It's done! AP Feb 22 14:47:26 volumio iwconfig[4115]: Error for wireless request "Set Power Management" (8B2C) : Feb 22 14:47:26 volumio iwconfig[4115]: SET failed on device wlan0 ; Operation not supported. Feb 22 14:47:26 volumio systemd[1]: Started Wireless Services. Feb 22 14:47:26 volumio sudo[3943]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:26 volumio volumio[1154]: info: Discovery: adding 7fc7f21c-f028-4e9c-bb9d-38e79f189b24 Feb 22 14:47:26 volumio volumio[1154]: info: Discovery: Found device Volumio Feb 22 14:47:26 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:26 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:27 volumio ntpd[4104]: Soliciting pool server 162.159.200.1 Feb 22 14:47:28 volumio ntpd[4104]: Soliciting pool server 84.16.73.33 Feb 22 14:47:28 volumio ntpd[4104]: Soliciting pool server 78.46.102.180 Feb 22 14:47:29 volumio ntpd[4104]: Soliciting pool server 5.79.108.34 Feb 22 14:47:29 volumio ntpd[4104]: Soliciting pool server 193.182.111.143 Feb 22 14:47:29 volumio ntpd[4104]: Soliciting pool server 193.182.111.142 Feb 22 14:47:30 volumio ntpd[4104]: Soliciting pool server 117.80.112.205 Feb 22 14:47:30 volumio ntpd[4104]: Soliciting pool server 193.182.111.141 Feb 22 14:47:30 volumio ntpd[4104]: Soliciting pool server 117.80.231.60 Feb 22 14:47:31 volumio ntpd[4104]: Soliciting pool server 113.207.49.85 Feb 22 14:47:31 volumio ntpd[4104]: Soliciting pool server 116.203.151.74 Feb 22 14:47:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 22 14:47:31 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 22 14:47:31 volumio sudo[4121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:31 volumio sudo[4121]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:31 volumio sudo[4121]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:31 volumio sudo[4124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 14:47:31 volumio sudo[4124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:31 volumio sudo[4124]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:32 volumio ntpd[4104]: Soliciting pool server 202.118.1.130 Feb 22 14:47:32 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Feb 22 14:47:32 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:32 volumio volumio[1154]: info: Volumio Network Manager: Network status updated: 2 Feb 22 14:47:33 volumio ntpd[4104]: Soliciting pool server 2001:67c:21bc:1e::123 Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: info: Retrieving Cloud Streaming UI Feb 22 14:47:34 volumio volumio[1154]: info: Getting Tidal Cloud Configuration Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: info: Getting Qobuz Cloud Configuration Feb 22 14:47:34 volumio volumio[1154]: info: Asking plugin for UI Config Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: info: Getting Spotify Cloud Configuration Feb 22 14:47:34 volumio volumio[1154]: info: Asking plugin for UI Config Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: info: Saving Spotify Acccount Feb 22 14:47:34 volumio volumio[1154]: info: Got Tidal Cloud Configuration Feb 22 14:47:34 volumio volumio[1154]: info: Got it Feb 22 14:47:34 volumio volumio[1154]: info: Got it Feb 22 14:47:34 volumio volumio[1154]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:47:34 volumio volumio[1154]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:47:34 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 22 14:47:36 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 22 14:47:36 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 22 14:47:36 volumio volumio[1154]: info: Refreshing Cached IP Addresses Feb 22 14:47:36 volumio sudo[4145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 22 14:47:36 volumio sudo[4145]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:36 volumio sudo[4145]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:36 volumio sudo[4148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:47:36 volumio sudo[4148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:36 volumio sudo[4148]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:36 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 22 14:47:36 volumio sudo[4150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:36 volumio sudo[4150]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:36 volumio sudo[4150]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:38 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:47:42 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:47:43 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand rescan Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:43 volumio volumio[1154]: info: Feb 22 14:47:43 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:43 volumio volumio[1154]: info: sendMpdCommand rescan took 2 milliseconds Feb 22 14:47:43 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:43 volumio volumio[1154]: info: Feb 22 14:47:43 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:43 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:43 volumio volumio[1154]: info: sendMpdCommand status took 4 milliseconds Feb 22 14:47:43 volumio volumio[1154]: info: sendMpdCommand status took 2 milliseconds Feb 22 14:47:43 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 27ms Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: Feb 22 14:47:44 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: Feb 22 14:47:44 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 26 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 26 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 3 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 2 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 1 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 1 milliseconds Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 80ms Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 55ms Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 55ms Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: Feb 22 14:47:44 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: Feb 22 14:47:44 volumio volumio[1154]: ---------------------------- MPD announces state update: update Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::getState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 12 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 9 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 9 milliseconds Feb 22 14:47:44 volumio volumio[1154]: info: sendMpdCommand status took 8 milliseconds Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: Command Router : Notfying DB Updatefalse Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::Close All Modals sent Feb 22 14:47:44 volumio volumio[1154]: verbose: ControllerMpd::parseState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ControllerMpd::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 14:47:44 volumio volumio[1154]: verbose: CURRENT POSITION 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState stateService stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: No code Feb 22 14:47:44 volumio volumio[1154]: info: CoreStateMachine::pushState Feb 22 14:47:44 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Feb 22 14:47:44 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 47ms Feb 22 14:47:44 volumio volumio[1154]: info: ------------------------------ 44ms Feb 22 14:47:46 volumio sudo[4184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:47:46 volumio sudo[4184]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:46 volumio sudo[4184]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:46 volumio sudo[4187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:47:46 volumio sudo[4187]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:46 volumio sudo[4187]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:46 volumio sudo[4190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 14:47:46 volumio sudo[4190]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:47:46 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 22 14:47:46 volumio sudo[4190]: pam_unix(sudo:session): session closed for user root Feb 22 14:47:46 volumio volumio[1154]: info: Upmpdcli Daemon Started Feb 22 14:47:46 volumio volumio[4192]: writing RSA key Feb 22 14:47:46 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:47:47 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 22 14:47:47 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 22 14:47:50 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:47:53 volumio volumio[1154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:47:53 volumio volumio[1154]: Error: dns service error: unknown Feb 22 14:47:53 volumio volumio[1154]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 22 14:47:53 volumio volumio[1154]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 22 14:47:53 volumio volumio[1154]: errorCode: -65537 Feb 22 14:47:53 volumio volumio[1154]: } Feb 22 14:47:53 volumio volumio[1154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:47:53 volumio sudo[4252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-22 14:46 Feb 22 14:47:53 volumio sudo[4252]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"