-- Logs begin at Thu 2025-09-11 00:11:00 +08, end at Thu 2025-09-11 00:22:23 +08. -- Sep 11 00:22:10 smsl-n100 systemd-timedated[3436]: Changed local time to Thu Sep 11 00:22:10 2025 Sep 11 00:22:10 smsl-n100 sudo[3434]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:10 smsl-n100 volumio-time-update[568]: volumio-time-update-util: System time updated successfully. Sep 11 00:22:10 smsl-n100 systemd[1]: Started Volumio Time Update Utility. Sep 11 00:22:10 smsl-n100 systemd[1]: Reached target Multi-User System. Sep 11 00:22:10 smsl-n100 systemd[1]: Reached target Graphical Interface. Sep 11 00:22:10 smsl-n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 11 00:22:10 smsl-n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 11 00:22:10 smsl-n100 systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 11 00:22:10 smsl-n100 systemd[1]: Startup finished in 6.046s (kernel) + 8min 29.061s (userspace) = 8min 35.108s. Sep 11 00:22:11 smsl-n100 volumio[2275]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 11 00:22:11 smsl-n100 volumio[2275]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 11 00:22:11 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 00:22:11 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 00:22:11 smsl-n100 volumio[2275]: info: Starting MyVolumio Remote Streaming Endpoints Sep 11 00:22:11 smsl-n100 volumio[2275]: info: MyVolumio not started Sep 11 00:22:11 smsl-n100 volumio[2275]: info: Initializing device activation check Sep 11 00:22:11 smsl-n100 volumio[2275]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 11 00:22:11 smsl-n100 volumio[2275]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Sep 11 00:22:11 smsl-n100 volumio[2275]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Sep 11 00:22:11 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Sep 11 00:22:11 smsl-n100 sudo[3443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Sep 11 00:22:11 smsl-n100 sudo[3443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:11 smsl-n100 sudo[3443]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:11 smsl-n100 sudo[3445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Sep 11 00:22:11 smsl-n100 sudo[3445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:12 smsl-n100 sudo[3445]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:12 smsl-n100 volumio[2275]: info: Permissions for /etc/network/interfaces set Sep 11 00:22:12 smsl-n100 volumio[2275]: info: Restarting Wired Network Sep 11 00:22:12 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 11 00:22:12 smsl-n100 volumio[2275]: info: Discovery: Restarting Advertising due to device name change Sep 11 00:22:12 smsl-n100 sudo[3448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Sep 11 00:22:12 smsl-n100 sudo[3448]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: pid 3449 deleted IP address 192.168.1.111/24 Sep 11 00:22:12 smsl-n100 dhcpcd[610]: wlan0: adding default route Sep 11 00:22:12 smsl-n100 avahi-daemon[563]: Withdrawing address record for 192.168.1.111 on eth0. Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: deleting route to 192.168.1.0/24 Sep 11 00:22:12 smsl-n100 avahi-daemon[563]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.111. Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: deleting default route via 192.168.1.1 Sep 11 00:22:12 smsl-n100 avahi-daemon[563]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 11 00:22:12 smsl-n100 sudo[3448]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:12 smsl-n100 volumio[2275]: info: Discovery: A device disappeared from network Sep 11 00:22:12 smsl-n100 dnsmasq[1698]: reading /etc/resolv.conf Sep 11 00:22:12 smsl-n100 dnsmasq[1698]: using nameserver 208.67.222.222#53 Sep 11 00:22:12 smsl-n100 dnsmasq[1698]: using nameserver 208.67.220.220#53 Sep 11 00:22:12 smsl-n100 sudo[3451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Sep 11 00:22:12 smsl-n100 sudo[3451]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Sep 11 00:22:12 smsl-n100 sudo[3451]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:12 smsl-n100 sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Sep 11 00:22:12 smsl-n100 sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Sep 11 00:22:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Sep 11 00:22:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Sep 11 00:22:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Sep 11 00:22:12 smsl-n100 sudo[3465]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:12 smsl-n100 ntpd[3412]: ntpd exiting on signal 15 (Terminated) Sep 11 00:22:12 smsl-n100 ntpd[3412]: 129.6.15.28 local addr 192.168.1.111 -> Sep 11 00:22:12 smsl-n100 systemd[1]: Stopping Network Time Service... Sep 11 00:22:12 smsl-n100 systemd[1]: ntp.service: Succeeded. Sep 11 00:22:12 smsl-n100 systemd[1]: Stopped Network Time Service. Sep 11 00:22:12 smsl-n100 systemd[1]: Starting Network Time Service... Sep 11 00:22:12 smsl-n100 ntpd[3478]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 11 00:22:12 smsl-n100 ntpd[3478]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 11 00:22:12 smsl-n100 systemd[1]: Started Network Time Service. Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: rebinding lease of 192.168.1.111 Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: if_sendraw: Network is down Sep 11 00:22:12 smsl-n100 ntpd[3484]: proto: precision = 1.166 usec (-20) Sep 11 00:22:12 smsl-n100 dhcpcd[610]: eth0: carrier lost Sep 11 00:22:12 smsl-n100 ntpd[3484]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 11 00:22:12 smsl-n100 ntpd[3484]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 11 00:22:12 smsl-n100 ntpd[3484]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 988 days ago Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listen and drop on 0 v6wildcard [::]:123 Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listen normally on 2 lo 127.0.0.1:123 Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listen normally on 3 wlan0 192.168.211.1:123 Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listen normally on 4 wlan0 169.254.240.112:123 Sep 11 00:22:12 smsl-n100 ntpd[3484]: Listening on routing socket on fd #21 for interface updates Sep 11 00:22:12 smsl-n100 ntpd[3484]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 11 00:22:12 smsl-n100 ntpd[3484]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 11 00:22:13 smsl-n100 volumio[2275]: info: Discovery: A device disappeared from network Sep 11 00:22:13 smsl-n100 ifplugd(eth0)[760]: Link beat lost. Sep 11 00:22:16 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 11 00:22:16 smsl-n100 dhcpcd[610]: eth0: carrier acquired Sep 11 00:22:16 smsl-n100 dhcpcd[610]: eth0: confile file changed, expiring leases Sep 11 00:22:16 smsl-n100 dhcpcd[610]: eth0: IAID 5b:be:2d:fe Sep 11 00:22:16 smsl-n100 dhcpcd[610]: eth0: soliciting an IPv6 router Sep 11 00:22:17 smsl-n100 dhcpcd[610]: eth0: rebinding lease of 192.168.1.111 Sep 11 00:22:17 smsl-n100 dhcpcd[610]: eth0: probing address 192.168.1.111/24 Sep 11 00:22:17 smsl-n100 volumio[2275]: info: Discovery: Started advertising with name: SMSL N100 Sep 11 00:22:17 smsl-n100 ifplugd(eth0)[760]: Link beat detected. Sep 11 00:22:18 smsl-n100 volumio[2275]: info: Reporting MCU Network Status: 0 Sep 11 00:22:18 smsl-n100 volumio[2275]: info: Volumio Network Manager: Network status updated: 0 Sep 11 00:22:18 smsl-n100 volumio[2275]: info: CoreStateMachine::pushState Sep 11 00:22:18 smsl-n100 volumio[2275]: info: CorePlayQueue::getTrack 0 Sep 11 00:22:18 smsl-n100 volumio[2275]: info: CoreCommandRouter::volumioPushState Sep 11 00:22:18 smsl-n100 volumio[2275]: error: Serial API: Failed to decode command: 100, message: undefined Sep 11 00:22:18 smsl-n100 volumio[2275]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 11 00:22:18 smsl-n100 volumio[2275]: error: Serial API: Failed to decode command: 100, message: undefined Sep 11 00:22:18 smsl-n100 volumio[2275]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 11 00:22:18 smsl-n100 volumio[2275]: error: Serial API: Failed to decode command: 0, message: undefined Sep 11 00:22:21 smsl-n100 dhcpcd[610]: eth0: leased 192.168.1.111 for 7200 seconds Sep 11 00:22:21 smsl-n100 dhcpcd[610]: eth0: adding route to 192.168.1.0/24 Sep 11 00:22:21 smsl-n100 dhcpcd[610]: eth0: adding default route via 192.168.1.1 Sep 11 00:22:21 smsl-n100 avahi-daemon[563]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.111. Sep 11 00:22:21 smsl-n100 dhcpcd[610]: wlan0: deleting default route Sep 11 00:22:21 smsl-n100 avahi-daemon[563]: New relevant interface eth0.IPv4 for mDNS. Sep 11 00:22:21 smsl-n100 avahi-daemon[563]: Registering new address record for 192.168.1.111 on eth0.IPv4. Sep 11 00:22:21 smsl-n100 dnsmasq[1698]: reading /etc/resolv.conf Sep 11 00:22:21 smsl-n100 dnsmasq[1698]: using nameserver 192.168.1.1#53 Sep 11 00:22:21 smsl-n100 dnsmasq[1698]: using nameserver 208.67.222.222#53 Sep 11 00:22:21 smsl-n100 dnsmasq[1698]: using nameserver 208.67.220.220#53 Sep 11 00:22:22 smsl-n100 ntpd[3484]: ntpd exiting on signal 15 (Terminated) Sep 11 00:22:22 smsl-n100 systemd[1]: Stopping Network Time Service... Sep 11 00:22:22 smsl-n100 systemd[1]: ntp.service: Succeeded. Sep 11 00:22:22 smsl-n100 systemd[1]: Stopped Network Time Service. Sep 11 00:22:22 smsl-n100 systemd[1]: Starting Network Time Service... Sep 11 00:22:22 smsl-n100 ntpd[3556]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 11 00:22:22 smsl-n100 ntpd[3556]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Sep 11 00:22:22 smsl-n100 systemd[1]: Started Network Time Service. Sep 11 00:22:22 smsl-n100 ntpd[3562]: proto: precision = 1.167 usec (-20) Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen and drop on 0 v6wildcard [::]:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen normally on 2 lo 127.0.0.1:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen normally on 3 eth0 192.168.1.111:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen normally on 4 wlan0 192.168.211.1:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listen normally on 5 wlan0 169.254.240.112:123 Sep 11 00:22:22 smsl-n100 ntpd[3562]: Listening on routing socket on fd #22 for interface updates Sep 11 00:22:22 smsl-n100 ntpd[3562]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 11 00:22:22 smsl-n100 ntpd[3562]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 11 00:22:22 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Sep 11 00:22:22 smsl-n100 volumio[2275]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Sep 11 00:22:22 smsl-n100 volumio[2275]: info: Refreshing Cached IP Addresses Sep 11 00:22:22 smsl-n100 sudo[3565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 00:22:22 smsl-n100 sudo[3565]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:22 smsl-n100 sudo[3565]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:22 smsl-n100 sudo[3568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 00:22:22 smsl-n100 sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 00:22:22 smsl-n100 sudo[3568]: pam_unix(sudo:session): session closed for user root Sep 11 00:22:22 smsl-n100 volumio[2275]: info: Discovery: adding 8a96d645-bd2a-400f-bf5a-fd6d503f9e34 Sep 11 00:22:22 smsl-n100 volumio[2275]: info: Discovery: Found device SMSL N100 Sep 11 00:22:22 smsl-n100 volumio[2275]: info: CoreCommandRouter::volumioGetState Sep 11 00:22:22 smsl-n100 volumio[2275]: info: CorePlayQueue::getTrack 0 Sep 11 00:22:22 smsl-n100 volumio[2275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 00:22:22 smsl-n100 volumio[2275]: Error: The value NaN is not a number Sep 11 00:22:22 smsl-n100 volumio[2275]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Sep 11 00:22:22 smsl-n100 volumio[2275]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Sep 11 00:22:22 smsl-n100 volumio[2275]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Sep 11 00:22:22 smsl-n100 volumio[2275]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Sep 11 00:22:22 smsl-n100 volumio[2275]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 11 00:22:22 smsl-n100 volumio[2275]: at Browser.emit (events.js:400:28) Sep 11 00:22:22 smsl-n100 volumio[2275]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 11 00:22:22 smsl-n100 volumio[2275]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 11 00:22:22 smsl-n100 volumio[2275]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 11 00:22:22 smsl-n100 volumio[2275]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 11 00:22:22 smsl-n100 volumio[2275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 00:22:23 smsl-n100 sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 00:21 Sep 11 00:22:23 smsl-n100 sudo[3579]: 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"