-- Logs begin at Mon 2025-03-17 23:40:44 CST, end at Tue 2025-03-18 19:22:21 CST. -- Mar 18 19:21:30 smsl-n100 volumio[1188]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Mar 18 19:21:30 smsl-n100 volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Mar 18 19:21:35 smsl-n100 volumio[1188]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Mar 18 19:21:35 smsl-n100 volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Mar 18 19:21:35 smsl-n100 sudo[2602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Mar 18 19:21:35 smsl-n100 sudo[2602]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:35 smsl-n100 sudo[2602]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:35 smsl-n100 sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Mar 18 19:21:35 smsl-n100 sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:35 smsl-n100 sudo[2604]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:35 smsl-n100 volumio[1188]: info: Permissions for /etc/network/interfaces set Mar 18 19:21:35 smsl-n100 volumio[1188]: info: Restarting Wired Network Mar 18 19:21:35 smsl-n100 volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 18 19:21:35 smsl-n100 volumio[1188]: info: Discovery: Restarting Advertising due to device name change Mar 18 19:21:35 smsl-n100 sudo[2607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Mar 18 19:21:35 smsl-n100 sudo[2607]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:35 smsl-n100 avahi-daemon[542]: Withdrawing address record for 192.168.3.183 on eth0. Mar 18 19:21:35 smsl-n100 avahi-daemon[542]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.3.183. Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: pid 2608 deleted IP address 192.168.3.183/24 Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: pid 2608 deleted IP address 192.168.3.183/24 Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: deleting route to 192.168.3.0/24 Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: deleting route to 192.168.3.0/24 Mar 18 19:21:35 smsl-n100 volumio[1188]: info: Discovery: A device disappeared from network Mar 18 19:21:35 smsl-n100 avahi-daemon[542]: Interface eth0.IPv4 no longer relevant for mDNS. Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: deleting default route via 192.168.3.1 Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: deleting default route via 192.168.3.1 Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: dhcp_envoption 213: Operation not supported Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: dhcp_envoption 213: Operation not supported Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: dhcp_envoption 213: Operation not supported Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: dhcp_envoption 213: Operation not supported Mar 18 19:21:35 smsl-n100 sudo[2607]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:35 smsl-n100 sudo[2611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Mar 18 19:21:35 smsl-n100 sudo[2611]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Mar 18 19:21:35 smsl-n100 sudo[2611]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: rebinding lease of 192.168.3.183 Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: if_sendraw: Network is down Mar 18 19:21:35 smsl-n100 dhcpcd[904]: dhcp_readpacket: eth0: Network is down Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: rebinding lease of 192.168.3.183 Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: if_sendraw: Network is down Mar 18 19:21:35 smsl-n100 dhcpcd[620]: dhcp_readpacket: eth0: Network is down Mar 18 19:21:35 smsl-n100 dhcpcd[904]: eth0: carrier lost Mar 18 19:21:35 smsl-n100 dhcpcd[620]: eth0: carrier lost Mar 18 19:21:35 smsl-n100 sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Mar 18 19:21:35 smsl-n100 sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Mar 18 19:21:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Mar 18 19:21:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Mar 18 19:21:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Mar 18 19:21:35 smsl-n100 sudo[2639]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:36 smsl-n100 ifplugd(eth0)[716]: Link beat lost. Mar 18 19:21:36 smsl-n100 volumio[1188]: info: Discovery: A device disappeared from network Mar 18 19:21:37 smsl-n100 ntpd[1177]: Deleting interface #3 eth0, 192.168.3.183#123, interface stats: received=56, sent=62, dropped=0, active_time=257 secs Mar 18 19:21:37 smsl-n100 ntpd[1177]: 162.159.200.1 local addr 192.168.3.183 -> Mar 18 19:21:37 smsl-n100 ntpd[1177]: 117.80.112.205 local addr 192.168.3.183 -> Mar 18 19:21:37 smsl-n100 ntpd[1177]: 81.16.177.123 local addr 192.168.3.183 -> Mar 18 19:21:37 smsl-n100 ntpd[1177]: 162.159.200.123 local addr 192.168.3.183 -> Mar 18 19:21:37 smsl-n100 ntpd[1177]: 202.118.1.130 local addr 192.168.3.183 -> Mar 18 19:21:37 smsl-n100 ntpd[1177]: 193.182.111.141 local addr 192.168.3.183 -> Mar 18 19:21:39 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Mar 18 19:21:39 smsl-n100 dhcpcd[904]: eth0: carrier acquired Mar 18 19:21:39 smsl-n100 dhcpcd[620]: eth0: carrier acquired Mar 18 19:21:39 smsl-n100 dhcpcd[904]: eth0: confile file changed, expiring leases Mar 18 19:21:39 smsl-n100 dhcpcd[620]: eth0: confile file changed, expiring leases Mar 18 19:21:39 smsl-n100 dhcpcd[904]: eth0: IAID 89:2c:a6:9c Mar 18 19:21:39 smsl-n100 dhcpcd[904]: eth0: probing address 192.168.3.187/24 Mar 18 19:21:39 smsl-n100 dhcpcd[620]: eth0: IAID 89:2c:a6:9c Mar 18 19:21:40 smsl-n100 dhcpcd[620]: eth0: probing address 192.168.3.187/24 Mar 18 19:21:40 smsl-n100 ntpd[1177]: receive: Unexpected origin timestamp 0xeb83d5c4.01e6b1ef does not match aorg 0000000000.00000000 from server@81.16.177.123 xmt 0xeb83d5c4.26824c7f Mar 18 19:21:40 smsl-n100 volumio[1188]: info: Discovery: Started advertising with name: SMSL N100 Mar 18 19:21:40 smsl-n100 dhcpcd[620]: eth0: soliciting an IPv6 router Mar 18 19:21:40 smsl-n100 ifplugd(eth0)[716]: Link beat detected. Mar 18 19:21:40 smsl-n100 dhcpcd[904]: eth0: soliciting an IPv6 router Mar 18 19:21:41 smsl-n100 volumio[1188]: info: Reporting MCU Network Status: 2 Mar 18 19:21:41 smsl-n100 volumio[1188]: info: Volumio Network Manager: Network status updated: 2 Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CoreStateMachine::pushState Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 18 19:21:41 smsl-n100 volumio[1188]: info: MRS: Pushing multiroomSync output update for this device Mar 18 19:21:41 smsl-n100 volumio[1188]: info: MRS: Pushing multiroomSync output Mar 18 19:21:41 smsl-n100 volumio[1188]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:21:41 smsl-n100 volumio[1188]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:21:41 smsl-n100 volumio[1188]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:21:41 smsl-n100 volumio[1188]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:21:41 smsl-n100 volumio[1188]: error: Serial API: Failed to decode command: 2, message: undefined Mar 18 19:21:41 smsl-n100 volumio[1188]: info: Discovery: adding 14167235-1516-42b3-b757-3df5b9f218a1 Mar 18 19:21:41 smsl-n100 volumio[1188]: info: Discovery: Found device SMSL N100 Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CoreCommandRouter::volumioGetState Mar 18 19:21:41 smsl-n100 volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 18 19:21:41 smsl-n100 volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:21:41 smsl-n100 volumio[1188]: Error: The value NaN is not a number Mar 18 19:21:41 smsl-n100 volumio[1188]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 18 19:21:41 smsl-n100 volumio[1188]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 18 19:21:41 smsl-n100 volumio[1188]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 18 19:21:41 smsl-n100 volumio[1188]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 18 19:21:41 smsl-n100 volumio[1188]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 18 19:21:41 smsl-n100 volumio[1188]: at Browser.emit (events.js:400:28) Mar 18 19:21:41 smsl-n100 volumio[1188]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 18 19:21:41 smsl-n100 volumio[1188]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 18 19:21:41 smsl-n100 volumio[1188]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 18 19:21:41 smsl-n100 volumio[1188]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 18 19:21:41 smsl-n100 volumio[1188]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Mar 18 19:21:41 smsl-n100 volumio[1188]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Mar 18 19:21:41 smsl-n100 volumio[1188]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Mar 18 19:21:41 smsl-n100 volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:21:42 smsl-n100 ntpd[1177]: receive: Unexpected origin timestamp 0xeb83d5c6.01ec07b0 does not match aorg 0000000000.00000000 from server@81.16.177.123 xmt 0xeb83d5c6.267c29db Mar 18 19:21:42 smsl-n100 sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 19:20 Mar 18 19:21:42 smsl-n100 sudo[2742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:42 smsl-n100 sudo[2742]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:43 smsl-n100 vtcs[2086]: [2025-03-18 19:21:43.027] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 18 19:21:43 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:21:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 18 19:21:43 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:21:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 18 19:21:43 smsl-n100 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 18 19:21:43 smsl-n100 systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 18 19:21:43 smsl-n100 systemd[1]: Started dynamicswap service. Mar 18 19:21:43 smsl-n100 systemd[1]: dynamicswap.service: Succeeded. Mar 18 19:21:43 smsl-n100 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 18 19:21:43 smsl-n100 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 18 19:21:43 smsl-n100 systemd[1]: Stopped Volumio Backend Module. Mar 18 19:21:43 smsl-n100 systemd[1]: Started Volumio Backend Module. Mar 18 19:21:43 smsl-n100 systemd[1]: Started dynamicswap service. Mar 18 19:21:43 smsl-n100 systemd[1]: dynamicswap.service: Succeeded. Mar 18 19:21:44 smsl-n100 ntpd[1177]: receive: Unexpected origin timestamp 0xeb83d5c8.01ccb0d1 does not match aorg 0000000000.00000000 from server@81.16.177.123 xmt 0xeb83d5c8.26741576 Mar 18 19:21:44 smsl-n100 dhcpcd[904]: eth0: using static address 192.168.3.187/24 Mar 18 19:21:44 smsl-n100 dhcpcd[904]: eth0: adding route to 192.168.3.0/24 Mar 18 19:21:44 smsl-n100 avahi-daemon[542]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.187. Mar 18 19:21:44 smsl-n100 avahi-daemon[542]: New relevant interface eth0.IPv4 for mDNS. Mar 18 19:21:44 smsl-n100 avahi-daemon[542]: Registering new address record for 192.168.3.187 on eth0.IPv4. Mar 18 19:21:45 smsl-n100 ntpd[1177]: Soliciting pool server 116.203.151.74 Mar 18 19:21:45 smsl-n100 dhcpcd[620]: eth0: using static address 192.168.3.187/24 Mar 18 19:21:45 smsl-n100 dhcpcd[620]: eth0: adding route to 192.168.3.0/24 Mar 18 19:21:46 smsl-n100 ntpd[1177]: Soliciting pool server 116.203.151.74 Mar 18 19:21:46 smsl-n100 ntpd[1177]: Listen normally on 5 eth0 192.168.3.187:123 Mar 18 19:21:46 smsl-n100 ntpd[1177]: new interface(s) found: waking up resolver Mar 18 19:21:46 smsl-n100 volumio[2755]: info: ------------------------------------------- Mar 18 19:21:46 smsl-n100 volumio[2755]: info: ----- Volumio3 ---- Mar 18 19:21:46 smsl-n100 volumio[2755]: info: ------------------------------------------- Mar 18 19:21:46 smsl-n100 volumio[2755]: info: ----- System startup ---- Mar 18 19:21:46 smsl-n100 volumio[2755]: info: ------------------------------------------- Mar 18 19:21:47 smsl-n100 ntpd[1177]: Soliciting pool server 78.46.102.180 Mar 18 19:21:47 smsl-n100 volumio[2755]: info: MYVOLUMIO Environment detected Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Plugin folders cleanup Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning into folder /volumio/app/plugins/ Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category audio_interface Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category miscellanea Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category music_service Mar 18 19:21:48 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:21:48] [connect] Successful connection Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category plugins.json Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category system_controller Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning category user_interface Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Scanning into folder /data/plugins/ Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Plugin folders cleanup completed Mar 18 19:21:48 smsl-n100 volumio[2755]: info: ------------------------------------------- Mar 18 19:21:48 smsl-n100 volumio[2755]: info: ----- Core plugins startup ---- Mar 18 19:21:48 smsl-n100 volumio[2755]: info: ------------------------------------------- Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Loading plugins from folder /volumio/app/plugins/ Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin bluetooth to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin multiroom to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin upnp to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin metavolumio to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin cd_controller to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin raat to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Loading plugins from folder /data/plugins/ Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Loading plugin "system"... Mar 18 19:21:48 smsl-n100 volumio[2755]: info: Loading plugin "appearance"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "network"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Refreshing Cached IP Addresses Mar 18 19:21:50 smsl-n100 sudo[2820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 19:21:50 smsl-n100 sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:50 smsl-n100 sudo[2820]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:50 smsl-n100 sudo[2822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 19:21:50 smsl-n100 sudo[2822]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:50 smsl-n100 sudo[2822]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "services"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "alsa_controller"... Mar 18 19:21:50 smsl-n100 sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 18 19:21:50 smsl-n100 sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:50 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "wizard"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "networkfs"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Starting Udev Watcher for removable devices Mar 18 19:21:50 smsl-n100 sudo[2846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lujianfeng,password=17070341@qq.com,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.57/q /mnt/NAS/DSD Mar 18 19:21:50 smsl-n100 sudo[2846]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Ignoring mount for partition: boot Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Ignoring mount for partition: volumio Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Ignoring mount for partition: volumio_data Mar 18 19:21:50 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "volumio_command_line_client"... Mar 18 19:21:50 smsl-n100 kernel: CIFS: Attempting to mount //192.168.3.57/q Mar 18 19:21:50 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "upnp"... Mar 18 19:21:50 smsl-n100 sudo[2831]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:50 smsl-n100 volumio[2755]: info: [1742296910602] Starting Upmpd Daemon Mar 18 19:21:50 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "my_music"... Mar 18 19:21:50 smsl-n100 volumio[2755]: info: Loading plugin "mpd"... Mar 18 19:21:50 smsl-n100 sudo[2846]: pam_unix(sudo:session): session closed for user root Mar 18 19:21:51 smsl-n100 ntpd[1177]: Soliciting pool server 193.182.111.143 Mar 18 19:21:51 smsl-n100 volumio[2755]: info: Loading plugin "upnp_browser"... Mar 18 19:21:51 smsl-n100 volumio[2755]: info: Loading plugin "bluetooth"... Mar 18 19:21:52 smsl-n100 volumio[2755]: info: [1742296912112] Starting BluetoothController Mar 18 19:21:52 smsl-n100 volumio[2755]: info: Loading plugin "alarm-clock"... Mar 18 19:21:52 smsl-n100 volumio[2755]: info: Loading plugin "manifestui"... Mar 18 19:21:52 smsl-n100 volumio[2755]: info: Loading plugin "metavolumio"... Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Loading plugin "airplay_emulation"... Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Starting Shairport Sync Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Loading plugin "cd_controller"... Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Loading plugin "last_100"... Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Loading plugin "raat"... Mar 18 19:21:55 smsl-n100 volumio[2755]: info: RAAT Plugin loaded Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Adding restartRAATSocket REST API Endpoint Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Mar 18 19:21:55 smsl-n100 volumio[2755]: info: Loading plugin "streaming_services"... Mar 18 19:21:57 smsl-n100 volumio[2755]: info: Starting Streaming Service Transparent Proxy Mar 18 19:21:57 smsl-n100 volumio[2755]: info: Loading plugin "tidalconnect"... Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Loading plugin "webradio"... Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Loading plugin "i2s_dacs"... Mar 18 19:21:58 smsl-n100 volumio[2755]: info: I2S DAC not set, start Auto-detection Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Loading plugin "volumiodiscovery"... Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** For more information see Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 19:21:58 smsl-n100 volumio[2755]: *** WARNING *** For more information see Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** For more information see Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 19:21:58 smsl-n100 node[2755]: *** WARNING *** For more information see Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Discovery: Started advertising with name: SMSL N100 Mar 18 19:21:58 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 19:21:58 smsl-n100 volumio[2755]: info: Loading plugin "multiroom"... Mar 18 19:22:00 smsl-n100 volumio[2755]: STREAMING PROXY: Starting server on port 3245 Mar 18 19:22:00 smsl-n100 volumio[2755]: Node JS runtime: 14 Mar 18 19:22:00 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:22:00] [connect] Successful connection Mar 18 19:22:01 smsl-n100 volumio[2755]: info: Applying required configuration parameters for plugin multiroom Mar 18 19:22:01 smsl-n100 sudo[2928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 18 19:22:01 smsl-n100 sudo[2928]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:01 smsl-n100 sudo[2928]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:01 smsl-n100 volumio[2755]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 18 19:22:01 smsl-n100 volumio[2755]: info: MRS: MultiRoom plugin initialized Mar 18 19:22:01 smsl-n100 volumio[2755]: info: MRS: STOPPING SNAPCLIENT Mar 18 19:22:01 smsl-n100 volumio[2755]: info: MRS: Snap server stop Mar 18 19:22:01 smsl-n100 sudo[2944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 18 19:22:01 smsl-n100 sudo[2944]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:01 smsl-n100 volumio[2755]: info: MRS: STOPPING volumioStreaming Mar 18 19:22:01 smsl-n100 sudo[2947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 18 19:22:01 smsl-n100 sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:01 smsl-n100 sudo[2944]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:01 smsl-n100 sudo[2949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 18 19:22:01 smsl-n100 volumio[2755]: info: Loading plugin "outputs"... Mar 18 19:22:01 smsl-n100 sudo[2949]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:01 smsl-n100 volumio[2755]: info: Loading plugin "albumart"... Mar 18 19:22:01 smsl-n100 sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 18 19:22:01 smsl-n100 sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:01 smsl-n100 sudo[2952]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:01 smsl-n100 sudo[2947]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:01 smsl-n100 volumio[2755]: info: Plugin example_plugin is not enabled Mar 18 19:22:01 smsl-n100 volumio[2755]: info: Loading plugin "hi_res_audio"... Mar 18 19:22:01 smsl-n100 sudo[2949]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:02 smsl-n100 volumio[2755]: Forking 3 albumart workers Mar 18 19:22:04 smsl-n100 volumio[2755]: Starting albumart workers Mar 18 19:22:04 smsl-n100 volumio[2755]: Starting albumart workers Mar 18 19:22:04 smsl-n100 volumio[2755]: info: Applying required configuration parameters for plugin hi_res_audio Mar 18 19:22:04 smsl-n100 volumio[2755]: info: Loading plugin "inputs"... Mar 18 19:22:04 smsl-n100 volumio[2755]: Starting albumart workers Mar 18 19:22:06 smsl-n100 volumio[2755]: info: Loading plugin "qobuz"... Mar 18 19:22:08 smsl-n100 volumio[2755]: info: Loading plugin "smart_inputs"... Mar 18 19:22:08 smsl-n100 volumio[2755]: info: Loading plugin "tidal"... Mar 18 19:22:10 smsl-n100 volumio[2755]: info: Loading plugin "oem_helper"... Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Applying required configuration parameters for plugin oem_helper Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Loading plugin "smslcontrol"... Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Adding this device properties Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Setting Additional Device Volumio Properties: [object Object] Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Loading plugin "updater_comm"... Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Plugin mpdemulation is not enabled Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Loading plugin "rest_api"... Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Loading plugin "websocket"... Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Starting Socket.io Server version 2.3.0 Mar 18 19:22:12 smsl-n100 volumio[2755]: info: Loading i18n strings for locale zh Mar 18 19:22:12 smsl-n100 volumio[2755]: Updating browse sources language Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::initPlayerControls Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:12 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 19:22:13 smsl-n100 volumio[2755]: Express server listening on port 3000 Mar 18 19:22:13 smsl-n100 volumio[2755]: [Metrics] WebUI: 27s 522.69ms Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreStateMachine::resetVolumioState Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreStateMachine::getcurrentVolume Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioRetrievevolume Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:13 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:13 smsl-n100 sudo[3036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 19:22:13 smsl-n100 sudo[3036]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:13 smsl-n100 sudo[3036]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:13 smsl-n100 volumio[2755]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 18 19:22:13 smsl-n100 volumio[2755]: info: Completed loading Core Plugins Mar 18 19:22:13 smsl-n100 sudo[3039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 19:22:13 smsl-n100 volumio[2755]: info: Preparing to generate the ALSA configuration file Mar 18 19:22:13 smsl-n100 sudo[3039]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:13 smsl-n100 sudo[3039]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:13 smsl-n100 volumio[2755]: info: Volumio Network Manager: Network status updated: 3 Mar 18 19:22:13 smsl-n100 volumio[2755]: info: MRS: Removed streaming files Mar 18 19:22:13 smsl-n100 volumio[2755]: info: MRS: volumioStreaming STOPPED Mar 18 19:22:13 smsl-n100 volumio[2755]: info: MRS: SNAPSERVER STOPPED Mar 18 19:22:13 smsl-n100 volumio[2755]: info: MRS: SNAPCLIENT STOPPED Mar 18 19:22:13 smsl-n100 volumio[2755]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 18 19:22:13 smsl-n100 volumio[2755]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 18 19:22:13 smsl-n100 volumio[2755]: info: Reading ALSA contributions from plugins. Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.183 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 18 19:22:13 smsl-n100 volumio[2755]: verbose: New Socket.io Connection to 192.168.3.178 from 192.168.3.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Reloading queue from file Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreStateMachine::setRepeat false single undefined Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreStateMachine::setRandom false Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Setting Device type: NanoPi NEO 3 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Asound.conf file unchanged, so no further update is needed Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Output device has changed, restarting MPD Mar 18 19:22:14 smsl-n100 sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 18 19:22:14 smsl-n100 sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 sudo[3048]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:14 smsl-n100 sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 19:22:14 smsl-n100 sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Output device has changed, restarting Shairport Sync Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:14 smsl-n100 sudo[3051]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:14 smsl-n100 sudo[3053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 19:22:14 smsl-n100 sudo[3053]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 systemd[1]: Stopping Music Player Daemon... Mar 18 19:22:14 smsl-n100 systemd[1]: mpd.service: Succeeded. Mar 18 19:22:14 smsl-n100 systemd[1]: Stopped Music Player Daemon. Mar 18 19:22:14 smsl-n100 systemd[1]: Starting Music Player Daemon... Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:14 smsl-n100 volumio[2755]: info: ___________ START PLUGINS ___________ Mar 18 19:22:14 smsl-n100 volumio[2755]: info: ControllerMpd::onStart: Initializing MPD Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Creating MPD Configuration file Mar 18 19:22:14 smsl-n100 sudo[3063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 19:22:14 smsl-n100 sudo[3063]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 sudo[3063]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:14 smsl-n100 sudo[3059]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 19:22:14 smsl-n100 sudo[3059]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 19:22:14 smsl-n100 sudo[3059]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:14 smsl-n100 volumio[2755]: info: [1742296934686] CoreMusicLibrary::Adding element 媒体服务器 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 19:22:14 smsl-n100 sudo[3066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 19:22:14 smsl-n100 sudo[3066]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 18 19:22:14 smsl-n100 systemd[1]: mpd.service: Succeeded. Mar 18 19:22:14 smsl-n100 systemd[1]: Stopped Music Player Daemon. Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 19:22:14 smsl-n100 systemd[1]: Starting Music Player Daemon... Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Adding METAVOLUMIO REST API Endpoints Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:14 smsl-n100 sudo[3072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Mar 18 19:22:14 smsl-n100 sudo[3072]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:14 smsl-n100 sudo[3072]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:14 smsl-n100 volumio[2755]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:14 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:14 smsl-n100 sudo[3070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 19:22:14 smsl-n100 sudo[3070]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:15 smsl-n100 sudo[3070]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Preparing CD Folders Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding CD REST API Endpoints Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Starting UDEV Watcher for CD Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Detecting CD presence with UDEV Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 19:22:15 smsl-n100 volumio[2755]: info: [1742296935128] CoreMusicLibrary::Adding element Last_100 Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Starting RAAT Plugin Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Additional UI Settings Added for plugin music_service/raat Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Registering DSP Elements listener and retrieving current ones Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Additional DSP elements updated Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Updating RAAT Signal Path Mar 18 19:22:15 smsl-n100 volumio[2755]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Streaming services startup Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Starting Streaming Daemon Mar 18 19:22:15 smsl-n100 sudo[3083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 18 19:22:15 smsl-n100 sudo[3083]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:15 smsl-n100 sudo[3083]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 19:22:15 smsl-n100 volumio[2755]: info: [1742296935383] CoreMusicLibrary::Adding element Webradio Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Initializing BBC Radios Mar 18 19:22:15 smsl-n100 sudo[3090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 18 19:22:15 smsl-n100 sudo[3090]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:15 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Mar 18 19:22:15 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 18 19:22:15 smsl-n100 systemd[1]: vtcs.service: Succeeded. Mar 18 19:22:15 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Mar 18 19:22:15 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:22:15] [connect] Successful connection Mar 18 19:22:15 smsl-n100 sudo[3090]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 18 19:22:15 smsl-n100 volumio[2755]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 18 19:22:15 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:15 smsl-n100 volumio[2755]: error: Hi Res Audio Failed Login: Missing Login Data Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Initializing Serial Communication on port /dev/ttyS1 Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Touch Event Listener Process Starting Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding inputs REST Endpoints Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Scanning Audio Inputs Mar 18 19:22:15 smsl-n100 sudo[3107]: pam_unix(sudo:auth): conversation failed Mar 18 19:22:15 smsl-n100 sudo[3107]: pam_unix(sudo:auth): auth could not identify password for [volumio] Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Checking against Known Cards name Mar 18 19:22:15 smsl-n100 volumio[2755]: info: Adding Server instance for streaming Mar 18 19:22:16 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 19:22:16 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Initializing GPIOs Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Starting Button Listener Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Turning off Green LED Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Tuning on Blue LED Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Signaling System Ready Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Volumio Calling Home Mar 18 19:22:16 smsl-n100 volumio[2755]: (node:2755) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Mar 18 19:22:16 smsl-n100 volumio[2755]: (Use `node --trace-deprecation ...` to show where the warning was created) Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Stopping AccessToken refresher cron for QOBUZ Mar 18 19:22:16 smsl-n100 volumio[2755]: info: AccessToken refresher cron started for QOBUZ Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Stopping AccessToken refresher cron Mar 18 19:22:16 smsl-n100 volumio[2755]: info: AccessToken refresher cron started Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Adding TIDAL REST API Endpoints Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Adding QOBUZ REST API Endpoints Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Serial port opened successfully Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Sending serial start messages Mar 18 19:22:16 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:16 smsl-n100 volumio[2755]: info: Reporting MCU Network Status: 3 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Touch Event Listener Process Closed Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Cannot start Volumio Streaming Daemon Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 18 19:22:17 smsl-n100 volumio[2755]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Mar 18 19:22:17 smsl-n100 volumio[2755]: Can't get device info: No such device Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MPD Permissions set Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MPD Permissions set Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Upmpdcli Daemon Started Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Setting Device Volume Override Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K9 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Disabling external Volume Control Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:17 smsl-n100 volumio-remote-updater[531]: [2025-03-18 19:22:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742296935 101 Mar 18 19:22:17 smsl-n100 volumio[2755]: 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: 13 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 3, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:17 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:17 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:17 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Starting Shairport Sync Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Starting Shairport Sync Mar 18 19:22:17 smsl-n100 sudo[3140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 19:22:17 smsl-n100 sudo[3140]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:17 smsl-n100 volumio[2755]: info: Starting Shairport Sync Mar 18 19:22:17 smsl-n100 sudo[3143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 19:22:17 smsl-n100 sudo[3143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:17 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 19:22:17 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Mar 18 19:22:17 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 19:22:17 smsl-n100 sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 19:22:17 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 19:22:17 smsl-n100 sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:17 smsl-n100 sudo[3140]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:17 smsl-n100 sudo[3143]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Shairport-Sync Started Mar 18 19:22:18 smsl-n100 sudo[3149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 18 19:22:18 smsl-n100 volumio[2755]: Error adding Membership: Error: addMembership EINVAL Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Shairport-Sync Started Mar 18 19:22:18 smsl-n100 sudo[3149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:18 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:18 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:18 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 18 19:22:18 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 18 19:22:18 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Mar 18 19:22:18 smsl-n100 volumio[2755]: error: Serial API: Failed to decode command: 100, message: undefined Mar 18 19:22:18 smsl-n100 volumio[2755]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 18 19:22:18 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 19:22:18 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 19:22:18 smsl-n100 sudo[3146]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:18 smsl-n100 sudo[3149]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Shairport-Sync Started Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Volumio BT Module successfully started Mar 18 19:22:18 smsl-n100 volumio[2755]: info: TidalConnect service stoped! Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 18 19:22:18 smsl-n100 volumio[2755]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 18 19:22:18 smsl-n100 sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 18 19:22:18 smsl-n100 sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:18 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Mar 18 19:22:18 smsl-n100 sudo[3164]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:19 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 18 19:22:19 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Mar 18 19:22:19 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Mar 18 19:22:19 smsl-n100 sudo[3053]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:19 smsl-n100 sudo[3066]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:19 smsl-n100 volumio[2755]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Mar 18 19:22:19 smsl-n100 volumio[2755]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Mar 18 19:22:19 smsl-n100 volumio[2755]: See "systemctl status mpd.service" and "journalctl -xe" for details. Mar 18 19:22:19 smsl-n100 volumio[2755]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 18 19:22:19 smsl-n100 systemd[1]: Starting Music Player Daemon... Mar 18 19:22:19 smsl-n100 volumio[2755]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Mar 18 19:22:19 smsl-n100 volumio[2755]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Mar 18 19:22:19 smsl-n100 volumio[2755]: See "systemctl status mpd.service" and "journalctl -xe" for details. Mar 18 19:22:19 smsl-n100 volumio[2755]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 18 19:22:19 smsl-n100 sudo[3171]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 19:22:19 smsl-n100 sudo[3171]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 19:22:19 smsl-n100 sudo[3171]: pam_unix(sudo:session): session closed for user root Mar 18 19:22:19 smsl-n100 volumio[2755]: info: Executing endpoint tc_getconfig Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 18 19:22:19 smsl-n100 vtcs[3166]: STARTING TidalConnect services, version: 1.4.0.34 Mar 18 19:22:19 smsl-n100 vtcs[3166]: STARTED TidalConnect services. Mar 18 19:22:19 smsl-n100 volumio[2755]: info: Executing endpoint tc_connect Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 18 19:22:19 smsl-n100 volumio[2755]: info: Connecting to TidalConnect Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::servicePushState Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:19 smsl-n100 volumio[2755]: 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} Mar 18 19:22:19 smsl-n100 volumio[2755]: verbose: CURRENT POSITION 0 Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreStateMachine::syncState stateService stop Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:22:19 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: No code Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::servicePushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: 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} Mar 18 19:22:20 smsl-n100 volumio[2755]: verbose: CURRENT POSITION 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::syncState stateService stop Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: No code Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreStateMachine::pushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioPushState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: Discovery: adding 14167235-1516-42b3-b757-3df5b9f218a1 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: Discovery: Found device SMSL N100 Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CoreCommandRouter::volumioGetState Mar 18 19:22:20 smsl-n100 volumio[2755]: info: CorePlayQueue::getTrack 0 Mar 18 19:22:20 smsl-n100 volumio[2755]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:22:20 smsl-n100 volumio[2755]: Error: The value NaN is not a number Mar 18 19:22:20 smsl-n100 volumio[2755]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 18 19:22:20 smsl-n100 volumio[2755]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 18 19:22:20 smsl-n100 volumio[2755]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 18 19:22:20 smsl-n100 volumio[2755]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 18 19:22:20 smsl-n100 volumio[2755]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 18 19:22:20 smsl-n100 volumio[2755]: at Browser.emit (events.js:400:28) Mar 18 19:22:20 smsl-n100 volumio[2755]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 18 19:22:20 smsl-n100 volumio[2755]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 18 19:22:20 smsl-n100 volumio[2755]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 18 19:22:20 smsl-n100 volumio[2755]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 18 19:22:20 smsl-n100 volumio[2755]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Mar 18 19:22:20 smsl-n100 volumio[2755]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Mar 18 19:22:20 smsl-n100 volumio[2755]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Mar 18 19:22:20 smsl-n100 volumio[2755]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:22:21 smsl-n100 sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 19:21 Mar 18 19:22:21 smsl-n100 sudo[3213]: 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"