-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-05-20 20:58:39 CEST. -- May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:00 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. May 20 20:57:00 smsl-n100 sudo[1229]: pam_unix(sudo:session): session closed for user root May 20 20:57:00 smsl-n100 sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 20 20:57:00 smsl-n100 sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:00 smsl-n100 volumio[807]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 20 20:57:00 smsl-n100 volumio[807]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 20 20:57:00 smsl-n100 volumio[807]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 20 20:57:00 smsl-n100 volumio[807]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 20 20:57:00 smsl-n100 volumio[807]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 20 20:57:00 smsl-n100 volumio[807]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 20 20:57:00 smsl-n100 volumiobt[1191]: pulseaudio: no process found May 20 20:57:00 smsl-n100 sudo[1233]: pam_unix(sudo:session): session closed for user root May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:00 smsl-n100 sudo[1231]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 20:57:00 smsl-n100 sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:00 smsl-n100 sudo[1231]: pam_unix(sudo:session): session closed for user root May 20 20:57:00 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.11' (uid=1000 pid=1242 comm="/usr/bin/pulseaudio --high-priority=true ") May 20 20:57:00 smsl-n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:00 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 20 20:57:00 smsl-n100 systemd[1]: Started RealtimeKit Scheduling Policy Service. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Successfully called chroot. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Successfully dropped privileges. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Successfully limited resources. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Running. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Watchdog thread running. May 20 20:57:00 smsl-n100 rtkit-daemon[1243]: Canary thread running. May 20 20:57:00 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.12' (uid=0 pid=1243 comm="/usr/lib/rtkit/rtkit-daemon ") May 20 20:57:00 smsl-n100 systemd[1]: Starting Authorization Manager... May 20 20:57:00 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:00 smsl-n100 polkitd[1246]: started daemon version 0.105 using authority implementation `local' version `0.105' May 20 20:57:00 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 20 20:57:00 smsl-n100 systemd[1]: Started Authorization Manager. May 20 20:57:01 smsl-n100 volumio[807]: info: Executing endpoint tc_getconfig May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 20 20:57:01 smsl-n100 vtcs[1234]: STARTING TidalConnect services, version: 1.4.0.34 May 20 20:57:01 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.32' (uid=1000 pid=1242 comm="/usr/bin/pulseaudio --high-priority=true ") May 20 20:57:01 smsl-n100 vtcs[1234]: STARTED TidalConnect services. May 20 20:57:01 smsl-n100 volumio[807]: info: Executing endpoint tc_connect May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 20 20:57:01 smsl-n100 volumio[807]: info: Connecting to TidalConnect May 20 20:57:01 smsl-n100 systemd[1]: Starting Bluetooth service... May 20 20:57:01 smsl-n100 volumio[807]: info: MRS: Getting audio outputs on start May 20 20:57:01 smsl-n100 volumio[807]: info: MRS: Requesting all other devices output May 20 20:57:01 smsl-n100 pulseaudio[1242]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 20 20:57:01 smsl-n100 pulseaudio[1242]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 20 20:57:01 smsl-n100 volumiobt[1191]: Applying permissions May 20 20:57:01 smsl-n100 volumiobt[1191]: Setting BT discoverable and pairable May 20 20:57:01 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::servicePushState May 20 20:57:01 smsl-n100 volumio[807]: info: CoreStateMachine::pushState May 20 20:57:01 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::volumioPushState May 20 20:57:01 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:01 smsl-n100 volumio[807]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::servicePushState May 20 20:57:01 smsl-n100 volumio[807]: info: CoreStateMachine::pushState May 20 20:57:01 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:01 smsl-n100 volumio[807]: info: CoreCommandRouter::volumioPushState May 20 20:57:01 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:01 smsl-n100 volumio[807]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 20 20:57:01 smsl-n100 bluetoothd[1259]: Bluetooth daemon 5.50 May 20 20:57:01 smsl-n100 bluetoothd[1259]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 20 20:57:01 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.bluez' May 20 20:57:01 smsl-n100 systemd[1]: Started Bluetooth service. May 20 20:57:01 smsl-n100 bluetoothd[1259]: Starting SDP server May 20 20:57:01 smsl-n100 bluetoothd[1259]: Excluding (cli) sap May 20 20:57:01 smsl-n100 bluetoothd[1259]: kernel lacks bnep-protocol support May 20 20:57:01 smsl-n100 bluetoothd[1259]: System does not support network plugin May 20 20:57:01 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.35' (uid=0 pid=1259 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 20 20:57:01 smsl-n100 bluetoothd[1259]: Bluetooth management interface 1.14 initialized May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# -e power on May 20 20:57:01 smsl-n100 volumiobt[1191]: Invalid command in menu main: -e May 20 20:57:01 smsl-n100 volumiobt[1191]: May 20 20:57:01 smsl-n100 volumiobt[1191]: Use "help" for a list of available commands in a menu. May 20 20:57:01 smsl-n100 volumiobt[1191]: Use "menu " if you want to enter any submenu. May 20 20:57:01 smsl-n100 volumiobt[1191]: Use "back" if you want to return to menu main. May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# agent on May 20 20:57:01 smsl-n100 volumiobt[1191]: Failed to register agent object May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# discoverable on May 20 20:57:01 smsl-n100 volumiobt[1191]: No default controller available May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# pairable on May 20 20:57:01 smsl-n100 volumiobt[1191]: No default controller available May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# agent NoInputNoOutput May 20 20:57:01 smsl-n100 volumiobt[1191]: Failed to register agent object May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# default-agent May 20 20:57:01 smsl-n100 volumiobt[1191]: No agent is registered May 20 20:57:01 smsl-n100 volumiobt[1191]: [bluetooth]# quit May 20 20:57:01 smsl-n100 pulseaudio[1242]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 20 20:57:01 smsl-n100 volumiobt[1191]: [59B blob data] May 20 20:57:01 smsl-n100 systemd[1]: Starting Hostname Service... May 20 20:57:01 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:01 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.hostname1' May 20 20:57:01 smsl-n100 systemd[1]: Started Hostname Service. May 20 20:57:03 smsl-n100 volumio[807]: info: TidalConnect service started! May 20 20:57:03 smsl-n100 volumio[807]: info: Completed starting Core Plugins May 20 20:57:03 smsl-n100 volumio[807]: info: ------------------------------------------- May 20 20:57:03 smsl-n100 volumio[807]: info: ----- MyVolumio plugins startup ---- May 20 20:57:03 smsl-n100 volumio[807]: info: ------------------------------------------- May 20 20:57:03 smsl-n100 volumio[807]: info: [MyVolumio PluginManager] Fetching plans data.... May 20 20:57:04 smsl-n100 mpd[1240]: May 20 20:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 20:57:04 smsl-n100 systemd[1]: Started Music Player Daemon. May 20 20:57:04 smsl-n100 volumio[807]: Upnp client error: Error: This socket has been ended by the other party May 20 20:57:04 smsl-n100 mpd_monitor.sh[521]: MPD restarted due to no mpc output. May 20 20:57:04 smsl-n100 volumio[807]: error: MPD error: The expression evaluated to a falsy value: May 20 20:57:04 smsl-n100 volumio[807]: assert.ok(self.idling) May 20 20:57:04 smsl-n100 volumio[807]: error: The expression evaluated to a falsy value: May 20 20:57:04 smsl-n100 volumio[807]: assert.ok(self.idling) May 20 20:57:04 smsl-n100 volumio[807]: Upnp client error: Error: This socket has been ended by the other party May 20 20:57:04 smsl-n100 volumio[807]: error: updateQueue error: null May 20 20:57:04 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:04 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:04 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:04 smsl-n100 sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.166/USB /mnt/NAS/USB May 20 20:57:04 smsl-n100 sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:04 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.166/USB May 20 20:57:04 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. May 20 20:57:04 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 20 20:57:04 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 20 20:57:04 smsl-n100 sudo[1282]: pam_unix(sudo:session): session closed for user root May 20 20:57:04 smsl-n100 volumio[807]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds May 20 20:57:08 smsl-n100 volumio[807]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=e90348cd91cc07954eb7fc2f88f432e3" http://updates.volumio.org/downloader-v1/track-device May 20 20:57:08 smsl-n100 volumio[807]: % Total % Received % Xferd Average Speed Time Time Time Current May 20 20:57:08 smsl-n100 volumio[807]: Dload Upload Total Spent Left Speed May 20 20:57:08 smsl-n100 volumio[807]: [132B blob data] May 20 20:57:08 smsl-n100 volumio[807]: retrying in 5 seconds, trial 0 May 20 20:57:08 smsl-n100 volumio[807]: info: Volumio Calling Home May 20 20:57:09 smsl-n100 volumio[807]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 20 20:57:09 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:09 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:09 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:10 smsl-n100 sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.166/USB /mnt/NAS/USB May 20 20:57:10 smsl-n100 sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:10 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.166/USB May 20 20:57:10 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. May 20 20:57:10 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 20 20:57:10 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 20 20:57:10 smsl-n100 sudo[1344]: pam_unix(sudo:session): session closed for user root May 20 20:57:10 smsl-n100 volumio[807]: info: Cannot mount NAS USB at system boot, trial number 4 ,retrying in 5 seconds May 20 20:57:14 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:14 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:14 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:15 smsl-n100 sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.166/USB /mnt/NAS/USB May 20 20:57:15 smsl-n100 sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:15 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.166/USB May 20 20:57:15 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. May 20 20:57:15 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 20 20:57:15 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 20 20:57:15 smsl-n100 sudo[1357]: pam_unix(sudo:session): session closed for user root May 20 20:57:15 smsl-n100 volumio[807]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 20 20:57:16 smsl-n100 ntpd[765]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 20 20:57:18 smsl-n100 volumio[807]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=e90348cd91cc07954eb7fc2f88f432e3" http://updates.volumio.org/downloader-v1/track-device May 20 20:57:18 smsl-n100 volumio[807]: % Total % Received % Xferd Average Speed Time Time Time Current May 20 20:57:18 smsl-n100 volumio[807]: Dload Upload Total Spent Left Speed May 20 20:57:18 smsl-n100 volumio[807]: [132B blob data] May 20 20:57:18 smsl-n100 volumio[807]: retrying in 5 seconds, trial 1 May 20 20:57:18 smsl-n100 volumio[807]: info: Volumio Calling Home May 20 20:57:18 smsl-n100 ntpd[765]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 20 20:57:18 smsl-n100 ntpd[765]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 20 20:57:19 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:19 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:19 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:21 smsl-n100 ntpd[765]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 20 20:57:23 smsl-n100 volumio[807]: error: MyVolumio Plugin failed to start in a timely fashion May 20 20:57:23 smsl-n100 volumio[807]: [Metrics] CommandRouter: 62s 84.98ms May 20 20:57:23 smsl-n100 volumio[807]: info: CoreCommandRouter::volumiosetStartupVolume May 20 20:57:23 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:23 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 20:57:23 smsl-n100 volumio[807]: info: CoreCommandRouter::Close All Modals sent May 20 20:57:23 smsl-n100 volumio[807]: info: CoreCommandRouter::Close All Modals sent May 20 20:57:24 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 20 20:57:24 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 20:57:24 smsl-n100 volumio[807]: xcb_connection_has_error() returned true May 20 20:57:24 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 20 20:57:24 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:24 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:24 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:27 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 20:57:27 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 20:57:27 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 20:57:28 smsl-n100 volumio[807]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=e90348cd91cc07954eb7fc2f88f432e3" http://updates.volumio.org/downloader-v1/track-device May 20 20:57:28 smsl-n100 volumio[807]: % Total % Received % Xferd Average Speed Time Time Time Current May 20 20:57:28 smsl-n100 volumio[807]: Dload Upload Total Spent Left Speed May 20 20:57:28 smsl-n100 volumio[807]: [132B blob data] May 20 20:57:28 smsl-n100 volumio[807]: retrying in 5 seconds, trial 2 May 20 20:57:28 smsl-n100 volumio[807]: info: Volumio Calling Home May 20 20:57:29 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:29 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:29 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:30 smsl-n100 volumio[807]: info: BOOT COMPLETED May 20 20:57:32 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded. May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 20:57:32 smsl-n100 volumio[807]: info: Not Reporting Auto name since its the default one May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 20 20:57:32 smsl-n100 volumio[807]: xcb_connection_has_error() returned true May 20 20:57:32 smsl-n100 sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 20 20:57:32 smsl-n100 sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:32 smsl-n100 sudo[1429]: pam_unix(sudo:session): session closed for user root May 20 20:57:32 smsl-n100 sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 20 20:57:32 smsl-n100 sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 20:57:32 smsl-n100 systemd[1]: Started RAAT DAEMON. May 20 20:57:32 smsl-n100 sudo[1431]: pam_unix(sudo:session): session closed for user root May 20 20:57:32 smsl-n100 volumio[807]: info: Raat Daemon started successfully May 20 20:57:32 smsl-n100 volumio[807]: info: Executing endpoint restartRAATSocket May 20 20:57:32 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 20 20:57:32 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:32 smsl-n100 volumio[807]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 20 20:57:34 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:34 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:34 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:35 smsl-n100 volumio[807]: info: RAAT: Requesting Headphone Status May 20 20:57:35 smsl-n100 volumio[807]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 20 20:57:35 smsl-n100 volumio[807]: info: CoreStateMachine::pushState May 20 20:57:35 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:57:35 smsl-n100 volumio[807]: info: CoreCommandRouter::volumioPushState May 20 20:57:35 smsl-n100 volumio[807]: error: Serial API: Failed to decode command: 100, message: undefined May 20 20:57:35 smsl-n100 volumio[807]: error: Serial API: Failed to decode command: MAXVOL, message: May 20 20:57:35 smsl-n100 volumio[807]: error: Serial API: Failed to decode command: 100, message: undefined May 20 20:57:35 smsl-n100 volumio[807]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 20 20:57:39 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:39 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:39 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:40 smsl-n100 systemd[1]: nmbd.service: Start operation timed out. Terminating. May 20 20:57:40 smsl-n100 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM May 20 20:57:40 smsl-n100 systemd[1]: nmbd.service: Failed with result 'timeout'. May 20 20:57:40 smsl-n100 systemd[1]: Failed to start Samba NMB Daemon. May 20 20:57:40 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... May 20 20:57:40 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:40 smsl-n100 winbindd[1471]: [2025/05/20 20:57:40.925020, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 20 20:57:40 smsl-n100 winbindd[1471]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 20 20:57:40 smsl-n100 winbindd[1471]: [2025/05/20 20:57:40.950633, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 20 20:57:40 smsl-n100 winbindd[1471]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 20 20:57:40 smsl-n100 systemd[1]: Started Samba Winbind Daemon. May 20 20:57:40 smsl-n100 systemd[1]: Starting Samba SMB Daemon... May 20 20:57:40 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:57:41 smsl-n100 winbindd[1471]: [2025/05/20 20:57:41.634947, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) May 20 20:57:41 smsl-n100 winbindd[1471]: res_names->count = 3, expected 4 May 20 20:57:41 smsl-n100 smbd[1475]: [2025/05/20 20:57:41.682114, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 20 20:57:41 smsl-n100 smbd[1475]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 20 20:57:41 smsl-n100 systemd[1]: Started Samba SMB Daemon. May 20 20:57:44 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:44 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:44 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:49 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:49 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:49 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:54 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:54 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:54 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:57:59 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:57:59 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:57:59 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:04 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:05 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:05 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:05 smsl-n100 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 20 20:58:05 smsl-n100 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 20 20:58:10 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:10 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:10 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:15 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:15 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:15 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:20 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:20 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:20 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:21 smsl-n100 ntpd[765]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 20 20:58:23 smsl-n100 ntpd[765]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 20 20:58:23 smsl-n100 ntpd[765]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 20 20:58:25 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:25 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:25 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:27 smsl-n100 ntpd[765]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 20 20:58:30 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:30 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:30 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: carrier acquired May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: IAID 79:a6:a5:22 May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: adding address fe80::5c96:79ff:fea6:a522 May 20 20:58:31 smsl-n100 dhcpcd[621]: ipv6_addaddr1: Permission denied May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: soliciting a DHCP lease May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: offered 192.168.1.74 from 192.168.1.1 May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: probing address 192.168.1.74/24 May 20 20:58:31 smsl-n100 dhcpcd[621]: eth0: soliciting an IPv6 router May 20 20:58:31 smsl-n100 ifplugd(eth0)[761]: Link beat detected. May 20 20:58:32 smsl-n100 ifplugd(eth0)[761]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 20 20:58:32 smsl-n100 ifplugd(eth0)[761]: client: sending commands to master dhcpcd process May 20 20:58:32 smsl-n100 dhcpcd[1676]: sending commands to master dhcpcd process May 20 20:58:32 smsl-n100 dhcpcd[621]: control command: /sbin/dhcpcd eth0 May 20 20:58:32 smsl-n100 ifplugd(eth0)[761]: Program executed successfully. May 20 20:58:35 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Fetching time from Volumio... May 20 20:58:35 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Date not found in response May 20 20:58:35 smsl-n100 volumio-time-update[550]: volumio-time-update-util: Retrying in 5 seconds... May 20 20:58:37 smsl-n100 dhcpcd[621]: eth0: leased 192.168.1.74 for 86400 seconds May 20 20:58:37 smsl-n100 dhcpcd[621]: eth0: adding route to 192.168.1.0/24 May 20 20:58:37 smsl-n100 avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.74. May 20 20:58:37 smsl-n100 avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. May 20 20:58:37 smsl-n100 dhcpcd[621]: eth0: adding default route via 192.168.1.1 May 20 20:58:37 smsl-n100 avahi-daemon[534]: Registering new address record for 192.168.1.74 on eth0.IPv4. May 20 20:58:37 smsl-n100 ntpd[765]: ntpd exiting on signal 15 (Terminated) May 20 20:58:37 smsl-n100 systemd[1]: Stopping Network Time Service... May 20 20:58:37 smsl-n100 systemd[1]: ntp.service: Succeeded. May 20 20:58:37 smsl-n100 systemd[1]: Stopped Network Time Service. May 20 20:58:37 smsl-n100 systemd[1]: Starting Network Time Service... May 20 20:58:37 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:58:37 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 20:58:37 smsl-n100 volumio[807]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 20:58:37 smsl-n100 ntpd[1759]: ntpd 4.2.8p12@1.3728-o (1): Starting May 20 20:58:37 smsl-n100 ntpd[1759]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 20 20:58:37 smsl-n100 systemd[1]: Started Network Time Service. May 20 20:58:37 smsl-n100 ntpd[1765]: proto: precision = 1.166 usec (-20) May 20 20:58:37 smsl-n100 ntpd[1765]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 20 20:58:37 smsl-n100 ntpd[1765]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 20 20:58:37 smsl-n100 ntpd[1765]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 875 days ago May 20 20:58:37 smsl-n100 ntpd[1765]: Listen and drop on 0 v6wildcard [::]:123 May 20 20:58:37 smsl-n100 ntpd[1765]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 20 20:58:37 smsl-n100 ntpd[1765]: Listen normally on 2 lo 127.0.0.1:123 May 20 20:58:37 smsl-n100 ntpd[1765]: Listen normally on 3 eth0 192.168.1.74:123 May 20 20:58:37 smsl-n100 ntpd[1765]: Listening on routing socket on fd #20 for interface updates May 20 20:58:37 smsl-n100 ntpd[1765]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 20 20:58:37 smsl-n100 ntpd[1765]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 20 20:58:38 smsl-n100 volumio[807]: info: Discovery: adding fb74965d-62a3-4ccc-a848-f15079eef8e5 May 20 20:58:38 smsl-n100 volumio[807]: info: Discovery: Found device SMSL N100 May 20 20:58:38 smsl-n100 volumio[807]: info: CoreCommandRouter::volumioGetState May 20 20:58:38 smsl-n100 volumio[807]: info: CorePlayQueue::getTrack 0 May 20 20:58:38 smsl-n100 volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 20:58:38 smsl-n100 volumio[807]: Error: The value NaN is not a number May 20 20:58:38 smsl-n100 volumio[807]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 20 20:58:38 smsl-n100 volumio[807]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 20 20:58:38 smsl-n100 volumio[807]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 20 20:58:38 smsl-n100 volumio[807]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 20 20:58:38 smsl-n100 volumio[807]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 20 20:58:38 smsl-n100 volumio[807]: at Browser.emit (events.js:400:28) May 20 20:58:38 smsl-n100 volumio[807]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 20 20:58:38 smsl-n100 volumio[807]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 20 20:58:38 smsl-n100 volumio[807]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 20 20:58:38 smsl-n100 volumio[807]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 20 20:58:38 smsl-n100 volumio[807]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 20 20:58:38 smsl-n100 volumio[807]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 20 20:58:38 smsl-n100 volumio[807]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 20 20:58:38 smsl-n100 volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 20:58:38 smsl-n100 ntpd[1765]: Soliciting pool server 193.70.94.182 May 20 20:58:39 smsl-n100 sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 20:57 May 20 20:58:39 smsl-n100 sudo[1776]: 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"