-- Logs begin at Thu 2025-12-11 15:14:51 MSK, end at Thu 2025-12-11 15:17:12 MSK. -- Dec 11 15:16:00 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:00 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:00 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:00 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:00 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:00 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:01 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:01 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:01 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:02 smsl-n100 ntpd[733]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 11 15:16:02 smsl-n100 ntpd[733]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 11 15:16:02 smsl-n100 volumio[787]: error: MyVolumio Plugin failed to start in a timely fashion Dec 11 15:16:02 smsl-n100 volumio[787]: [Metrics] CommandRouter: 57s 604.92ms Dec 11 15:16:02 smsl-n100 volumio[787]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 15:16:02 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:02 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:16:02 smsl-n100 volumio[787]: info: CoreCommandRouter::Close All Modals sent Dec 11 15:16:02 smsl-n100 volumio[787]: info: CoreCommandRouter::Close All Modals sent Dec 11 15:16:02 smsl-n100 volumio[787]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 11 15:16:02 smsl-n100 volumio[787]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Dec 11 15:16:02 smsl-n100 volumio[787]: aplay: main:828: audio open error: No such device Dec 11 15:16:03 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:03 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:03 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:03 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 15:16:03 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 15:16:03 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 11 15:16:03 smsl-n100 volumio[787]: info: Stopping RAAT Plugin Dec 11 15:16:03 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 15:16:03 smsl-n100 sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 11 15:16:03 smsl-n100 sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:16:03 smsl-n100 sudo[1394]: pam_unix(sudo:session): session closed for user root Dec 11 15:16:03 smsl-n100 volumio[787]: info: Raat Daemon stopped successfully Dec 11 15:16:04 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:04 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:04 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:05 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:05 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:05 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:05 smsl-n100 ntpd[733]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 11 15:16:05 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:05 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:05 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:06 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:06 smsl-n100 ntpd[733]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 11 15:16:06 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:06 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:06 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 15:16:06 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:16:06 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 15:16:07 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:08 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:08 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:08 smsl-n100 volumio[787]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=d400064bdf7cc84bee937f6b1b01efaa" http://updates.volumio.org/downloader-v1/track-device Dec 11 15:16:08 smsl-n100 volumio[787]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 15:16:08 smsl-n100 volumio[787]: Dload Upload Total Spent Left Speed Dec 11 15:16:08 smsl-n100 volumio[787]: [132B blob data] Dec 11 15:16:08 smsl-n100 volumio[787]: retrying in 5 seconds, trial 2 Dec 11 15:16:08 smsl-n100 volumio[787]: info: Volumio Calling Home Dec 11 15:16:09 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:09 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:09 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:09 smsl-n100 volumio[787]: info: BOOT COMPLETED Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:16:10 smsl-n100 volumio[787]: info: Not Reporting Auto name since its the default one Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 11 15:16:10 smsl-n100 volumio[787]: info: Configured Audio card not found, not starting RAAT Dec 11 15:16:10 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:10 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:10 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:10 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:10 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:10 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:12 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:12 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:12 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:12 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:13 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:13 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:13 smsl-n100 volumio[787]: info: RAAT: Requesting Headphone Status Dec 11 15:16:13 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 11 15:16:13 smsl-n100 volumio[787]: info: CoreStateMachine::pushState Dec 11 15:16:13 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:13 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioPushState Dec 11 15:16:13 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: 100, message: undefined Dec 11 15:16:13 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 11 15:16:13 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: 100, message: undefined Dec 11 15:16:13 smsl-n100 volumio[787]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 11 15:16:14 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:14 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:14 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:15 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:15 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:15 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:15 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:15 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:15 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:17 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:17 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:17 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:18 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:18 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:18 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:19 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:19 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:19 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:20 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:20 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:20 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:20 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:20 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:20 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:20 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 11 15:16:21 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:21 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:21 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:21 smsl-n100 ifplugd(eth0)[750]: Link beat detected. Dec 11 15:16:21 smsl-n100 ifplugd(eth0)[750]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 11 15:16:21 smsl-n100 ifplugd(eth0)[750]: client: sending commands to master dhcpcd process Dec 11 15:16:21 smsl-n100 dhcpcd[1477]: sending commands to master dhcpcd process Dec 11 15:16:21 smsl-n100 dhcpcd[1052]: control command: /sbin/dhcpcd eth0 Dec 11 15:16:22 smsl-n100 dhcpcd[1052]: eth0: carrier acquired Dec 11 15:16:22 smsl-n100 dhcpcd[1052]: eth0: IAID 7d:c6:22:8b Dec 11 15:16:22 smsl-n100 ifplugd(eth0)[750]: Program executed successfully. Dec 11 15:16:22 smsl-n100 dhcpcd[1052]: eth0: soliciting an IPv6 router Dec 11 15:16:22 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:22 smsl-n100 dhcpcd[1052]: eth0: rebinding lease of 192.168.0.167 Dec 11 15:16:22 smsl-n100 dhcpcd[1052]: eth0: probing address 192.168.0.167/24 Dec 11 15:16:22 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:22 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:23 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:23 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:23 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:23 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:23 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:23 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:25 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:25 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:25 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:25 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:25 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Date not found in response Dec 11 15:16:25 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 15:16:26 smsl-n100 systemd[1]: nmbd.service: Start operation timed out. Terminating. Dec 11 15:16:26 smsl-n100 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Dec 11 15:16:26 smsl-n100 systemd[1]: nmbd.service: Failed with result 'timeout'. Dec 11 15:16:26 smsl-n100 systemd[1]: Failed to start Samba NMB Daemon. Dec 11 15:16:26 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... Dec 11 15:16:26 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:26 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:26 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:27 smsl-n100 winbindd[1512]: [2025/12/11 15:16:27.159880, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 11 15:16:27 smsl-n100 winbindd[1512]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 11 15:16:27 smsl-n100 winbindd[1512]: [2025/12/11 15:16:27.181745, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 11 15:16:27 smsl-n100 winbindd[1512]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 11 15:16:27 smsl-n100 systemd[1]: Started Samba Winbind Daemon. Dec 11 15:16:27 smsl-n100 systemd[1]: Starting Samba SMB Daemon... Dec 11 15:16:27 smsl-n100 dhcpcd[1052]: eth0: leased 192.168.0.167 for 7200 seconds Dec 11 15:16:27 smsl-n100 dhcpcd[1052]: eth0: adding route to 192.168.0.0/24 Dec 11 15:16:27 smsl-n100 dhcpcd[1052]: eth0: adding default route via 192.168.0.1 Dec 11 15:16:27 smsl-n100 avahi-daemon[542]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.167. Dec 11 15:16:27 smsl-n100 avahi-daemon[542]: New relevant interface eth0.IPv4 for mDNS. Dec 11 15:16:27 smsl-n100 avahi-daemon[542]: Registering new address record for 192.168.0.167 on eth0.IPv4. Dec 11 15:16:27 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:27 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:27 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:27 smsl-n100 ntpd[733]: ntpd exiting on signal 15 (Terminated) Dec 11 15:16:27 smsl-n100 systemd[1]: Stopping Network Time Service... Dec 11 15:16:27 smsl-n100 systemd[1]: ntp.service: Succeeded. Dec 11 15:16:27 smsl-n100 systemd[1]: Stopped Network Time Service. Dec 11 15:16:27 smsl-n100 systemd[1]: Starting Network Time Service... Dec 11 15:16:27 smsl-n100 ntpd[1551]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 11 15:16:27 smsl-n100 ntpd[1551]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 11 15:16:27 smsl-n100 systemd[1]: Started Network Time Service. Dec 11 15:16:27 smsl-n100 ntpd[1557]: proto: precision = 1.166 usec (-20) Dec 11 15:16:27 smsl-n100 ntpd[1557]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 11 15:16:27 smsl-n100 ntpd[1557]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 11 15:16:27 smsl-n100 ntpd[1557]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1080 days ago Dec 11 15:16:27 smsl-n100 ntpd[1557]: Listen and drop on 0 v6wildcard [::]:123 Dec 11 15:16:27 smsl-n100 ntpd[1557]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 11 15:16:27 smsl-n100 ntpd[1557]: Listen normally on 2 lo 127.0.0.1:123 Dec 11 15:16:27 smsl-n100 ntpd[1557]: Listen normally on 3 eth0 192.168.0.167:123 Dec 11 15:16:27 smsl-n100 ntpd[1557]: Listening on routing socket on fd #20 for interface updates Dec 11 15:16:27 smsl-n100 ntpd[1557]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 11 15:16:27 smsl-n100 ntpd[1557]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 11 15:16:28 smsl-n100 smbd[1516]: [2025/12/11 15:16:28.028703, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 11 15:16:28 smsl-n100 smbd[1516]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 11 15:16:28 smsl-n100 systemd[1]: Started Samba SMB Daemon. Dec 11 15:16:28 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:28 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:28 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:28 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:28 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:28 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:29 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 15:16:29 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:29 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:30 smsl-n100 volumio[1212]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 11 15:16:30 smsl-n100 volumio[787]: info: Reporting MCU Network Status: 1 Dec 11 15:16:30 smsl-n100 volumio[787]: info: Volumio Network Manager: Network status updated: 1 Dec 11 15:16:30 smsl-n100 volumio[787]: info: CoreStateMachine::pushState Dec 11 15:16:30 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:30 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioPushState Dec 11 15:16:30 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: 100, message: undefined Dec 11 15:16:30 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 11 15:16:30 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: 100, message: undefined Dec 11 15:16:30 smsl-n100 volumio[787]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 11 15:16:30 smsl-n100 volumio[787]: error: Serial API: Failed to decode command: 1, message: undefined Dec 11 15:16:30 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Fetching time from Volumio... Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 15:16:37 smsl-n100 volumio[787]: info: Discovery: Getting this device information Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:37 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 15:16:37 smsl-n100 volumio[787]: verbose: New Socket.io Connection to 192.168.0.167:3000 from 192.168.0.148 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 15:16:37 smsl-n100 volumio[787]: verbose: New Socket.io Connection to 192.168.0.167:3000 from 192.168.0.148 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 15:16:37 smsl-n100 volumio[787]: info: Discovery: Getting this device information Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:37 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:37 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 15:16:37 smsl-n100 ntpd[1557]: Soliciting pool server 93.95.98.77 Dec 11 15:16:37 smsl-n100 ntpd[1557]: Soliciting pool server 128.0.27.167 Dec 11 15:16:37 smsl-n100 ntpd[1557]: Soliciting pool server 195.122.253.38 Dec 11 15:16:37 smsl-n100 ntpd[1557]: Soliciting pool server 151.0.2.53 Dec 11 15:16:38 smsl-n100 volumio[1212]: ................................................................................................................++++ Dec 11 15:16:38 smsl-n100 ntpd[1557]: Soliciting pool server 79.111.119.96 Dec 11 15:16:38 smsl-n100 ntpd[1557]: Soliciting pool server 151.0.2.54 Dec 11 15:16:38 smsl-n100 ntpd[1557]: Soliciting pool server 89.169.135.41 Dec 11 15:16:39 smsl-n100 volumio[787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 11 15:16:39 smsl-n100 volumio[1212]: ................++++ Dec 11 15:16:39 smsl-n100 volumio[1212]: e is 65537 (0x010001) Dec 11 15:16:39 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:16:39 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:16:39 smsl-n100 volumio[1212]: writing RSA key Dec 11 15:16:39 smsl-n100 ntpd[1557]: Soliciting pool server 89.110.95.134 Dec 11 15:16:39 smsl-n100 ntpd[1557]: Soliciting pool server 37.23.221.106 Dec 11 15:16:39 smsl-n100 volumio-time-update[532]: volumio-time-update-util: Setting system time to: 2025-12-11 15:17:09 Dec 11 15:16:39 smsl-n100 sudo[1606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-11 15:17:09 Dec 11 15:16:39 smsl-n100 sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:16:40 smsl-n100 dbus-daemon[552]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1607 comm="timedatectl set-time 2025-12-11 15:17:09 ") Dec 11 15:16:40 smsl-n100 systemd[1]: Starting Time & Date Service... Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 11 15:16:40 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 11 15:16:40 smsl-n100 dbus-daemon[552]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 11 15:16:40 smsl-n100 systemd[1]: Started Time & Date Service. Dec 11 15:17:09 smsl-n100 systemd-timedated[1608]: Changed local time to Thu Dec 11 15:17:09 2025 Dec 11 15:17:09 smsl-n100 sudo[1606]: pam_unix(sudo:session): session closed for user root Dec 11 15:17:09 smsl-n100 volumio-time-update[532]: volumio-time-update-util: System time updated successfully. Dec 11 15:17:09 smsl-n100 systemd[1]: Started Volumio Time Update Utility. Dec 11 15:17:09 smsl-n100 systemd[1]: Reached target Multi-User System. Dec 11 15:17:09 smsl-n100 systemd[1]: Reached target Graphical Interface. Dec 11 15:17:09 smsl-n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 11 15:17:09 smsl-n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 11 15:17:09 smsl-n100 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 11 15:17:09 smsl-n100 systemd[1]: Startup finished in 5.676s (kernel) + 1min 50.552s (userspace) = 1min 56.228s. Dec 11 15:17:09 smsl-n100 ntpd[1557]: Soliciting pool server 91.206.16.3 Dec 11 15:17:09 smsl-n100 ntpd[1557]: Soliciting pool server 51.250.110.169 Dec 11 15:17:11 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 11 15:17:11 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 11 15:17:11 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:17:11 smsl-n100 volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:17:11 smsl-n100 volumio[787]: info: Starting MyVolumio Remote Streaming Endpoints Dec 11 15:17:11 smsl-n100 volumio[787]: info: MyVolumio not started Dec 11 15:17:11 smsl-n100 volumio[787]: info: Initializing device activation check Dec 11 15:17:11 smsl-n100 volumio[787]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 11 15:17:11 smsl-n100 volumio[787]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Dec 11 15:17:11 smsl-n100 volumio[787]: info: Discovery: adding 50df62c2-2fce-4de2-9bf3-f47f2c5a61f1 Dec 11 15:17:11 smsl-n100 volumio[787]: info: Discovery: Found device SMSL N100 Dec 11 15:17:11 smsl-n100 volumio[787]: info: CoreCommandRouter::volumioGetState Dec 11 15:17:11 smsl-n100 volumio[787]: info: CorePlayQueue::getTrack 0 Dec 11 15:17:11 smsl-n100 volumio[787]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:17:11 smsl-n100 volumio[787]: Error: The value NaN is not a number Dec 11 15:17:11 smsl-n100 volumio[787]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Dec 11 15:17:11 smsl-n100 volumio[787]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Dec 11 15:17:11 smsl-n100 volumio[787]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Dec 11 15:17:11 smsl-n100 volumio[787]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Dec 11 15:17:11 smsl-n100 volumio[787]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Dec 11 15:17:11 smsl-n100 volumio[787]: at Browser.emit (events.js:400:28) Dec 11 15:17:11 smsl-n100 volumio[787]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Dec 11 15:17:11 smsl-n100 volumio[787]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Dec 11 15:17:11 smsl-n100 volumio[787]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Dec 11 15:17:11 smsl-n100 volumio[787]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Dec 11 15:17:11 smsl-n100 volumio[787]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Dec 11 15:17:11 smsl-n100 volumio[787]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Dec 11 15:17:11 smsl-n100 volumio[787]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Dec 11 15:17:11 smsl-n100 volumio[787]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:17:12 smsl-n100 sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 15:16 Dec 11 15:17:12 smsl-n100 sudo[1636]: 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"