-- Logs begin at Fri 2025-05-23 17:23:43 MSK, end at Mon 2025-05-26 11:17:24 MSK. -- May 26 11:16:03 smsl-n100 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 11:16:03 smsl-n100 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 11:16:03 smsl-n100 volumio[956]: info: Discovery: Getting this device information May 26 11:16:03 smsl-n100 volumio[956]: info: CoreCommandRouter::volumioGetState May 26 11:16:03 smsl-n100 volumio[956]: info: CorePlayQueue::getTrack 11 May 26 11:16:03 smsl-n100 volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 11:16:08 smsl-n100 volumio[956]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 26 11:16:09 smsl-n100 volumio[956]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 May 26 11:16:27 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-DISCONNECTED bssid=90:fb:5d:92:d4:17 reason=3 May 26 11:16:27 smsl-n100 dhcpcd[941]: wlan0: carrier lost May 26 11:16:27 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 26 11:16:27 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=RU May 26 11:16:27 smsl-n100 avahi-daemon[554]: Withdrawing address record for 192.168.1.169 on wlan0. May 26 11:16:27 smsl-n100 avahi-daemon[554]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.169. May 26 11:16:27 smsl-n100 avahi-daemon[554]: Interface wlan0.IPv4 no longer relevant for mDNS. May 26 11:16:27 smsl-n100 dhcpcd[941]: wlan0: deleting route to 192.168.1.0/24 May 26 11:16:27 smsl-n100 dhcpcd[941]: wlan0: deleting default route via 192.168.1.1 May 26 11:16:27 smsl-n100 volumio[956]: info: Discovery: A device disappeared from network May 26 11:16:29 smsl-n100 ntpd[940]: Deleting interface #3 wlan0, 192.168.1.169#123, interface stats: received=302, sent=340, dropped=0, active_time=3906 secs May 26 11:16:29 smsl-n100 ntpd[940]: 94.26.228.11 local addr 192.168.1.169 -> May 26 11:16:29 smsl-n100 ntpd[940]: 51.250.68.198 local addr 192.168.1.169 -> May 26 11:16:29 smsl-n100 ntpd[940]: 92.241.18.100 local addr 192.168.1.169 -> May 26 11:16:29 smsl-n100 ntpd[940]: 162.159.200.123 local addr 192.168.1.169 -> May 26 11:16:29 smsl-n100 ntpd[940]: 83.167.27.4 local addr 192.168.1.169 -> May 26 11:16:29 smsl-n100 ntpd[940]: 92.255.126.3 local addr 192.168.1.169 -> May 26 11:16:32 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 90:fb:5d:92:d4:16 (SSID='OpenWrt' freq=2422 MHz) May 26 11:16:35 smsl-n100 volumio[956]: info: Reporting MCU Network Status: 0 May 26 11:16:35 smsl-n100 volumio[956]: info: Volumio Network Manager: Network status updated: 0 May 26 11:16:35 smsl-n100 volumio[956]: info: CoreStateMachine::pushState May 26 11:16:35 smsl-n100 volumio[956]: info: CorePlayQueue::getTrack 11 May 26 11:16:35 smsl-n100 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:16:35 smsl-n100 volumio[956]: info: CoreCommandRouter::volumioPushState May 26 11:16:35 smsl-n100 volumio[956]: info: MRS: Pushing multiroomSync output update for this device May 26 11:16:35 smsl-n100 volumio[956]: info: MRS: Pushing multiroomSync output May 26 11:16:35 smsl-n100 volumio[956]: error: Serial API: Failed to decode command: 100, message: undefined May 26 11:16:35 smsl-n100 volumio[956]: error: Serial API: Failed to decode command: MAXVOL, message: May 26 11:16:35 smsl-n100 volumio[956]: error: Serial API: Failed to decode command: 100, message: undefined May 26 11:16:35 smsl-n100 volumio[956]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 26 11:16:35 smsl-n100 volumio[956]: error: Serial API: Failed to decode command: 0, message: undefined May 26 11:16:39 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 May 26 11:16:39 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 26 11:16:39 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=RU May 26 11:16:43 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 90:fb:5d:92:d4:16 (SSID='OpenWrt' freq=2422 MHz) May 26 11:16:43 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 May 26 11:16:49 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 90:fb:5d:92:d4:16 (SSID='OpenWrt' freq=2422 MHz) May 26 11:16:49 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 May 26 11:16:56 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 90:fb:5d:92:d4:16 (SSID='OpenWrt' freq=2422 MHz) May 26 11:16:56 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 May 26 11:16:56 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=2 ssid="OpenWrt" auth_failures=1 duration=10 reason=CONN_FAILED May 26 11:17:06 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 90:fb:5d:92:d4:17 (SSID='OpenWrt_AC' freq=5320 MHz) May 26 11:17:07 smsl-n100 wpa_supplicant[845]: wlan0: Associated with 90:fb:5d:92:d4:17 May 26 11:17:07 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 26 11:17:07 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU May 26 11:17:07 smsl-n100 wpa_supplicant[845]: wlan0: WPA: Key negotiation completed with 90:fb:5d:92:d4:17 [PTK=CCMP GTK=CCMP] May 26 11:17:07 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:fb:5d:92:d4:17 completed [id=0 id_str=] May 26 11:17:07 smsl-n100 dhcpcd[941]: wlan0: carrier acquired May 26 11:17:07 smsl-n100 dhcpcd[941]: wlan0: IAID 2b:ee:c8:49 May 26 11:17:07 smsl-n100 nmbd[730]: [2025/05/26 11:17:07.433226, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) May 26 11:17:07 smsl-n100 nmbd[730]: reload_interfaces: No subnets to listen to. Waiting.. May 26 11:17:07 smsl-n100 dhcpcd[941]: wlan0: soliciting an IPv6 router May 26 11:17:07 smsl-n100 dhcpcd[941]: wlan0: soliciting a DHCP lease May 26 11:17:12 smsl-n100 dhcpcd[941]: wlan0: probing for an IPv4LL address May 26 11:17:16 smsl-n100 dhcpcd[941]: wlan0: offered 192.168.1.169 from 192.168.1.1 May 26 11:17:16 smsl-n100 dhcpcd[941]: wlan0: probing address 192.168.1.169/24 May 26 11:17:17 smsl-n100 dhcpcd[941]: wlan0: using IPv4LL address 169.254.233.117 May 26 11:17:17 smsl-n100 avahi-daemon[554]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.233.117. May 26 11:17:17 smsl-n100 dhcpcd[941]: wlan0: adding route to 169.254.0.0/16 May 26 11:17:17 smsl-n100 avahi-daemon[554]: New relevant interface wlan0.IPv4 for mDNS. May 26 11:17:17 smsl-n100 dhcpcd[941]: wlan0: adding default route May 26 11:17:17 smsl-n100 avahi-daemon[554]: Registering new address record for 169.254.233.117 on wlan0.IPv4. May 26 11:17:19 smsl-n100 ntpd[940]: Listen normally on 4 wlan0 169.254.233.117:123 May 26 11:17:19 smsl-n100 ntpd[940]: new interface(s) found: waking up resolver May 26 11:17:21 smsl-n100 dhcpcd[941]: wlan0: leased 192.168.1.169 for 43200 seconds May 26 11:17:21 smsl-n100 dhcpcd[941]: wlan0: adding route to 192.168.1.0/24 May 26 11:17:21 smsl-n100 dhcpcd[941]: wlan0: changing default route via 192.168.1.1 May 26 11:17:21 smsl-n100 avahi-daemon[554]: Registering new address record for 192.168.1.169 on wlan0.IPv4. May 26 11:17:21 smsl-n100 ntpd[940]: ntpd exiting on signal 15 (Terminated) May 26 11:17:21 smsl-n100 ntpd[940]: 92.255.126.3 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 systemd[1]: Stopping Network Time Service... May 26 11:17:21 smsl-n100 ntpd[940]: 83.167.27.4 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 systemd[1]: ntp.service: Succeeded. May 26 11:17:21 smsl-n100 ntpd[940]: 162.159.200.123 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 ntpd[940]: 92.241.18.100 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 ntpd[940]: 51.250.68.198 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 ntpd[940]: 94.26.228.11 local addr 169.254.233.117 -> May 26 11:17:21 smsl-n100 systemd[1]: Stopped Network Time Service. May 26 11:17:21 smsl-n100 systemd[1]: Starting Network Time Service... May 26 11:17:21 smsl-n100 ntpd[17691]: ntpd 4.2.8p12@1.3728-o (1): Starting May 26 11:17:21 smsl-n100 ntpd[17691]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 26 11:17:21 smsl-n100 systemd[1]: Started Network Time Service. May 26 11:17:21 smsl-n100 ntpd[17698]: proto: precision = 0.875 usec (-20) May 26 11:17:21 smsl-n100 dhcpcd[941]: wlan0: deleting route to 169.254.0.0/16 May 26 11:17:21 smsl-n100 ntpd[17698]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 26 11:17:21 smsl-n100 ntpd[17698]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 26 11:17:21 smsl-n100 ntpd[17698]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 881 days ago May 26 11:17:21 smsl-n100 ntpd[17698]: Listen and drop on 0 v6wildcard [::]:123 May 26 11:17:21 smsl-n100 ntpd[17698]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 26 11:17:21 smsl-n100 avahi-daemon[554]: Withdrawing address record for 169.254.233.117 on wlan0. May 26 11:17:21 smsl-n100 avahi-daemon[554]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.233.117. May 26 11:17:21 smsl-n100 avahi-daemon[554]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.169. May 26 11:17:21 smsl-n100 ntpd[17698]: Listen normally on 2 lo 127.0.0.1:123 May 26 11:17:21 smsl-n100 ntpd[17698]: Listen normally on 3 wlan0 192.168.1.169:123 May 26 11:17:21 smsl-n100 ntpd[17698]: Listening on routing socket on fd #20 for interface updates May 26 11:17:21 smsl-n100 ntpd[17698]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 26 11:17:21 smsl-n100 ntpd[17698]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 26 11:17:22 smsl-n100 ntpd[17698]: Soliciting pool server 217.170.87.229 May 26 11:17:23 smsl-n100 ntpd[17698]: Soliciting pool server 92.255.126.4 May 26 11:17:23 smsl-n100 ntpd[17698]: Soliciting pool server 212.113.99.6 May 26 11:17:23 smsl-n100 volumio[956]: info: Discovery: adding b5ec292e-f0d1-43bd-b6ed-d55be01f460e May 26 11:17:23 smsl-n100 volumio[956]: info: Discovery: Found device SMSL N100 May 26 11:17:23 smsl-n100 volumio[956]: info: CoreCommandRouter::volumioGetState May 26 11:17:23 smsl-n100 volumio[956]: info: CorePlayQueue::getTrack 11 May 26 11:17:23 smsl-n100 volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 11:17:23 smsl-n100 volumio[956]: Error: The value NaN is not a number May 26 11:17:23 smsl-n100 volumio[956]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 26 11:17:23 smsl-n100 volumio[956]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 26 11:17:23 smsl-n100 volumio[956]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 26 11:17:23 smsl-n100 volumio[956]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 26 11:17:23 smsl-n100 volumio[956]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 26 11:17:23 smsl-n100 volumio[956]: at Browser.emit (events.js:400:28) May 26 11:17:23 smsl-n100 volumio[956]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 26 11:17:23 smsl-n100 volumio[956]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 26 11:17:23 smsl-n100 volumio[956]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 26 11:17:23 smsl-n100 volumio[956]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 26 11:17:23 smsl-n100 volumio[956]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 26 11:17:23 smsl-n100 volumio[956]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 26 11:17:23 smsl-n100 volumio[956]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 26 11:17:23 smsl-n100 volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 11:17:24 smsl-n100 ntpd[17698]: Soliciting pool server 89.109.251.24 May 26 11:17:24 smsl-n100 ntpd[17698]: Soliciting pool server 45.90.217.6 May 26 11:17:24 smsl-n100 ntpd[17698]: Soliciting pool server 193.106.93.116 May 26 11:17:24 smsl-n100 sudo[17725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-26 11:16 May 26 11:17:24 smsl-n100 sudo[17725]: 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"