-- Logs begin at Thu 2025-10-16 16:23:58 NZDT, end at Thu 2025-10-16 16:28:13 NZDT. -- Oct 16 16:27:03 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:27:05 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 16:27:14 smsl-n100 ntpd[697]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 16 16:27:15 smsl-n100 volumio[1878]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=8b10e2ea1ca33b7ef5082645af512341" http://updates.volumio.org/downloader-v1/track-device Oct 16 16:27:15 smsl-n100 volumio[1878]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 16:27:15 smsl-n100 volumio[1878]: Dload Upload Total Spent Left Speed Oct 16 16:27:15 smsl-n100 volumio[1878]: [1.6K blob data] Oct 16 16:27:15 smsl-n100 volumio[1878]: retrying in 5 seconds, trial 2 Oct 16 16:27:15 smsl-n100 volumio[1878]: info: Volumio Calling Home Oct 16 16:27:20 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:27:20 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:27:20 smsl-n100 volumio[1878]: info: Discovery: Getting this device information Oct 16 16:27:20 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumioGetState Oct 16 16:27:20 smsl-n100 volumio[1878]: info: CorePlayQueue::getTrack 0 Oct 16 16:27:20 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:27:23 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:27:23 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:27:28 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:27:30 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Oct 16 16:27:30 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Oct 16 16:27:30 smsl-n100 sudo[2874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 16:27:30 smsl-n100 sudo[2874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:30 smsl-n100 sudo[2874]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:34 smsl-n100 volumio[1878]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Retrieving Cloud Streaming UI Oct 16 16:27:34 smsl-n100 ntpd[697]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Getting Tidal Cloud Configuration Oct 16 16:27:34 smsl-n100 volumio[1878]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Getting Qobuz Cloud Configuration Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Asking plugin for UI Config Oct 16 16:27:34 smsl-n100 volumio[1878]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Getting Spotify Cloud Configuration Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Asking plugin for UI Config Oct 16 16:27:34 smsl-n100 volumio[1878]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Saving Spotify Acccount Oct 16 16:27:34 smsl-n100 volumio[1878]: info: Got it Oct 16 16:27:34 smsl-n100 volumio[1878]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 16:27:35 smsl-n100 volumio[1878]: info: Got Tidal Cloud Configuration Oct 16 16:27:35 smsl-n100 volumio[1878]: info: Got it Oct 16 16:27:35 smsl-n100 volumio[1878]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:27:39 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Oct 16 16:27:39 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 16 16:27:39 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Oct 16 16:27:39 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Oct 16 16:27:39 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 16 16:27:45 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 16:27:45 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 16 16:27:48 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:27:48 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:27:48 smsl-n100 volumio[1878]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Oct 16 16:27:48 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Oct 16 16:27:48 smsl-n100 volumio[1878]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:27:50 smsl-n100 volumio[1878]: info: Discovery: Getting this device information Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumioGetState Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CorePlayQueue::getTrack 0 Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::Reload Ui Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Oct 16 16:27:50 smsl-n100 volumio[1878]: info: Wizard terminated Successfully Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumiosetStartupVolume Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:27:50 smsl-n100 volumio[1878]: info: Executing Deferred Wifi Connection Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 16 16:27:50 smsl-n100 volumio[1878]: info: Saving new wireless network Oct 16 16:27:50 smsl-n100 sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 16 16:27:50 smsl-n100 sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:50 smsl-n100 sudo[2913]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:50 smsl-n100 volumio[1878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 16 16:27:50 smsl-n100 volumio[1878]: info: Discovery: Restarting Advertising due to device name change Oct 16 16:27:50 smsl-n100 sudo[2916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 16 16:27:50 smsl-n100 sudo[2916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:50 smsl-n100 systemd[1]: Starting Wireless Services... Oct 16 16:27:50 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:27:51 smsl-n100 wireless.js[2918]: Cleaning previous... Oct 16 16:27:51 smsl-n100 volumio[1878]: info: Discovery: A device disappeared from network Oct 16 16:27:51 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Oct 16 16:27:51 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:27:51 smsl-n100 dnsmasq[1858]: exiting on receipt of SIGTERM Oct 16 16:27:51 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Oct 16 16:27:51 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Oct 16 16:27:51 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 16 16:27:51 smsl-n100 dhcpcd[609]: wlan0: carrier lost Oct 16 16:27:51 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:27:51 smsl-n100 systemd[1]: hostapd.service: Succeeded. Oct 16 16:27:51 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 16 16:27:52 smsl-n100 avahi-daemon[538]: Withdrawing address record for 169.254.108.38 on wlan0. Oct 16 16:27:52 smsl-n100 dhcpcd[609]: wlan0: deleting route to 169.254.0.0/16 Oct 16 16:27:52 smsl-n100 dhcpcd[609]: wlan0: deleting default route Oct 16 16:27:52 smsl-n100 sudo[2951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 16:27:52 smsl-n100 sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 avahi-daemon[538]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 16 16:27:52 smsl-n100 avahi-daemon[538]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 16 16:27:52 smsl-n100 avahi-daemon[538]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 16 16:27:52 smsl-n100 sudo[2951]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:52 smsl-n100 sudo[2963]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 16:27:52 smsl-n100 sudo[2963]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 sudo[2963]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:52 smsl-n100 wireless.js[2918]: Stopped aP Oct 16 16:27:52 smsl-n100 sudo[2970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 16:27:52 smsl-n100 sudo[2970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 sudo[2970]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:52 smsl-n100 sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 16 16:27:52 smsl-n100 sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 sudo[2972]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:52 smsl-n100 sudo[2979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 16:27:52 smsl-n100 sudo[2979]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 sudo[2979]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:52 smsl-n100 sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 16 16:27:52 smsl-n100 sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:52 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 16 16:27:52 smsl-n100 dhcpcd[609]: wlan0: carrier acquired Oct 16 16:27:52 smsl-n100 dhcpcd[609]: wlan0: IAID 2b:ee:c9:f9 Oct 16 16:27:52 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Oct 16 16:27:53 smsl-n100 dhcpcd[609]: wlan0: soliciting an IPv6 router Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Oct 16 16:27:53 smsl-n100 dhcpcd[609]: wlan0: soliciting a DHCP lease Oct 16 16:27:53 smsl-n100 volumio[1878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 16 16:27:53 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumioGetState Oct 16 16:27:53 smsl-n100 volumio[1878]: info: CorePlayQueue::getTrack 0 Oct 16 16:27:53 smsl-n100 volumio[1878]: info: CoreStateMachine::pushState Oct 16 16:27:53 smsl-n100 volumio[1878]: info: CorePlayQueue::getTrack 0 Oct 16 16:27:53 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumioPushState Oct 16 16:27:53 smsl-n100 volumio[1878]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:27:53 smsl-n100 volumio[1878]: info: MRS: Pushing multiroomSync output Oct 16 16:27:53 smsl-n100 volumio[1878]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:27:53 smsl-n100 volumio[1878]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:27:53 smsl-n100 volumio[1878]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:27:53 smsl-n100 volumio[1878]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:27:53 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:27:53 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:27:53 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:27:54 smsl-n100 ntpd[697]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=165 secs Oct 16 16:27:54 smsl-n100 ntpd[697]: Deleting interface #6 wlan0, 169.254.108.38#123, interface stats: received=0, sent=0, dropped=0, active_time=154 secs Oct 16 16:27:54 smsl-n100 ntpd[697]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 16 16:27:54 smsl-n100 ntpd[697]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 16 16:27:54 smsl-n100 ntpd[697]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 16 16:27:54 smsl-n100 ntpd[697]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 16 16:27:55 smsl-n100 volumio[1878]: info: Discovery: Started advertising with name: SMSL N100 Oct 16 16:27:56 smsl-n100 sudo[2981]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:56 smsl-n100 wireless.js[2918]: Start wireless flow Oct 16 16:27:56 smsl-n100 wireless.js[2918]: Stopped hotspot (if there).. Oct 16 16:27:57 smsl-n100 sudo[2994]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 16:27:57 smsl-n100 sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:57 smsl-n100 sudo[2994]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:57 smsl-n100 sudo[2996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 16:27:57 smsl-n100 sudo[2996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:57 smsl-n100 dhcpcd[609]: wlan0: carrier lost Oct 16 16:27:57 smsl-n100 sudo[2996]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:57 smsl-n100 wireless.js[2918]: DHCP IP Oct 16 16:27:57 smsl-n100 wireless.js[2918]: Start ap Oct 16 16:27:57 smsl-n100 wpa_supplicant[3001]: Successfully initialized wpa_supplicant Oct 16 16:27:57 smsl-n100 sudo[3006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 16 16:27:57 smsl-n100 sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:57 smsl-n100 dhcpcd[609]: wlan0: carrier acquired Oct 16 16:27:57 smsl-n100 dhcpcd[3014]: sending commands to master dhcpcd process Oct 16 16:27:57 smsl-n100 sudo[3006]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:57 smsl-n100 dhcpcd[609]: wlan0: IAID 2b:ee:c9:f9 Oct 16 16:27:57 smsl-n100 dhcpcd[609]: wlan0: carrier lost Oct 16 16:27:57 smsl-n100 dhcpcd[609]: control command: /sbin/dhcpcd Oct 16 16:27:58 smsl-n100 wireless.js[2918]: trying... Oct 16 16:27:58 smsl-n100 sudo[3025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:27:58 smsl-n100 sudo[3025]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:58 smsl-n100 sudo[3025]: pam_unix(sudo:session): session closed for user root Oct 16 16:27:58 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:27:58 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:27:58 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:27:59 smsl-n100 wireless.js[2918]: trying... Oct 16 16:27:59 smsl-n100 sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:27:59 smsl-n100 sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:27:59 smsl-n100 sudo[3033]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:00 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:00 smsl-n100 sudo[3050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:00 smsl-n100 sudo[3050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:00 smsl-n100 sudo[3050]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:01 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:01 smsl-n100 sudo[3053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:01 smsl-n100 sudo[3053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:01 smsl-n100 sudo[3053]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:02 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:02 smsl-n100 sudo[3059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:02 smsl-n100 sudo[3059]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:02 smsl-n100 sudo[3059]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: Trying to associate with b0:e4:d5:28:d1:81 (SSID='Jiang-Family' freq=5745 MHz) Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: Associated with b0:e4:d5:28:d1:81 Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: WPA: Key negotiation completed with b0:e4:d5:28:d1:81 [PTK=CCMP GTK=CCMP] Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:e4:d5:28:d1:81 completed [id=0 id_str=] Oct 16 16:28:02 smsl-n100 dhcpcd[609]: wlan0: carrier acquired Oct 16 16:28:02 smsl-n100 dhcpcd[609]: wlan0: IAID 2b:ee:c9:f9 Oct 16 16:28:02 smsl-n100 wpa_supplicant[3017]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU Oct 16 16:28:03 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:03 smsl-n100 sudo[3065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:03 smsl-n100 sudo[3065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:03 smsl-n100 sudo[3065]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:03 smsl-n100 dhcpcd[609]: wlan0: soliciting an IPv6 router Oct 16 16:28:03 smsl-n100 dhcpcd[609]: wlan0: soliciting a DHCP lease Oct 16 16:28:03 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:28:03 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:28:03 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:28:04 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:04 smsl-n100 sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:04 smsl-n100 sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:04 smsl-n100 sudo[3114]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:05 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:05 smsl-n100 sudo[3117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:05 smsl-n100 sudo[3117]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:05 smsl-n100 sudo[3117]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:06 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:06 smsl-n100 sudo[3120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:06 smsl-n100 sudo[3120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:06 smsl-n100 sudo[3120]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:06 smsl-n100 dhcpcd[609]: wlan0: offered 192.168.86.39 from 192.168.86.1 Oct 16 16:28:06 smsl-n100 dhcpcd[609]: wlan0: ignoring offer of 192.168.86.39 from 192.168.86.1 Oct 16 16:28:06 smsl-n100 dhcpcd[609]: wlan0: probing address 192.168.86.39/24 Oct 16 16:28:07 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:07 smsl-n100 sudo[3123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:07 smsl-n100 sudo[3123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:07 smsl-n100 sudo[3123]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:08 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:08 smsl-n100 sudo[3126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:08 smsl-n100 sudo[3126]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:08 smsl-n100 sudo[3126]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:08 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Fetching time from Volumio... Oct 16 16:28:08 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Date not found in response Oct 16 16:28:08 smsl-n100 volumio-time-update[546]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 16:28:09 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:09 smsl-n100 sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:09 smsl-n100 sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:09 smsl-n100 sudo[3134]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:10 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:10 smsl-n100 sudo[3151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:10 smsl-n100 sudo[3151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:10 smsl-n100 sudo[3151]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:11 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:11 smsl-n100 sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:11 smsl-n100 sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:11 smsl-n100 sudo[3154]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:11 smsl-n100 dhcpcd[609]: wlan0: leased 192.168.86.39 for 86400 seconds Oct 16 16:28:11 smsl-n100 avahi-daemon[538]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.39. Oct 16 16:28:11 smsl-n100 avahi-daemon[538]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 16:28:11 smsl-n100 dhcpcd[609]: wlan0: adding route to 192.168.86.0/24 Oct 16 16:28:11 smsl-n100 avahi-daemon[538]: Registering new address record for 192.168.86.39 on wlan0.IPv4. Oct 16 16:28:11 smsl-n100 dhcpcd[609]: wlan0: adding default route via 192.168.86.1 Oct 16 16:28:11 smsl-n100 ntpd[697]: ntpd exiting on signal 15 (Terminated) Oct 16 16:28:11 smsl-n100 systemd[1]: Stopping Network Time Service... Oct 16 16:28:11 smsl-n100 systemd[1]: ntp.service: Succeeded. Oct 16 16:28:11 smsl-n100 systemd[1]: Stopped Network Time Service. Oct 16 16:28:11 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:28:11 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:28:11 smsl-n100 systemd[1]: Starting Network Time Service... Oct 16 16:28:11 smsl-n100 volumio[1878]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:28:11 smsl-n100 ntpd[3191]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 16 16:28:11 smsl-n100 ntpd[3191]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 16 16:28:11 smsl-n100 systemd[1]: Started Network Time Service. Oct 16 16:28:11 smsl-n100 ntpd[3199]: proto: precision = 0.875 usec (-20) Oct 16 16:28:11 smsl-n100 ntpd[3199]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 16 16:28:11 smsl-n100 ntpd[3199]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 16 16:28:11 smsl-n100 ntpd[3199]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1024 days ago Oct 16 16:28:11 smsl-n100 ntpd[3199]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 16:28:11 smsl-n100 ntpd[3199]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 16:28:11 smsl-n100 ntpd[3199]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 16:28:11 smsl-n100 ntpd[3199]: Listen normally on 3 wlan0 192.168.86.39:123 Oct 16 16:28:11 smsl-n100 ntpd[3199]: Listening on routing socket on fd #20 for interface updates Oct 16 16:28:11 smsl-n100 ntpd[3199]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 16:28:11 smsl-n100 ntpd[3199]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 16:28:12 smsl-n100 volumio[1878]: info: MRS: Found cast device: Chromecast-b32674d852551e964121296a229c3ce8 Oct 16 16:28:12 smsl-n100 volumio[1878]: info: Adding audio output: Oct 16 16:28:12 smsl-n100 volumio[1878]: info: MRS: Found cast device: Chromecast-HD-3f5c2e05c6b4aafb50e1e2d2f4e6131f Oct 16 16:28:12 smsl-n100 volumio[1878]: info: Adding audio output: Oct 16 16:28:12 smsl-n100 wireless.js[2918]: trying... Oct 16 16:28:12 smsl-n100 sudo[3205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 16:28:12 smsl-n100 sudo[3205]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:12 smsl-n100 sudo[3205]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:12 smsl-n100 wireless.js[2918]: Connected to: ----Jiang-Family Oct 16 16:28:12 smsl-n100 wireless.js[2918]: ---- Oct 16 16:28:12 smsl-n100 sudo[3208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 16:28:12 smsl-n100 sudo[3208]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:28:12 smsl-n100 sudo[3208]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:12 smsl-n100 wireless.js[2918]: ... joined AP, wlan0 IPv4 is 192.168.86.39, ipV6 is undefined Oct 16 16:28:12 smsl-n100 wireless.js[2918]: It's done! AP Oct 16 16:28:12 smsl-n100 iwconfig[3212]: Error for wireless request "Set Power Management" (8B2C) : Oct 16 16:28:12 smsl-n100 iwconfig[3212]: SET failed on device wlan0 ; Operation not permitted. Oct 16 16:28:12 smsl-n100 systemd[1]: Started Wireless Services. Oct 16 16:28:12 smsl-n100 sudo[2916]: pam_unix(sudo:session): session closed for user root Oct 16 16:28:12 smsl-n100 volumio[1878]: info: Discovery: adding 199e646b-0fd1-4e5b-b0fd-05a66a19ef62 Oct 16 16:28:12 smsl-n100 volumio[1878]: info: Discovery: Found device SMSL N100 Oct 16 16:28:12 smsl-n100 volumio[1878]: info: CoreCommandRouter::volumioGetState Oct 16 16:28:12 smsl-n100 volumio[1878]: info: CorePlayQueue::getTrack 0 Oct 16 16:28:12 smsl-n100 volumio[1878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:28:12 smsl-n100 volumio[1878]: Error: The value NaN is not a number Oct 16 16:28:12 smsl-n100 volumio[1878]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 16 16:28:12 smsl-n100 volumio[1878]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 16 16:28:12 smsl-n100 volumio[1878]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 16 16:28:12 smsl-n100 volumio[1878]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 16 16:28:12 smsl-n100 volumio[1878]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 16 16:28:12 smsl-n100 volumio[1878]: at Browser.emit (events.js:400:28) Oct 16 16:28:12 smsl-n100 volumio[1878]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 16 16:28:12 smsl-n100 volumio[1878]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 16 16:28:12 smsl-n100 volumio[1878]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 16 16:28:12 smsl-n100 volumio[1878]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 16 16:28:12 smsl-n100 volumio[1878]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 16 16:28:12 smsl-n100 volumio[1878]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 16 16:28:12 smsl-n100 volumio[1878]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 16 16:28:12 smsl-n100 volumio[1878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:28:13 smsl-n100 ntpd[3199]: Soliciting pool server 203.190.214.199 Oct 16 16:28:13 smsl-n100 sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 16:27 Oct 16 16:28:13 smsl-n100 sudo[3222]: 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="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"