-- Logs begin at Sun 2025-11-02 18:12:55 UTC, end at Sun 2025-11-02 18:42:38 UTC. -- Nov 02 18:41:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Nov 02 18:41:07 smsl-n100 dhcpcd[932]: eth0: carrier lost Nov 02 18:41:07 smsl-n100 dhcpcd[948]: eth0: carrier lost Nov 02 18:41:07 smsl-n100 avahi-daemon[544]: Withdrawing address record for 192.168.0.60 on eth0. Nov 02 18:41:07 smsl-n100 avahi-daemon[544]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.60. Nov 02 18:41:07 smsl-n100 avahi-daemon[544]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 02 18:41:07 smsl-n100 dhcpcd[932]: eth0: deleting route to 192.168.0.0/24 Nov 02 18:41:07 smsl-n100 dhcpcd[932]: eth0: deleting default route via 192.168.0.1 Nov 02 18:41:07 smsl-n100 volumio[1091]: info: Discovery: A device disappeared from network Nov 02 18:41:07 smsl-n100 dhcpcd[948]: eth0: deleting route to 192.168.0.0/24 Nov 02 18:41:07 smsl-n100 dhcpcd[948]: eth0: deleting default route via 192.168.0.1 Nov 02 18:41:07 smsl-n100 ifplugd(eth0)[759]: Link beat lost. Nov 02 18:41:08 smsl-n100 ntpd[1080]: Deleting interface #3 eth0, 192.168.0.60#123, interface stats: received=176, sent=177, dropped=0, active_time=629 secs Nov 02 18:41:08 smsl-n100 ntpd[1080]: 89.161.47.136 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 156.17.20.254 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 94.240.10.55 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 194.146.251.101 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 54.37.233.160 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 89.161.47.131 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 178.215.228.24 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 192.86.14.67 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 193.59.134.156 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 162.159.200.123 local addr 192.168.0.60 -> Nov 02 18:41:08 smsl-n100 ntpd[1080]: 213.222.217.10 local addr 192.168.0.60 -> Nov 02 18:41:12 smsl-n100 volumio[1091]: info: Reporting MCU Network Status: 2 Nov 02 18:41:12 smsl-n100 volumio[1091]: info: Volumio Network Manager: Network status updated: 2 Nov 02 18:41:12 smsl-n100 volumio[1091]: info: CoreStateMachine::pushState Nov 02 18:41:12 smsl-n100 volumio[1091]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:12 smsl-n100 volumio[1091]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:12 smsl-n100 volumio[1091]: info: MRS: Pushing multiroomSync output update for this device Nov 02 18:41:12 smsl-n100 volumio[1091]: info: MRS: Pushing multiroomSync output Nov 02 18:41:12 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:41:12 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:41:12 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:41:12 smsl-n100 volumio[1091]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:41:12 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 2, message: undefined Nov 02 18:41:17 smsl-n100 ifplugd(eth0)[759]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Nov 02 18:41:17 smsl-n100 ifplugd(eth0)[759]: client: sending commands to master dhcpcd process Nov 02 18:41:17 smsl-n100 dhcpcd[3087]: sending commands to master dhcpcd process Nov 02 18:41:17 smsl-n100 dhcpcd[932]: control command: /sbin/dhcpcd -k eth0 Nov 02 18:41:17 smsl-n100 dhcpcd[932]: eth0: removing interface Nov 02 18:41:17 smsl-n100 ifplugd(eth0)[759]: Program executed successfully. Nov 02 18:41:17 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Nov 02 18:41:17 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Nov 02 18:41:17 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Nov 02 18:41:17 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Button was pressed for 5 seconds, starting hotspot Nov 02 18:41:22 smsl-n100 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Nov 02 18:41:22 smsl-n100 volumio[1091]: error: Forcing Hotspot mode Nov 02 18:41:22 smsl-n100 sudo[3121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Nov 02 18:41:22 smsl-n100 sudo[3121]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 smsl-n100 sudo[3121]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Permissions for /etc/hostapd/hostapd.conf Nov 02 18:41:22 smsl-n100 volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Discovery: Restarting Advertising due to device name change Nov 02 18:41:22 smsl-n100 sudo[3124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 02 18:41:22 smsl-n100 sudo[3124]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 smsl-n100 systemd[1]: Stopping Wireless Services... Nov 02 18:41:22 smsl-n100 wpa_supplicant[848]: wlan0: CTRL-EVENT-DISCONNECTED bssid=92:8c:b5:42:2d:77 reason=3 locally_generated=1 Nov 02 18:41:22 smsl-n100 dhcpcd[932]: wlan0: carrier lost Nov 02 18:41:22 smsl-n100 volumio[1091]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:41:22 smsl-n100 wpa_supplicant[848]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 02 18:41:22 smsl-n100 wpa_supplicant[848]: wlan0: CTRL-EVENT-TERMINATING Nov 02 18:41:22 smsl-n100 avahi-daemon[544]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 02 18:41:22 smsl-n100 avahi-daemon[544]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.9. Nov 02 18:41:22 smsl-n100 avahi-daemon[544]: Withdrawing address record for 192.168.0.9 on wlan0. Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Discovery: A device disappeared from network Nov 02 18:41:22 smsl-n100 systemd[1]: wireless.service: Succeeded. Nov 02 18:41:22 smsl-n100 systemd[1]: Stopped Wireless Services. Nov 02 18:41:22 smsl-n100 volumio[1091]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:41:22 smsl-n100 systemd[1]: Starting Wireless Services... Nov 02 18:41:22 smsl-n100 dhcpcd[932]: wlan0: deleting route to 192.168.0.0/24 Nov 02 18:41:22 smsl-n100 dhcpcd[932]: wlan0: deleting default route via 192.168.0.1 Nov 02 18:41:22 smsl-n100 volumio[1091]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Reporting MCU Network Status: 0 Nov 02 18:41:22 smsl-n100 volumio[1091]: info: Volumio Network Manager: Network status updated: 0 Nov 02 18:41:22 smsl-n100 volumio[1091]: info: CoreStateMachine::pushState Nov 02 18:41:22 smsl-n100 volumio[1091]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 smsl-n100 volumio[1091]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:22 smsl-n100 volumio[1091]: info: MRS: Pushing multiroomSync output update for this device Nov 02 18:41:22 smsl-n100 volumio[1091]: info: MRS: Pushing multiroomSync output Nov 02 18:41:22 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:41:22 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:41:22 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:41:22 smsl-n100 volumio[1091]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:41:22 smsl-n100 volumio[1091]: error: Serial API: Failed to decode command: 0, message: undefined Nov 02 18:41:23 smsl-n100 wireless.js[3139]: Cleaning previous... Nov 02 18:41:23 smsl-n100 sudo[3180]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 18:41:23 smsl-n100 sudo[3180]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:23 smsl-n100 sudo[3180]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 smsl-n100 sudo[3182]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 18:41:23 smsl-n100 sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:23 smsl-n100 sudo[3182]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 smsl-n100 wireless.js[3139]: Stopped aP Nov 02 18:41:23 smsl-n100 sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 18:41:23 smsl-n100 sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:23 smsl-n100 dhcpcd[932]: wlan0: carrier acquired Nov 02 18:41:23 smsl-n100 sudo[3189]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 smsl-n100 dhcpcd[932]: wlan0: IAID 2b:ee:c9:b8 Nov 02 18:41:23 smsl-n100 sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 02 18:41:23 smsl-n100 sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:23 smsl-n100 sudo[3192]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 smsl-n100 sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 18:41:23 smsl-n100 sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:23 smsl-n100 sudo[3199]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 smsl-n100 sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 02 18:41:23 smsl-n100 sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:24 smsl-n100 dhcpcd[932]: wlan0: rebinding lease of 192.168.0.9 Nov 02 18:41:24 smsl-n100 dhcpcd[932]: wlan0: soliciting an IPv6 router Nov 02 18:41:24 smsl-n100 ntpd[1080]: Deleting interface #4 wlan0, 192.168.0.9#123, interface stats: received=33, sent=33, dropped=0, active_time=645 secs Nov 02 18:41:24 smsl-n100 ntpd[1080]: 213.222.217.10 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 162.159.200.123 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 193.59.134.156 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 192.86.14.67 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 178.215.228.24 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 89.161.47.131 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 54.37.233.160 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 194.146.251.101 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 94.240.10.55 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 156.17.20.254 local addr 192.168.0.9 -> Nov 02 18:41:24 smsl-n100 ntpd[1080]: 89.161.47.136 local addr 192.168.0.9 -> Nov 02 18:41:27 smsl-n100 volumio[1091]: info: Discovery: Started advertising with name: SMSL N100 Nov 02 18:41:28 smsl-n100 sudo[3201]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:28 smsl-n100 wireless.js[3139]: Wireless networking forced to hotspot mode Nov 02 18:41:28 smsl-n100 sudo[3209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 18:41:28 smsl-n100 sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:28 smsl-n100 sudo[3209]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:28 smsl-n100 sudo[3211]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 18:41:28 smsl-n100 sudo[3211]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:28 smsl-n100 dhcpcd[932]: wlan0: carrier lost Nov 02 18:41:28 smsl-n100 sudo[3211]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:28 smsl-n100 wireless.js[3139]: Starting Force Hotspot Nov 02 18:41:28 smsl-n100 avahi-daemon[544]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 02 18:41:28 smsl-n100 avahi-daemon[544]: New relevant interface wlan0.IPv4 for mDNS. Nov 02 18:41:28 smsl-n100 avahi-daemon[544]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Nov 02 18:41:28 smsl-n100 dhcpcd[932]: wlan0: carrier acquired Nov 02 18:41:28 smsl-n100 dhcpcd[932]: wlan0: IAID 2b:ee:c9:b8 Nov 02 18:41:28 smsl-n100 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Nov 02 18:41:28 smsl-n100 volumio[1091]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:41:28 smsl-n100 dhcpcd[932]: wlan0: soliciting an IPv6 router Nov 02 18:41:28 smsl-n100 hostapd[3233]: Configuration file: /etc/hostapd/hostapd.conf Nov 02 18:41:28 smsl-n100 dhcpcd[932]: wlan0: rebinding lease of 192.168.0.9 Nov 02 18:41:29 smsl-n100 volumio[1091]: info: Discovery: adding a8934c21-5cf2-4340-b295-fcf288d7b59e Nov 02 18:41:29 smsl-n100 volumio[1091]: info: Discovery: Found device SMSL N100 Nov 02 18:41:29 smsl-n100 volumio[1091]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:29 smsl-n100 volumio[1091]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:29 smsl-n100 volumio[1091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:41:29 smsl-n100 volumio[1091]: Error: The value NaN is not a number Nov 02 18:41:29 smsl-n100 volumio[1091]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 02 18:41:29 smsl-n100 volumio[1091]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 02 18:41:29 smsl-n100 volumio[1091]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 02 18:41:29 smsl-n100 volumio[1091]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 02 18:41:29 smsl-n100 volumio[1091]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 02 18:41:29 smsl-n100 volumio[1091]: at Browser.emit (events.js:400:28) Nov 02 18:41:29 smsl-n100 volumio[1091]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 02 18:41:29 smsl-n100 volumio[1091]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 02 18:41:29 smsl-n100 volumio[1091]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 02 18:41:29 smsl-n100 volumio[1091]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 02 18:41:29 smsl-n100 volumio[1091]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 02 18:41:29 smsl-n100 volumio[1091]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 02 18:41:29 smsl-n100 volumio[1091]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 02 18:41:29 smsl-n100 volumio[1091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:41:29 smsl-n100 hostapd[3233]: Using interface wlan0 with hwaddr 28:f5:2b:ee:c9:b8 and ssid "Volumio-F88EE" Nov 02 18:41:29 smsl-n100 hostapd[3233]: wlan0: interface state UNINITIALIZED->ENABLED Nov 02 18:41:29 smsl-n100 hostapd[3233]: wlan0: AP-ENABLED Nov 02 18:41:29 smsl-n100 ntpd[1080]: Listen normally on 5 wlan0 192.168.211.1:123 Nov 02 18:41:29 smsl-n100 ntpd[1080]: new interface(s) found: waking up resolver Nov 02 18:41:29 smsl-n100 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Nov 02 18:41:29 smsl-n100 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Nov 02 18:41:29 smsl-n100 dnsmasq[3246]: dnsmasq: syntax check OK. Nov 02 18:41:29 smsl-n100 iwconfig[3248]: Error for wireless request "Set Power Management" (8B2C) : Nov 02 18:41:29 smsl-n100 iwconfig[3248]: SET failed on device wlan0 ; Operation not permitted. Nov 02 18:41:29 smsl-n100 systemd[1]: wireless.service: Succeeded. Nov 02 18:41:29 smsl-n100 systemd[1]: Started Wireless Services. Nov 02 18:41:29 smsl-n100 sudo[3124]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: started, version 2.80 cachesize 150 Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Nov 02 18:41:30 smsl-n100 dnsmasq-dhcp[3255]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: reading /etc/resolv.conf Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: using nameserver 208.67.222.222#53 Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: using nameserver 208.67.220.220#53 Nov 02 18:41:30 smsl-n100 dnsmasq[3255]: read /etc/hosts - 5 addresses Nov 02 18:41:30 smsl-n100 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Nov 02 18:41:30 smsl-n100 systemd[1]: Reached target Host and Network Name Lookups. Nov 02 18:41:30 smsl-n100 sudo[3262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 18:40 Nov 02 18:41:30 smsl-n100 sudo[3262]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:30 smsl-n100 sudo[3262]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:30 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:41:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 18:41:30 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:41:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 18:41:30 smsl-n100 vtcs[1510]: [2025-11-02 18:41:30.720] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 02 18:41:30 smsl-n100 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 18:41:30 smsl-n100 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 18:41:30 smsl-n100 systemd[1]: Started dynamicswap service. Nov 02 18:41:30 smsl-n100 systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:41:31 smsl-n100 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 18:41:31 smsl-n100 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 02 18:41:31 smsl-n100 systemd[1]: Stopped Volumio Backend Module. Nov 02 18:41:31 smsl-n100 systemd[1]: Started Volumio Backend Module. Nov 02 18:41:31 smsl-n100 systemd[1]: Started dynamicswap service. Nov 02 18:41:31 smsl-n100 systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:41:33 smsl-n100 dhcpcd[932]: wlan0: probing for an IPv4LL address Nov 02 18:41:33 smsl-n100 dhcpcd[932]: wlan0: DHCP lease expired Nov 02 18:41:33 smsl-n100 dhcpcd[932]: wlan0: soliciting a DHCP lease Nov 02 18:41:34 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:41:34 smsl-n100 volumio[3277]: info: ----- Volumio3 ---- Nov 02 18:41:34 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:41:34 smsl-n100 volumio[3277]: info: ----- System startup ---- Nov 02 18:41:34 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:41:35 smsl-n100 volumio[3277]: info: MYVOLUMIO Environment detected Nov 02 18:41:35 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:41:35] [connect] Successful connection Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Plugin folders cleanup Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning into folder /volumio/app/plugins/ Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category audio_interface Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category miscellanea Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category music_service Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category plugins.json Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category system_controller Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning category user_interface Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Scanning into folder /data/plugins/ Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Plugin folders cleanup completed Nov 02 18:41:35 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:41:35 smsl-n100 volumio[3277]: info: ----- Core plugins startup ---- Nov 02 18:41:35 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin upnp to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin raat to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Loading plugins from folder /data/plugins/ Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Loading plugin "system"... Nov 02 18:41:35 smsl-n100 volumio[3277]: info: Loading plugin "appearance"... Nov 02 18:41:37 smsl-n100 volumio[3277]: info: Loading plugin "network"... Nov 02 18:41:37 smsl-n100 volumio[3277]: info: Refreshing Cached IP Addresses Nov 02 18:41:37 smsl-n100 sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:41:37 smsl-n100 sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:37 smsl-n100 sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:41:37 smsl-n100 sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:37 smsl-n100 sudo[3311]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:38 smsl-n100 sudo[3313]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "services"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "alsa_controller"... Nov 02 18:41:38 smsl-n100 sudo[3322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 18:41:38 smsl-n100 sudo[3322]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:38 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "wizard"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "networkfs"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Starting Udev Watcher for removable devices Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Ignoring mount for partition: boot Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Ignoring mount for partition: volumio Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Ignoring mount for partition: volumio_data Nov 02 18:41:38 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "volumio_command_line_client"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "upnp"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: [1762108898312] Starting Upmpd Daemon Nov 02 18:41:38 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "my_music"... Nov 02 18:41:38 smsl-n100 volumio[3277]: info: Loading plugin "mpd"... Nov 02 18:41:38 smsl-n100 ntpd[1080]: Soliciting pool server 212.127.78.21 Nov 02 18:41:39 smsl-n100 volumio[3277]: info: Loading plugin "upnp_browser"... Nov 02 18:41:39 smsl-n100 dhcpcd[932]: wlan0: using IPv4LL address 169.254.78.250 Nov 02 18:41:39 smsl-n100 dhcpcd[932]: wlan0: adding route to 169.254.0.0/16 Nov 02 18:41:39 smsl-n100 dhcpcd[932]: wlan0: adding default route Nov 02 18:41:39 smsl-n100 avahi-daemon[544]: Registering new address record for 169.254.78.250 on wlan0.IPv4. Nov 02 18:41:39 smsl-n100 volumio[3277]: info: Loading plugin "bluetooth"... Nov 02 18:41:40 smsl-n100 volumio[3277]: info: [1762108900045] Starting BluetoothController Nov 02 18:41:40 smsl-n100 volumio[3277]: info: Loading plugin "alarm-clock"... Nov 02 18:41:40 smsl-n100 volumio[3277]: info: Loading plugin "manifestui"... Nov 02 18:41:40 smsl-n100 ntpd[1080]: Listen normally on 6 wlan0 169.254.78.250:123 Nov 02 18:41:40 smsl-n100 ntpd[1080]: new interface(s) found: waking up resolver Nov 02 18:41:40 smsl-n100 volumio[3277]: info: Loading plugin "metavolumio"... Nov 02 18:41:42 smsl-n100 sudo[3322]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:43 smsl-n100 volumio[3277]: info: Loading plugin "airplay_emulation"... Nov 02 18:41:43 smsl-n100 volumio[3277]: info: Starting Shairport Sync Nov 02 18:41:43 smsl-n100 volumio[3277]: info: Loading plugin "cd_controller"... Nov 02 18:41:43 smsl-n100 volumio[3277]: info: Loading plugin "last_100"... Nov 02 18:41:43 smsl-n100 volumio[3277]: info: Loading plugin "raat"... Nov 02 18:41:43 smsl-n100 volumio[3277]: info: RAAT Plugin loaded Nov 02 18:41:44 smsl-n100 volumio[3277]: info: Adding restartRAATSocket REST API Endpoint Nov 02 18:41:44 smsl-n100 volumio[3277]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Nov 02 18:41:44 smsl-n100 volumio[3277]: info: Loading plugin "streaming_services"... Nov 02 18:41:45 smsl-n100 volumio[3277]: info: Starting Streaming Service Transparent Proxy Nov 02 18:41:45 smsl-n100 volumio[3277]: info: Loading plugin "tidalconnect"... Nov 02 18:41:45 smsl-n100 volumio[3277]: info: Loading plugin "webradio"... Nov 02 18:41:46 smsl-n100 volumio[3277]: info: Loading plugin "i2s_dacs"... Nov 02 18:41:46 smsl-n100 volumio[3277]: info: I2S DAC not set, start Auto-detection Nov 02 18:41:46 smsl-n100 volumio[3277]: info: Loading plugin "volumiodiscovery"... Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** For more information see Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:46 smsl-n100 volumio[3277]: *** WARNING *** For more information see Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** For more information see Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:46 smsl-n100 node[3277]: *** WARNING *** For more information see Nov 02 18:41:46 smsl-n100 volumio[3277]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 18:41:46 smsl-n100 volumio[3277]: info: Discovery: Started advertising with name: SMSL N100 Nov 02 18:41:46 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:46 smsl-n100 volumio[3277]: info: Loading plugin "multiroom"... Nov 02 18:41:47 smsl-n100 volumio[3277]: STREAMING PROXY: Starting server on port 3245 Nov 02 18:41:47 smsl-n100 volumio[3277]: Node JS runtime: 14 Nov 02 18:41:48 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:41:48] [connect] Successful connection Nov 02 18:41:49 smsl-n100 volumio[3277]: info: Applying required configuration parameters for plugin multiroom Nov 02 18:41:49 smsl-n100 sudo[3373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 02 18:41:49 smsl-n100 sudo[3373]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:49 smsl-n100 sudo[3373]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 smsl-n100 volumio[3277]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Nov 02 18:41:49 smsl-n100 volumio[3277]: info: MRS: MultiRoom plugin initialized Nov 02 18:41:49 smsl-n100 volumio[3277]: info: MRS: STOPPING SNAPCLIENT Nov 02 18:41:49 smsl-n100 volumio[3277]: info: MRS: Snap server stop Nov 02 18:41:49 smsl-n100 sudo[3389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 02 18:41:49 smsl-n100 sudo[3389]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:49 smsl-n100 volumio[3277]: info: MRS: STOPPING volumioStreaming Nov 02 18:41:49 smsl-n100 sudo[3392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 02 18:41:49 smsl-n100 sudo[3392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:49 smsl-n100 sudo[3389]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 smsl-n100 sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 02 18:41:49 smsl-n100 sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:49 smsl-n100 volumio[3277]: info: Loading plugin "outputs"... Nov 02 18:41:49 smsl-n100 volumio[3277]: info: Loading plugin "albumart"... Nov 02 18:41:49 smsl-n100 sudo[3397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 02 18:41:49 smsl-n100 sudo[3392]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 smsl-n100 sudo[3397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:49 smsl-n100 sudo[3397]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 smsl-n100 sudo[3395]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 smsl-n100 volumio[3277]: info: Plugin example_plugin is not enabled Nov 02 18:41:49 smsl-n100 volumio[3277]: info: Loading plugin "hi_res_audio"... Nov 02 18:41:50 smsl-n100 volumio[3277]: Forking 3 albumart workers Nov 02 18:41:52 smsl-n100 volumio[3277]: Starting albumart workers Nov 02 18:41:52 smsl-n100 volumio[3277]: Starting albumart workers Nov 02 18:41:52 smsl-n100 volumio[3277]: info: Applying required configuration parameters for plugin hi_res_audio Nov 02 18:41:52 smsl-n100 volumio[3277]: info: Loading plugin "inputs"... Nov 02 18:41:52 smsl-n100 volumio[3277]: Starting albumart workers Nov 02 18:41:54 smsl-n100 volumio[3277]: info: Loading plugin "qobuz"... Nov 02 18:41:54 smsl-n100 hostapd[3245]: wlan0: STA 8a:1a:86:a1:5d:ee IEEE 802.11: associated Nov 02 18:41:54 smsl-n100 hostapd[3245]: wlan0: STA 8a:1a:86:a1:5d:ee RADIUS: starting accounting session 6776701833CBCF59 Nov 02 18:41:54 smsl-n100 ntpd[1080]: Soliciting pool server 2a0d:5440::24 Nov 02 18:41:56 smsl-n100 volumio[3277]: info: Loading plugin "smart_inputs"... Nov 02 18:41:56 smsl-n100 volumio[3277]: info: Loading plugin "tidal"... Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPDISCOVER(wlan0) 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPOFFER(wlan0) 192.168.211.146 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPDISCOVER(wlan0) 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPOFFER(wlan0) 192.168.211.146 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPDISCOVER(wlan0) 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPOFFER(wlan0) 192.168.211.146 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPREQUEST(wlan0) 192.168.211.146 8a:1a:86:a1:5d:ee Nov 02 18:41:57 smsl-n100 dnsmasq-dhcp[3255]: DHCPACK(wlan0) 192.168.211.146 8a:1a:86:a1:5d:ee Galaxy-S24 Nov 02 18:41:58 smsl-n100 volumio[3277]: info: Loading plugin "oem_helper"... Nov 02 18:41:59 smsl-n100 ntpd[1080]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Applying required configuration parameters for plugin oem_helper Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Loading plugin "smslcontrol"... Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Adding this device properties Nov 02 18:42:00 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Setting Additional Device Volumio Properties: [object Object] Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Loading plugin "updater_comm"... Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Plugin mpdemulation is not enabled Nov 02 18:42:00 smsl-n100 volumio[3277]: info: Loading plugin "rest_api"... Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Loading plugin "websocket"... Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Starting Socket.io Server version 2.3.0 Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Loading i18n strings for locale en Nov 02 18:42:01 smsl-n100 volumio[3277]: Updating browse sources language Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::initPlayerControls Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:01 smsl-n100 volumio[3277]: Express server listening on port 3000 Nov 02 18:42:01 smsl-n100 volumio[3277]: [Metrics] WebUI: 28s 253.30ms Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreStateMachine::resetVolumioState Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreStateMachine::getcurrentVolume Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:01 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:01 smsl-n100 sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:42:01 smsl-n100 sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:01 smsl-n100 sudo[3484]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:01 smsl-n100 sudo[3487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:01 smsl-n100 sudo[3487]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:01 smsl-n100 volumio[3277]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 02 18:42:01 smsl-n100 sudo[3487]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Completed loading Core Plugins Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Preparing to generate the ALSA configuration file Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Cannot read play queue from file Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Volumio Network Manager: Network status updated: 0 Nov 02 18:42:01 smsl-n100 volumio[3277]: info: MRS: Removed streaming files Nov 02 18:42:01 smsl-n100 volumio[3277]: info: MRS: volumioStreaming STOPPED Nov 02 18:42:01 smsl-n100 volumio[3277]: info: MRS: SNAPSERVER STOPPED Nov 02 18:42:01 smsl-n100 volumio[3277]: info: MRS: SNAPCLIENT STOPPED Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 02 18:42:01 smsl-n100 volumio[3277]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 02 18:42:01 smsl-n100 volumio[3277]: info: Reading ALSA contributions from plugins. Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Discovery: Getting this device information Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Discovery: Getting this device information Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreStateMachine::setRepeat null single undefined Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreStateMachine::setRandom null Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Setting Device type: NanoPi NEO 3 Nov 02 18:42:02 smsl-n100 volumio[3277]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.146 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 18:42:02 smsl-n100 sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 18:42:02 smsl-n100 sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Output device has changed, restarting MPD Nov 02 18:42:02 smsl-n100 sudo[3495]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:02 smsl-n100 sudo[3498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:42:02 smsl-n100 sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:02 smsl-n100 sudo[3498]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:02 smsl-n100 volumio[3277]: info: Output device has changed, restarting Shairport Sync Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:02 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:02 smsl-n100 sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:02 smsl-n100 sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:02 smsl-n100 systemd[1]: Stopping Music Player Daemon... Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:03 smsl-n100 systemd[1]: mpd.service: Succeeded. Nov 02 18:42:03 smsl-n100 volumio[3277]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 18:42:03 smsl-n100 systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:03 smsl-n100 systemd[1]: Starting Music Player Daemon... Nov 02 18:42:03 smsl-n100 volumio[3277]: info: ___________ START PLUGINS ___________ Nov 02 18:42:03 smsl-n100 volumio[3277]: info: ControllerMpd::onStart: Initializing MPD Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Creating MPD Configuration file Nov 02 18:42:03 smsl-n100 sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:42:03 smsl-n100 sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:03 smsl-n100 sudo[3510]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:03 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:42:03] [connect] Successful connection Nov 02 18:42:03 smsl-n100 volumio[3277]: info: [1762108923231] CoreMusicLibrary::Adding element Media Servers Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:03 smsl-n100 sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:03 smsl-n100 sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:42:03 smsl-n100 sudo[3506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:03 smsl-n100 sudo[3506]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 sudo[3506]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 18:42:03 smsl-n100 systemd[1]: mpd.service: Succeeded. Nov 02 18:42:03 smsl-n100 systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:03 smsl-n100 systemd[1]: Starting Music Player Daemon... Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding METAVOLUMIO REST API Endpoints Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:03 smsl-n100 sudo[3517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Nov 02 18:42:03 smsl-n100 sudo[3517]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 sudo[3517]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:03 smsl-n100 sudo[3516]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:03 smsl-n100 sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 sudo[3516]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Preparing CD Folders Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding CD REST API Endpoints Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Starting UDEV Watcher for CD Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Detecting CD presence with UDEV Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:03 smsl-n100 volumio[3277]: info: [1762108923669] CoreMusicLibrary::Adding element Last_100 Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Starting RAAT Plugin Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Additional UI Settings Added for plugin music_service/raat Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Registering DSP Elements listener and retrieving current ones Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Additional DSP elements updated Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Updating RAAT Signal Path Nov 02 18:42:03 smsl-n100 volumio[3277]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Streaming services startup Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Starting Streaming Daemon Nov 02 18:42:03 smsl-n100 sudo[3528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 18:42:03 smsl-n100 sudo[3528]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 sudo[3528]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:03 smsl-n100 volumio[3277]: info: [1762108923927] CoreMusicLibrary::Adding element Webradio Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:03 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:03 smsl-n100 sudo[3535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 18:42:03 smsl-n100 sudo[3535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 smsl-n100 volumio[3277]: info: Initializing BBC Radios Nov 02 18:42:04 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Nov 02 18:42:04 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 02 18:42:04 smsl-n100 systemd[1]: vtcs.service: Succeeded. Nov 02 18:42:04 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Nov 02 18:42:04 smsl-n100 sudo[3535]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 02 18:42:04 smsl-n100 volumio[3277]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:04 smsl-n100 volumio[3277]: error: Hi Res Audio Failed Login: Missing Login Data Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Initializing Serial Communication on port /dev/ttyS1 Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Touch Event Listener Process Starting Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Adding inputs REST Endpoints Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Scanning Audio Inputs Nov 02 18:42:04 smsl-n100 sudo[3552]: pam_unix(sudo:auth): conversation failed Nov 02 18:42:04 smsl-n100 sudo[3552]: pam_unix(sudo:auth): auth could not identify password for [volumio] Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Checking against Known Cards name Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Adding Server instance for streaming Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:04 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Initializing GPIOs Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Starting Button Listener Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Turning off Green LED Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Tuning on Blue LED Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Signaling System Ready Nov 02 18:42:04 smsl-n100 volumio[3277]: info: Volumio Calling Home Nov 02 18:42:04 smsl-n100 volumio[3277]: (node:3277) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Nov 02 18:42:04 smsl-n100 volumio[3277]: (Use `node --trace-deprecation ...` to show where the warning was created) Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Stopping AccessToken refresher cron for QOBUZ Nov 02 18:42:05 smsl-n100 volumio[3277]: info: AccessToken refresher cron started for QOBUZ Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Stopping AccessToken refresher cron Nov 02 18:42:05 smsl-n100 volumio[3277]: info: AccessToken refresher cron started Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Adding TIDAL REST API Endpoints Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Adding QOBUZ REST API Endpoints Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Serial port opened successfully Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Sending serial start messages Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Reporting MCU Network Status: 0 Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Touch Event Listener Process Closed Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Cannot start Volumio Streaming Daemon Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 18:42:05 smsl-n100 volumio[3277]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Nov 02 18:42:05 smsl-n100 volumio[3277]: Can't get device info: No such device Nov 02 18:42:05 smsl-n100 volumio[3277]: info: MPD Permissions set Nov 02 18:42:05 smsl-n100 volumio[3277]: info: MPD Permissions set Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Upmpdcli Daemon Started Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Setting Device Volume Override Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 18:42:05 smsl-n100 volumio[3277]: info: Disabling external Volume Control Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:05 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:05 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:05 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:05 smsl-n100 volumio-remote-updater[541]: [2025-11-02 18:42:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762108923 101 Nov 02 18:42:05 smsl-n100 volumio[3277]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 0, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:06 smsl-n100 volumio[3277]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:06 smsl-n100 sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 02 18:42:06 smsl-n100 sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:06 smsl-n100 sudo[3583]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:06 smsl-n100 volumio[3277]: info: Starting Shairport Sync Nov 02 18:42:06 smsl-n100 volumio[3277]: info: Starting Shairport Sync Nov 02 18:42:06 smsl-n100 sudo[3586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:06 smsl-n100 sudo[3586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:06 smsl-n100 volumio[3277]: info: Starting Shairport Sync Nov 02 18:42:06 smsl-n100 sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:06 smsl-n100 sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:06 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:06 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:06 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:06 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:06 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:07 smsl-n100 sudo[3592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:07 smsl-n100 sudo[3592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:07 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:07 smsl-n100 sudo[3586]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:07 smsl-n100 sudo[3589]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:07 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:07 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 18:42:07 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:07 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:07 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:07 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:07 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:07 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:07 smsl-n100 sudo[3592]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Volumio BT Module successfully started Nov 02 18:42:07 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:07 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Shairport-Sync Started Nov 02 18:42:07 smsl-n100 volumio[3277]: Error adding Membership: Error: addMembership EINVAL Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Shairport-Sync Started Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Shairport-Sync Started Nov 02 18:42:07 smsl-n100 volumio[3277]: info: TidalConnect service stoped! Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 18:42:07 smsl-n100 volumio[3277]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 18:42:07 smsl-n100 sudo[3607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 18:42:07 smsl-n100 sudo[3607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:07 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Nov 02 18:42:07 smsl-n100 sudo[3607]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 smsl-n100 volumio[3277]: info: Executing endpoint tc_getconfig Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 18:42:08 smsl-n100 vtcs[3609]: STARTING TidalConnect services, version: 1.4.0.34 Nov 02 18:42:08 smsl-n100 vtcs[3609]: STARTED TidalConnect services. Nov 02 18:42:08 smsl-n100 volumio[3277]: info: Executing endpoint tc_connect Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 18:42:08 smsl-n100 volumio[3277]: info: Connecting to TidalConnect Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::servicePushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 mpd[3526]: Nov 02 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 02 18:42:08 smsl-n100 volumio[3277]: verbose: CURRENT POSITION 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::syncState stateService stop Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::syncState currentStatus stop Nov 02 18:42:08 smsl-n100 systemd[1]: Started Music Player Daemon. Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 sudo[3513]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 smsl-n100 sudo[3501]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 smsl-n100 volumio[3277]: info: No code Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::servicePushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 02 18:42:08 smsl-n100 volumio[3277]: verbose: CURRENT POSITION 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::syncState stateService stop Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::syncState currentStatus stop Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: No code Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:08 smsl-n100 volumio[3277]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:42:08 smsl-n100 volumio[3277]: assert.ok(self.idling) Nov 02 18:42:08 smsl-n100 volumio[3277]: error: The expression evaluated to a falsy value: Nov 02 18:42:08 smsl-n100 volumio[3277]: assert.ok(self.idling) Nov 02 18:42:08 smsl-n100 volumio[3277]: error: updateQueue error: null Nov 02 18:42:08 smsl-n100 volumio[3277]: info: MPD running with PID3526 Nov 02 18:42:08 smsl-n100 volumio[3277]: ,establishing connection Nov 02 18:42:08 smsl-n100 volumio[3277]: error: updateQueue error: null Nov 02 18:42:09 smsl-n100 volumio[3277]: info: MRS: Getting audio outputs on start Nov 02 18:42:09 smsl-n100 volumio[3277]: info: MRS: Requesting all other devices output Nov 02 18:42:10 smsl-n100 volumio[3277]: info: TidalConnect service started! Nov 02 18:42:10 smsl-n100 volumio[3277]: info: Completed starting Core Plugins Nov 02 18:42:10 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:42:10 smsl-n100 volumio[3277]: info: ----- MyVolumio plugins startup ---- Nov 02 18:42:10 smsl-n100 volumio[3277]: info: ------------------------------------------- Nov 02 18:42:10 smsl-n100 volumio[3277]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 18:42:13 smsl-n100 volumio[3277]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 18:42:18 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Nov 02 18:42:18 smsl-n100 volumio[3277]: info: Saving new wireless network Nov 02 18:42:18 smsl-n100 sudo[3703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Nov 02 18:42:19 smsl-n100 sudo[3703]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:19 smsl-n100 sudo[3703]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:19 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 02 18:42:19 smsl-n100 volumio[3277]: info: Discovery: Restarting Advertising due to device name change Nov 02 18:42:19 smsl-n100 sudo[3706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 02 18:42:19 smsl-n100 sudo[3706]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:19 smsl-n100 systemd[1]: Starting Wireless Services... Nov 02 18:42:19 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:19 smsl-n100 ntpd[1080]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 02 18:42:20 smsl-n100 volumio[3277]: info: Discovery: A device disappeared from network Nov 02 18:42:20 smsl-n100 volumio[3277]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 02 18:42:20 smsl-n100 wireless.js[3708]: Cleaning previous... Nov 02 18:42:20 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Nov 02 18:42:20 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:20 smsl-n100 dnsmasq[3255]: exiting on receipt of SIGTERM Nov 02 18:42:20 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Nov 02 18:42:20 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Nov 02 18:42:20 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Nov 02 18:42:20 smsl-n100 dhcpcd[932]: wlan0: carrier lost Nov 02 18:42:20 smsl-n100 systemd[1]: hostapd.service: Succeeded. Nov 02 18:42:20 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Nov 02 18:42:20 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:20 smsl-n100 dhcpcd[932]: wlan0: deleting route to 169.254.0.0/16 Nov 02 18:42:20 smsl-n100 dhcpcd[932]: wlan0: deleting default route Nov 02 18:42:20 smsl-n100 avahi-daemon[544]: Withdrawing address record for 169.254.78.250 on wlan0. Nov 02 18:42:20 smsl-n100 sudo[3741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 18:42:20 smsl-n100 sudo[3741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 avahi-daemon[544]: Withdrawing address record for 192.168.211.1 on wlan0. Nov 02 18:42:20 smsl-n100 avahi-daemon[544]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 02 18:42:20 smsl-n100 avahi-daemon[544]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 02 18:42:20 smsl-n100 sudo[3741]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 smsl-n100 sudo[3752]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 18:42:20 smsl-n100 sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 sudo[3752]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 smsl-n100 volumio[3277]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 02 18:42:20 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:20 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:20 smsl-n100 wireless.js[3708]: Stopped aP Nov 02 18:42:20 smsl-n100 sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 18:42:20 smsl-n100 sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 sudo[3759]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 smsl-n100 sudo[3761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 02 18:42:20 smsl-n100 sudo[3761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 sudo[3761]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 smsl-n100 sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 18:42:20 smsl-n100 sudo[3768]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 sudo[3768]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 smsl-n100 sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 02 18:42:20 smsl-n100 sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:20 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:21 smsl-n100 volumio[3277]: info: CoreStateMachine::pushState Nov 02 18:42:21 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:21 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:21 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:21 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 18:42:21 smsl-n100 volumio[3277]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 18:42:21 smsl-n100 volumio[3277]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 18:42:21 smsl-n100 dhcpcd[932]: wlan0: carrier acquired Nov 02 18:42:21 smsl-n100 dhcpcd[932]: wlan0: IAID 2b:ee:c9:b8 Nov 02 18:42:21 smsl-n100 dhcpcd[932]: wlan0: soliciting a DHCP lease Nov 02 18:42:21 smsl-n100 dhcpcd[932]: wlan0: soliciting an IPv6 router Nov 02 18:42:22 smsl-n100 ntpd[1080]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=53 secs Nov 02 18:42:22 smsl-n100 ntpd[1080]: Deleting interface #6 wlan0, 169.254.78.250#123, interface stats: received=0, sent=11, dropped=0, active_time=42 secs Nov 02 18:42:22 smsl-n100 ntpd[1080]: 213.222.217.10 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 162.159.200.123 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 193.59.134.156 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 192.86.14.67 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 178.215.228.24 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 89.161.47.131 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 54.37.233.160 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 194.146.251.101 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 94.240.10.55 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 156.17.20.254 local addr 169.254.78.250 -> Nov 02 18:42:22 smsl-n100 ntpd[1080]: 89.161.47.136 local addr 169.254.78.250 -> Nov 02 18:42:24 smsl-n100 volumio[3277]: info: Discovery: Started advertising with name: SMSL N100 Nov 02 18:42:25 smsl-n100 sudo[3770]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:25 smsl-n100 wireless.js[3708]: Start wireless flow Nov 02 18:42:25 smsl-n100 wireless.js[3708]: Stopped hotspot (if there).. Nov 02 18:42:25 smsl-n100 sudo[3777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 18:42:25 smsl-n100 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:25 smsl-n100 sudo[3777]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:25 smsl-n100 sudo[3779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 18:42:25 smsl-n100 sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:25 smsl-n100 dhcpcd[932]: wlan0: carrier lost Nov 02 18:42:25 smsl-n100 sudo[3779]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:25 smsl-n100 wireless.js[3708]: DHCP IP Nov 02 18:42:25 smsl-n100 wireless.js[3708]: Start ap Nov 02 18:42:25 smsl-n100 wpa_supplicant[3784]: Successfully initialized wpa_supplicant Nov 02 18:42:25 smsl-n100 sudo[3788]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 02 18:42:25 smsl-n100 sudo[3788]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:25 smsl-n100 dhcpcd[932]: wlan0: carrier acquired Nov 02 18:42:25 smsl-n100 dhcpcd[3797]: sending commands to master dhcpcd process Nov 02 18:42:25 smsl-n100 sudo[3788]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:25 smsl-n100 dhcpcd[932]: wlan0: IAID 2b:ee:c9:b8 Nov 02 18:42:25 smsl-n100 dhcpcd[932]: wlan0: carrier lost Nov 02 18:42:25 smsl-n100 dhcpcd[932]: control command: /sbin/dhcpcd Nov 02 18:42:26 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:26 smsl-n100 sudo[3808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:26 smsl-n100 sudo[3808]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:26 smsl-n100 sudo[3808]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:27 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:27 smsl-n100 sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:27 smsl-n100 sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:27 smsl-n100 sudo[3812]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:27 smsl-n100 volumio[3277]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Nov 02 18:42:28 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:28 smsl-n100 sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:28 smsl-n100 sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:28 smsl-n100 sudo[3829]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:29 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:29 smsl-n100 sudo[3832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:29 smsl-n100 sudo[3832]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:29 smsl-n100 sudo[3832]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:30 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:30 smsl-n100 sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:30 smsl-n100 sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:30 smsl-n100 sudo[3838]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:30 smsl-n100 volumio[3277]: error: MyVolumio Plugin failed to start in a timely fashion Nov 02 18:42:30 smsl-n100 volumio[3277]: [Metrics] CommandRouter: 56s 395.07ms Nov 02 18:42:30 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 18:42:30 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:30 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:30 smsl-n100 volumio[3277]: info: CoreCommandRouter::Close All Modals sent Nov 02 18:42:30 smsl-n100 volumio[3277]: info: CoreCommandRouter::Close All Modals sent Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: Trying to associate with 92:8c:b5:42:2d:77 (SSID='Lesniczowka_tp' freq=5240 MHz) Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: Associated with 92:8c:b5:42:2d:77 Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: WPA: Key negotiation completed with 92:8c:b5:42:2d:77 [PTK=CCMP GTK=CCMP] Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: CTRL-EVENT-CONNECTED - Connection to 92:8c:b5:42:2d:77 completed [id=0 id_str=] Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: carrier acquired Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: IAID 2b:ee:c9:b8 Nov 02 18:42:31 smsl-n100 wpa_supplicant[3800]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: soliciting a DHCP lease Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: offered 192.168.0.9 from 192.168.0.1 Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: probing address 192.168.0.9/24 Nov 02 18:42:31 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:31 smsl-n100 sudo[3846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:31 smsl-n100 sudo[3846]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:31 smsl-n100 sudo[3846]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:31 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 18:42:31 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:42:31 smsl-n100 dhcpcd[932]: wlan0: soliciting an IPv6 router Nov 02 18:42:31 smsl-n100 volumio[3277]: xcb_connection_has_error() returned true Nov 02 18:42:31 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 18:42:32 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:32 smsl-n100 sudo[3851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:32 smsl-n100 sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:32 smsl-n100 sudo[3851]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:33 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:33 smsl-n100 sudo[3854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:33 smsl-n100 sudo[3854]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:33 smsl-n100 sudo[3854]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:34 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:34 smsl-n100 sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:34 smsl-n100 sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:34 smsl-n100 sudo[3857]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:34 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:34 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:34 smsl-n100 volumio[3277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:42:34 smsl-n100 volumio[3277]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=f88eee134ec14516e74924c3492974ae" http://updates.volumio.org/downloader-v1/track-device Nov 02 18:42:34 smsl-n100 volumio[3277]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 02 18:42:34 smsl-n100 volumio[3277]: Dload Upload Total Spent Left Speed Nov 02 18:42:34 smsl-n100 volumio[3277]: [1.5K blob data] Nov 02 18:42:34 smsl-n100 volumio[3277]: retrying in 5 seconds, trial 0 Nov 02 18:42:34 smsl-n100 volumio[3277]: info: Volumio Calling Home Nov 02 18:42:35 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:35 smsl-n100 sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:35 smsl-n100 sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:35 smsl-n100 sudo[3865]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:36 smsl-n100 dhcpcd[932]: wlan0: leased 192.168.0.9 for 7200 seconds Nov 02 18:42:36 smsl-n100 dhcpcd[932]: wlan0: adding route to 192.168.0.0/24 Nov 02 18:42:36 smsl-n100 avahi-daemon[544]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.9. Nov 02 18:42:36 smsl-n100 dhcpcd[932]: wlan0: adding default route via 192.168.0.1 Nov 02 18:42:36 smsl-n100 avahi-daemon[544]: New relevant interface wlan0.IPv4 for mDNS. Nov 02 18:42:36 smsl-n100 avahi-daemon[544]: Registering new address record for 192.168.0.9 on wlan0.IPv4. Nov 02 18:42:36 smsl-n100 wireless.js[3708]: trying... Nov 02 18:42:36 smsl-n100 sudo[3888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 18:42:36 smsl-n100 sudo[3888]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:36 smsl-n100 sudo[3888]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:36 smsl-n100 wireless.js[3708]: Connected to: ----Lesniczowka_tp Nov 02 18:42:36 smsl-n100 wireless.js[3708]: ---- Nov 02 18:42:36 smsl-n100 sudo[3899]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:36 smsl-n100 sudo[3899]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:36 smsl-n100 sudo[3899]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:36 smsl-n100 wireless.js[3708]: ... joined AP, wlan0 IPv4 is 192.168.0.9, ipV6 is undefined Nov 02 18:42:36 smsl-n100 wireless.js[3708]: It's done! AP Nov 02 18:42:36 smsl-n100 iwconfig[3907]: Error for wireless request "Set Power Management" (8B2C) : Nov 02 18:42:36 smsl-n100 iwconfig[3907]: SET failed on device wlan0 ; Operation not permitted. Nov 02 18:42:36 smsl-n100 systemd[1]: Started Wireless Services. Nov 02 18:42:36 smsl-n100 sudo[3706]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:36 smsl-n100 ntpd[1080]: ntpd exiting on signal 15 (Terminated) Nov 02 18:42:36 smsl-n100 systemd[1]: Stopping Network Time Service... Nov 02 18:42:36 smsl-n100 systemd[1]: ntp.service: Succeeded. Nov 02 18:42:36 smsl-n100 systemd[1]: Stopped Network Time Service. Nov 02 18:42:36 smsl-n100 systemd[1]: Starting Network Time Service... Nov 02 18:42:36 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:36 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:36 smsl-n100 ntpd[3910]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 18:42:36 smsl-n100 ntpd[3910]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 18:42:36 smsl-n100 systemd[1]: Started Network Time Service. Nov 02 18:42:37 smsl-n100 ntpd[3917]: proto: precision = 1.167 usec (-20) Nov 02 18:42:37 smsl-n100 ntpd[3917]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 18:42:37 smsl-n100 ntpd[3917]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 18:42:37 smsl-n100 ntpd[3917]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1041 days ago Nov 02 18:42:37 smsl-n100 ntpd[3917]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 18:42:37 smsl-n100 ntpd[3917]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 18:42:37 smsl-n100 ntpd[3917]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 18:42:37 smsl-n100 ntpd[3917]: Listen normally on 3 wlan0 192.168.0.9:123 Nov 02 18:42:37 smsl-n100 ntpd[3917]: Listening on routing socket on fd #20 for interface updates Nov 02 18:42:37 smsl-n100 ntpd[3917]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 18:42:37 smsl-n100 ntpd[3917]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 18:42:37 smsl-n100 volumio[3277]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:37 smsl-n100 volumio[3277]: info: MRS: Found cast device: Philips-Google-TV-TA-f6bdb957ca9811a4b5ef06d95b560d26 Nov 02 18:42:37 smsl-n100 volumio[3277]: info: Adding audio output: Nov 02 18:42:37 smsl-n100 volumio[3277]: info: Discovery: adding a8934c21-5cf2-4340-b295-fcf288d7b59e Nov 02 18:42:37 smsl-n100 volumio[3277]: info: Discovery: Found device SMSL N100 Nov 02 18:42:37 smsl-n100 volumio[3277]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:37 smsl-n100 volumio[3277]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:37 smsl-n100 volumio[3277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:37 smsl-n100 volumio[3277]: Error: The value NaN is not a number Nov 02 18:42:37 smsl-n100 volumio[3277]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 02 18:42:37 smsl-n100 volumio[3277]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 02 18:42:37 smsl-n100 volumio[3277]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 02 18:42:37 smsl-n100 volumio[3277]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 02 18:42:37 smsl-n100 volumio[3277]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 02 18:42:37 smsl-n100 volumio[3277]: at Browser.emit (events.js:400:28) Nov 02 18:42:37 smsl-n100 volumio[3277]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 02 18:42:37 smsl-n100 volumio[3277]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 02 18:42:37 smsl-n100 volumio[3277]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 02 18:42:37 smsl-n100 volumio[3277]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 02 18:42:37 smsl-n100 volumio[3277]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 02 18:42:37 smsl-n100 volumio[3277]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 02 18:42:37 smsl-n100 volumio[3277]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 02 18:42:37 smsl-n100 volumio[3277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:38 smsl-n100 ntpd[3917]: Soliciting pool server 193.59.134.156 Nov 02 18:42:38 smsl-n100 sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 18:41 Nov 02 18:42:38 smsl-n100 sudo[3942]: 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"