-- Logs begin at Fri 2025-02-14 14:46:15 CET, end at Fri 2025-02-14 15:22:00 CET. -- Feb 14 15:21:00 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:00 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:05 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Feb 14 15:21:07 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 15:21:07 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 15:21:07 n100 volumio[9763]: info: Discovery: Getting this device information Feb 14 15:21:07 n100 volumio[9763]: info: CoreCommandRouter::volumioGetState Feb 14 15:21:07 n100 volumio[9763]: info: CorePlayQueue::getTrack 0 Feb 14 15:21:07 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 15:21:08 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 14 15:21:10 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 14 15:21:14 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:25 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:25 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:30 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Feb 14 15:21:34 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:37 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 14 15:21:37 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 14 15:21:37 n100 volumio[9763]: info: Discovery: Getting this device information Feb 14 15:21:37 n100 volumio[9763]: info: CoreCommandRouter::volumioGetState Feb 14 15:21:37 n100 volumio[9763]: info: CorePlayQueue::getTrack 0 Feb 14 15:21:37 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 14 15:21:37 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 14 15:21:37 n100 volumio[9763]: info: Saving new wireless network Feb 14 15:21:38 n100 sudo[12238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Feb 14 15:21:38 n100 sudo[12238]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:38 n100 sudo[12238]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:38 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 14 15:21:38 n100 volumio[9763]: info: Discovery: Restarting Advertising due to device name change Feb 14 15:21:38 n100 sudo[12241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 14 15:21:38 n100 sudo[12241]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:38 n100 systemd[1]: Starting Wireless Services... Feb 14 15:21:38 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 14 15:21:39 n100 wireless.js[12243]: Cleaning previous... Feb 14 15:21:39 n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Feb 14 15:21:39 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 14 15:21:39 n100 volumio[9763]: info: Discovery: A device disappeared from network Feb 14 15:21:39 n100 dnsmasq[1872]: exiting on receipt of SIGTERM Feb 14 15:21:39 n100 systemd[1]: dnsmasq.service: Succeeded. Feb 14 15:21:39 n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Feb 14 15:21:39 n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Feb 14 15:21:39 n100 dhcpcd[627]: wlan0: carrier lost Feb 14 15:21:39 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 14 15:21:39 n100 systemd[1]: hostapd.service: Succeeded. Feb 14 15:21:39 n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Feb 14 15:21:39 n100 avahi-daemon[551]: Withdrawing address record for 169.254.83.17 on wlan0. Feb 14 15:21:39 n100 dhcpcd[627]: wlan0: deleting route to 169.254.0.0/16 Feb 14 15:21:39 n100 dhcpcd[627]: wlan0: deleting default route Feb 14 15:21:39 n100 sudo[12278]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 15:21:39 n100 sudo[12278]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:39 n100 avahi-daemon[551]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 14 15:21:39 n100 avahi-daemon[551]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 14 15:21:39 n100 avahi-daemon[551]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 14 15:21:39 n100 sudo[12278]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:39 n100 sudo[12288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 15:21:39 n100 sudo[12288]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:39 n100 sudo[12288]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:39 n100 wireless.js[12243]: Stopped aP Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 15:21:39 n100 sudo[12295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 15:21:39 n100 sudo[12295]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:39 n100 sudo[12295]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:39 n100 sudo[12297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 14 15:21:39 n100 sudo[12297]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:39 n100 sudo[12297]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:39 n100 sudo[12304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 15:21:39 n100 sudo[12304]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:39 n100 sudo[12304]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:39 n100 sudo[12306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 14 15:21:39 n100 sudo[12306]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:40 n100 dhcpcd[627]: wlan0: carrier acquired Feb 14 15:21:40 n100 dhcpcd[627]: wlan0: IAID 2b:ee:c9:84 Feb 14 15:21:40 n100 dhcpcd[627]: wlan0: soliciting a DHCP lease Feb 14 15:21:40 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:40 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:40 n100 volumio[9763]: 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 14 15:21:40 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 14 15:21:40 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 14 15:21:40 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 14 15:21:40 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 14 15:21:40 n100 dhcpcd[627]: wlan0: soliciting an IPv6 router Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 57 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Feb 14 15:21:41 n100 ntpd[752]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=10, sent=5, dropped=0, active_time=2055 secs Feb 14 15:21:41 n100 ntpd[752]: Deleting interface #6 wlan0, 169.254.83.17#123, interface stats: received=0, sent=0, dropped=0, active_time=2043 secs Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 59 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 61 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 62 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 63 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 64 Feb 14 15:21:41 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 65 Feb 14 15:21:41 n100 volumio[9763]: info: CoreCommandRouter::volumioGetState Feb 14 15:21:41 n100 volumio[9763]: info: CorePlayQueue::getTrack 0 Feb 14 15:21:42 n100 volumio[9763]: info: CoreStateMachine::pushState Feb 14 15:21:42 n100 volumio[9763]: info: CorePlayQueue::getTrack 0 Feb 14 15:21:42 n100 volumio[9763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 15:21:42 n100 volumio[9763]: info: CoreCommandRouter::volumioPushState Feb 14 15:21:42 n100 volumio[9763]: info: MRS: Pushing multiroomSync output update for this device Feb 14 15:21:42 n100 volumio[9763]: info: MRS: Pushing multiroomSync output Feb 14 15:21:42 n100 volumio[9763]: error: Serial API: Failed to decode command: 100, message: undefined Feb 14 15:21:42 n100 volumio[9763]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 14 15:21:42 n100 volumio[9763]: error: Serial API: Failed to decode command: 100, message: undefined Feb 14 15:21:42 n100 volumio[9763]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 14 15:21:43 n100 volumio[9763]: info: Discovery: Started advertising with name: N100 Feb 14 15:21:44 n100 sudo[12306]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:44 n100 wireless.js[12243]: Start wireless flow Feb 14 15:21:44 n100 wireless.js[12243]: Stopped hotspot (if there).. Feb 14 15:21:44 n100 sudo[12314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 15:21:44 n100 sudo[12314]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:44 n100 sudo[12314]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:44 n100 sudo[12316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 15:21:44 n100 sudo[12316]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:44 n100 dhcpcd[627]: wlan0: carrier lost Feb 14 15:21:44 n100 sudo[12316]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:44 n100 wireless.js[12243]: DHCP IP Feb 14 15:21:44 n100 wireless.js[12243]: Start ap Feb 14 15:21:44 n100 wpa_supplicant[12321]: Successfully initialized wpa_supplicant Feb 14 15:21:44 n100 sudo[12326]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 14 15:21:44 n100 sudo[12326]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:44 n100 dhcpcd[627]: wlan0: carrier acquired Feb 14 15:21:44 n100 dhcpcd[12334]: sending commands to master dhcpcd process Feb 14 15:21:44 n100 sudo[12326]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:44 n100 dhcpcd[627]: wlan0: IAID 2b:ee:c9:84 Feb 14 15:21:44 n100 dhcpcd[627]: wlan0: carrier lost Feb 14 15:21:44 n100 dhcpcd[627]: control command: /sbin/dhcpcd Feb 14 15:21:45 n100 wireless.js[12243]: trying... Feb 14 15:21:45 n100 volumio[9763]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 66 Feb 14 15:21:45 n100 sudo[12345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:45 n100 sudo[12345]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:45 n100 sudo[12345]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:45 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Feb 14 15:21:45 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:45 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:46 n100 wireless.js[12243]: trying... Feb 14 15:21:46 n100 sudo[12354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:46 n100 sudo[12354]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:46 n100 sudo[12354]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:47 n100 wireless.js[12243]: trying... Feb 14 15:21:47 n100 sudo[12360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:47 n100 sudo[12360]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:47 n100 sudo[12360]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:48 n100 wireless.js[12243]: trying... Feb 14 15:21:48 n100 sudo[12377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:48 n100 sudo[12377]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:48 n100 sudo[12377]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:49 n100 wireless.js[12243]: trying... Feb 14 15:21:49 n100 sudo[12380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:49 n100 sudo[12380]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:49 n100 sudo[12380]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: Trying to associate with 3c:82:c0:0c:fa:30 (SSID='VOO-V12X0ET' freq=5500 MHz) Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: Associated with 3c:82:c0:0c:fa:30 Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: WPA: Key negotiation completed with 3c:82:c0:0c:fa:30 [PTK=CCMP GTK=CCMP] Feb 14 15:21:50 n100 wpa_supplicant[12337]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:82:c0:0c:fa:30 completed [id=0 id_str=] Feb 14 15:21:50 n100 dhcpcd[627]: wlan0: carrier acquired Feb 14 15:21:50 n100 dhcpcd[627]: wlan0: IAID 2b:ee:c9:84 Feb 14 15:21:50 n100 dhcpcd[627]: wlan0: soliciting an IPv6 router Feb 14 15:21:50 n100 wireless.js[12243]: trying... Feb 14 15:21:50 n100 sudo[12386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:50 n100 sudo[12386]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:50 n100 sudo[12386]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:50 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Feb 14 15:21:50 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:50 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:50 n100 dhcpcd[627]: wlan0: soliciting a DHCP lease Feb 14 15:21:51 n100 wireless.js[12243]: trying... Feb 14 15:21:51 n100 sudo[12394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:51 n100 sudo[12394]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:51 n100 sudo[12394]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:52 n100 wireless.js[12243]: trying... Feb 14 15:21:52 n100 sudo[12397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:52 n100 sudo[12397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:52 n100 sudo[12397]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:53 n100 wireless.js[12243]: trying... Feb 14 15:21:53 n100 sudo[12400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:53 n100 sudo[12400]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:53 n100 sudo[12400]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:54 n100 dhcpcd[627]: wlan0: offered 192.168.0.131 from 192.168.0.1 Feb 14 15:21:54 n100 dhcpcd[627]: wlan0: probing address 192.168.0.131/24 Feb 14 15:21:54 n100 wireless.js[12243]: trying... Feb 14 15:21:54 n100 sudo[12403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:54 n100 sudo[12403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:54 n100 sudo[12403]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:55 n100 wireless.js[12243]: trying... Feb 14 15:21:55 n100 sudo[12406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:55 n100 sudo[12406]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:55 n100 sudo[12406]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:55 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Feb 14 15:21:55 n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Feb 14 15:21:55 n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 15:21:56 n100 wireless.js[12243]: trying... Feb 14 15:21:56 n100 sudo[12414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:56 n100 sudo[12414]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:56 n100 sudo[12414]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:57 n100 wireless.js[12243]: trying... Feb 14 15:21:57 n100 sudo[12420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:57 n100 sudo[12420]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:57 n100 sudo[12420]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:58 n100 wireless.js[12243]: trying... Feb 14 15:21:58 n100 sudo[12437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:58 n100 sudo[12437]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:58 n100 sudo[12437]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:58 n100 dhcpcd[627]: wlan0: leased 192.168.0.131 for 86400 seconds Feb 14 15:21:58 n100 avahi-daemon[551]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.131. Feb 14 15:21:58 n100 avahi-daemon[551]: New relevant interface wlan0.IPv4 for mDNS. Feb 14 15:21:58 n100 dhcpcd[627]: wlan0: adding route to 192.168.0.0/24 Feb 14 15:21:58 n100 avahi-daemon[551]: Registering new address record for 192.168.0.131 on wlan0.IPv4. Feb 14 15:21:58 n100 dhcpcd[627]: wlan0: adding default route via 192.168.0.1 Feb 14 15:21:59 n100 ntpd[752]: ntpd exiting on signal 15 (Terminated) Feb 14 15:21:59 n100 systemd[1]: Stopping Network Time Service... Feb 14 15:21:59 n100 systemd[1]: ntp.service: Succeeded. Feb 14 15:21:59 n100 systemd[1]: Stopped Network Time Service. Feb 14 15:21:59 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 14 15:21:59 n100 systemd[1]: Starting Network Time Service... Feb 14 15:21:59 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 14 15:21:59 n100 volumio[9763]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 14 15:21:59 n100 volumio[9763]: info: MRS: Found cast device: Onkyo-NS-6170-8417eb424484e1e8e59ba2d12825e493 Feb 14 15:21:59 n100 volumio[9763]: info: Adding audio output: Feb 14 15:21:59 n100 ntpd[12474]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 15:21:59 n100 ntpd[12474]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 14 15:21:59 n100 systemd[1]: Started Network Time Service. Feb 14 15:21:59 n100 ntpd[12482]: proto: precision = 0.875 usec (-20) Feb 14 15:21:59 n100 ntpd[12482]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 15:21:59 n100 ntpd[12482]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 15:21:59 n100 ntpd[12482]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 780 days ago Feb 14 15:21:59 n100 ntpd[12482]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 15:21:59 n100 ntpd[12482]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 15:21:59 n100 ntpd[12482]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 15:21:59 n100 ntpd[12482]: Listen normally on 3 wlan0 192.168.0.131:123 Feb 14 15:21:59 n100 ntpd[12482]: Listening on routing socket on fd #20 for interface updates Feb 14 15:21:59 n100 ntpd[12482]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Feb 14 15:21:59 n100 ntpd[12482]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Feb 14 15:21:59 n100 wireless.js[12243]: trying... Feb 14 15:21:59 n100 sudo[12485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 15:21:59 n100 sudo[12485]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:59 n100 sudo[12485]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:59 n100 wireless.js[12243]: Connected to: ----VOO-V12X0ET Feb 14 15:21:59 n100 wireless.js[12243]: ---- Feb 14 15:21:59 n100 sudo[12488]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 15:21:59 n100 sudo[12488]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:21:59 n100 sudo[12488]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:59 n100 wireless.js[12243]: ... joined AP, wlan0 IPv4 is 192.168.0.131, ipV6 is undefined Feb 14 15:21:59 n100 wireless.js[12243]: It's done! AP Feb 14 15:21:59 n100 iwconfig[12492]: Error for wireless request "Set Power Management" (8B2C) : Feb 14 15:21:59 n100 iwconfig[12492]: SET failed on device wlan0 ; Operation not permitted. Feb 14 15:21:59 n100 systemd[1]: Started Wireless Services. Feb 14 15:21:59 n100 sudo[12241]: pam_unix(sudo:session): session closed for user root Feb 14 15:21:59 n100 volumio[9763]: info: Discovery: adding 4d810b75-9892-407c-bb56-b7fbca16a689 Feb 14 15:21:59 n100 volumio[9763]: info: Discovery: Found device N100 Feb 14 15:21:59 n100 volumio[9763]: info: CoreCommandRouter::volumioGetState Feb 14 15:21:59 n100 volumio[9763]: info: CorePlayQueue::getTrack 0 Feb 14 15:21:59 n100 volumio[9763]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 15:21:59 n100 volumio[9763]: Error: The value NaN is not a number Feb 14 15:21:59 n100 volumio[9763]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Feb 14 15:21:59 n100 volumio[9763]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Feb 14 15:21:59 n100 volumio[9763]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Feb 14 15:21:59 n100 volumio[9763]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Feb 14 15:21:59 n100 volumio[9763]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Feb 14 15:21:59 n100 volumio[9763]: at Browser.emit (events.js:400:28) Feb 14 15:21:59 n100 volumio[9763]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Feb 14 15:21:59 n100 volumio[9763]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Feb 14 15:21:59 n100 volumio[9763]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Feb 14 15:21:59 n100 volumio[9763]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Feb 14 15:21:59 n100 volumio[9763]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Feb 14 15:21:59 n100 volumio[9763]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Feb 14 15:21:59 n100 volumio[9763]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Feb 14 15:21:59 n100 volumio[9763]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 15:22:00 n100 ntpd[12482]: Soliciting pool server 162.159.200.123 Feb 14 15:22:00 n100 sudo[12502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-14 15:21 Feb 14 15:22:00 n100 sudo[12502]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 15:22:00 n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"