-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Tue 2025-12-30 01:58:48 PST. -- Dec 30 01:57:34 smsl-n100 systemd-timedated[1006]: Changed local time to Tue Dec 30 01:57:34 2025 Dec 30 01:57:34 smsl-n100 sudo[982]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:34 smsl-n100 volumio-time-update[577]: volumio-time-update-util: System time updated successfully. Dec 30 01:57:34 smsl-n100 systemd[1]: Started Volumio Time Update Utility. Dec 30 01:57:34 smsl-n100 systemd[1]: Starting Daily apt download activities... Dec 30 01:57:34 smsl-n100 systemd[1]: Reached target Multi-User System. Dec 30 01:57:34 smsl-n100 systemd[1]: Reached target Graphical Interface. Dec 30 01:57:34 smsl-n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 30 01:57:34 smsl-n100 volumio[804]: info: Starting Udev Watcher for removable devices Dec 30 01:57:34 smsl-n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 30 01:57:34 smsl-n100 sudo[1015]: 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.154/Music /mnt/NAS/Music Dec 30 01:57:34 smsl-n100 sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:34 smsl-n100 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 30 01:57:34 smsl-n100 volumio[804]: info: Ignoring mount for partition: boot Dec 30 01:57:34 smsl-n100 volumio[804]: info: Ignoring mount for partition: volumio Dec 30 01:57:34 smsl-n100 volumio[804]: info: Ignoring mount for partition: volumio_data Dec 30 01:57:34 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 01:57:34 smsl-n100 volumio[804]: info: Loading plugin "volumio_command_line_client"... Dec 30 01:57:34 smsl-n100 volumio[804]: info: Loading plugin "upnp"... Dec 30 01:57:34 smsl-n100 volumio[804]: info: [1767088654235] Starting Upmpd Daemon Dec 30 01:57:34 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 01:57:34 smsl-n100 volumio[804]: info: Loading plugin "my_music"... Dec 30 01:57:34 smsl-n100 kernel: FS-Cache: Loaded Dec 30 01:57:34 smsl-n100 volumio[804]: info: Loading plugin "mpd"... Dec 30 01:57:34 smsl-n100 kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 30 01:57:34 smsl-n100 kernel: Key type cifs.spnego registered Dec 30 01:57:34 smsl-n100 kernel: Key type cifs.idmap registered Dec 30 01:57:34 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.154/Music Dec 30 01:57:34 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. Dec 30 01:57:34 smsl-n100 ntpd[931]: Soliciting pool server 45.63.54.13 Dec 30 01:57:34 smsl-n100 ntpd[931]: Soliciting pool server 172.235.60.8 Dec 30 01:57:34 smsl-n100 ntpd[931]: Soliciting pool server 104.131.155.175 Dec 30 01:57:35 smsl-n100 volumio[804]: info: Loading plugin "upnp_browser"... Dec 30 01:57:35 smsl-n100 systemd[1]: apt-daily.service: Succeeded. Dec 30 01:57:35 smsl-n100 systemd[1]: Started Daily apt download activities. Dec 30 01:57:35 smsl-n100 systemd[1]: Starting Daily apt upgrade and clean activities... Dec 30 01:57:35 smsl-n100 ntpd[931]: Soliciting pool server 23.142.248.8 Dec 30 01:57:35 smsl-n100 ntpd[931]: Soliciting pool server 24.144.88.190 Dec 30 01:57:36 smsl-n100 volumio[804]: info: Loading plugin "bluetooth"... Dec 30 01:57:36 smsl-n100 volumio[804]: info: [1767088656551] Starting BluetoothController Dec 30 01:57:36 smsl-n100 ntpd[931]: Soliciting pool server 66.59.198.178 Dec 30 01:57:36 smsl-n100 ntpd[931]: Soliciting pool server 2001:470:1f2a:51::123 Dec 30 01:57:36 smsl-n100 systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 30 01:57:36 smsl-n100 systemd[1]: Started Daily apt upgrade and clean activities. Dec 30 01:57:36 smsl-n100 systemd[1]: Startup finished in 5.934s (kernel) + 23.863s (userspace) = 29.797s. Dec 30 01:57:36 smsl-n100 volumio[804]: info: Loading plugin "alarm-clock"... Dec 30 01:57:37 smsl-n100 volumio[804]: info: Loading plugin "manifestui"... Dec 30 01:57:37 smsl-n100 volumio[804]: info: Loading plugin "metavolumio"... Dec 30 01:57:40 smsl-n100 volumio[804]: info: Loading plugin "airplay_emulation"... Dec 30 01:57:40 smsl-n100 volumio[804]: info: Starting Shairport Sync Dec 30 01:57:40 smsl-n100 volumio[804]: info: Loading plugin "cd_controller"... Dec 30 01:57:40 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Dec 30 01:57:40 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Dec 30 01:57:40 smsl-n100 sudo[1015]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a39bc716 does not match aorg 0000000000.00000000 from server@158.51.99.19 xmt 0xecfe2095.a548731e Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a39573a7 does not match aorg 0000000000.00000000 from server@97.107.136.23 xmt 0xecfe2095.a6c81b46 Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a3a14bef does not match aorg 0000000000.00000000 from server@172.104.28.175 xmt 0xecfe2095.a6d17e6e Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a38f1aac does not match aorg 0000000000.00000000 from server@172.234.37.140 xmt 0xecfe2095.a78f31af Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a39ea17f does not match aorg 0000000000.00000000 from server@96.245.170.99 xmt 0xecfe2095.a7d9f1e1 Dec 30 01:57:41 smsl-n100 ntpd[931]: receive: Unexpected origin timestamp 0xecfe2094.a3a406a5 does not match aorg 0000000000.00000000 from server@75.186.74.61 xmt 0xecfe2095.ab8131bb Dec 30 01:57:41 smsl-n100 volumio[804]: info: Loading plugin "last_100"... Dec 30 01:57:41 smsl-n100 volumio[804]: info: Loading plugin "raat"... Dec 30 01:57:42 smsl-n100 volumio[804]: info: RAAT Plugin loaded Dec 30 01:57:42 smsl-n100 volumio[804]: info: Adding restartRAATSocket REST API Endpoint Dec 30 01:57:42 smsl-n100 volumio[804]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Dec 30 01:57:42 smsl-n100 volumio[804]: info: Loading plugin "streaming_services"... Dec 30 01:57:44 smsl-n100 volumio[804]: info: Starting Streaming Service Transparent Proxy Dec 30 01:57:44 smsl-n100 volumio[804]: info: Loading plugin "tidalconnect"... Dec 30 01:57:45 smsl-n100 volumio[804]: info: Loading plugin "webradio"... Dec 30 01:57:45 smsl-n100 volumio[804]: info: Loading plugin "i2s_dacs"... Dec 30 01:57:45 smsl-n100 volumio[804]: info: I2S DAC not set, start Auto-detection Dec 30 01:57:45 smsl-n100 volumio[804]: info: Loading plugin "volumiodiscovery"... Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** For more information see Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 01:57:45 smsl-n100 volumio[804]: *** WARNING *** For more information see Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** For more information see Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 01:57:45 smsl-n100 node[804]: *** WARNING *** For more information see Dec 30 01:57:45 smsl-n100 volumio[804]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 30 01:57:45 smsl-n100 volumio[804]: info: Discovery: Started advertising with name: SMSL N100 Dec 30 01:57:45 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 01:57:45 smsl-n100 volumio[804]: info: Loading plugin "multiroom"... Dec 30 01:57:46 smsl-n100 volumio[804]: STREAMING PROXY: Starting server on port 3245 Dec 30 01:57:46 smsl-n100 volumio[804]: Node JS runtime: 14 Dec 30 01:57:47 smsl-n100 volumio-remote-updater[564]: [2025-12-30 01:57:47] [connect] Successful connection Dec 30 01:57:47 smsl-n100 systemd[1]: systemd-fsckd.service: Succeeded. Dec 30 01:57:48 smsl-n100 volumio[804]: info: Applying required configuration parameters for plugin multiroom Dec 30 01:57:48 smsl-n100 sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 30 01:57:48 smsl-n100 sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:48 smsl-n100 sudo[1154]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:48 smsl-n100 volumio[804]: info: MRS: MultiRoom plugin initialized Dec 30 01:57:48 smsl-n100 volumio[804]: info: MRS: STOPPING SNAPCLIENT Dec 30 01:57:48 smsl-n100 volumio[804]: info: MRS: Snap server stop Dec 30 01:57:48 smsl-n100 sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 30 01:57:48 smsl-n100 volumio[804]: info: MRS: STOPPING volumioStreaming Dec 30 01:57:48 smsl-n100 sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:48 smsl-n100 sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 30 01:57:48 smsl-n100 sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:48 smsl-n100 volumio[804]: info: Loading plugin "outputs"... Dec 30 01:57:48 smsl-n100 sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 30 01:57:48 smsl-n100 sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:48 smsl-n100 sudo[1170]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:48 smsl-n100 volumio[804]: info: Loading plugin "albumart"... Dec 30 01:57:48 smsl-n100 sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 30 01:57:48 smsl-n100 sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:57:49 smsl-n100 sudo[1178]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:49 smsl-n100 volumio[804]: info: Plugin example_plugin is not enabled Dec 30 01:57:49 smsl-n100 volumio[804]: info: Loading plugin "hi_res_audio"... Dec 30 01:57:49 smsl-n100 sudo[1172]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:49 smsl-n100 sudo[1175]: pam_unix(sudo:session): session closed for user root Dec 30 01:57:49 smsl-n100 volumio[804]: Forking 3 albumart workers Dec 30 01:57:51 smsl-n100 volumio[804]: Starting albumart workers Dec 30 01:57:51 smsl-n100 volumio[804]: Starting albumart workers Dec 30 01:57:51 smsl-n100 volumio[804]: Starting albumart workers Dec 30 01:57:52 smsl-n100 volumio[804]: info: Applying required configuration parameters for plugin hi_res_audio Dec 30 01:57:52 smsl-n100 volumio[804]: info: Loading plugin "inputs"... Dec 30 01:57:54 smsl-n100 volumio[804]: info: Loading plugin "qobuz"... Dec 30 01:57:56 smsl-n100 volumio[804]: info: Loading plugin "smart_inputs"... Dec 30 01:57:57 smsl-n100 volumio[804]: info: Loading plugin "tidal"... Dec 30 01:58:00 smsl-n100 volumio[804]: info: Loading plugin "oem_helper"... Dec 30 01:58:02 smsl-n100 volumio[804]: info: Applying required configuration parameters for plugin oem_helper Dec 30 01:58:02 smsl-n100 volumio[804]: info: Loading plugin "smslcontrol"... Dec 30 01:58:02 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 Dec 30 01:58:02 smsl-n100 volumio[804]: info: Adding this device properties Dec 30 01:58:02 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 30 01:58:02 smsl-n100 volumio[804]: info: Setting Additional Device Volumio Properties: [object Object] Dec 30 01:58:02 smsl-n100 volumio[804]: info: Loading plugin "updater_comm"... Dec 30 01:58:02 smsl-n100 volumio-remote-updater[564]: [2025-12-30 01:58:02] [connect] Successful connection Dec 30 01:58:02 smsl-n100 volumio[804]: info: Plugin mpdemulation is not enabled Dec 30 01:58:02 smsl-n100 volumio[804]: info: Loading plugin "rest_api"... Dec 30 01:58:02 smsl-n100 volumio[804]: info: Loading plugin "websocket"... Dec 30 01:58:02 smsl-n100 volumio[804]: info: Starting Socket.io Server version 2.3.0 Dec 30 01:58:02 smsl-n100 volumio[804]: info: Loading i18n strings for locale en Dec 30 01:58:02 smsl-n100 volumio[804]: Updating browse sources language Dec 30 01:58:02 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::initPlayerControls Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 01:58:03 smsl-n100 volumio[804]: Express server listening on port 3000 Dec 30 01:58:03 smsl-n100 volumio[804]: [Metrics] WebUI: 35s 464.51ms Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreStateMachine::resetVolumioState Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreStateMachine::getcurrentVolume Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 01:58:03 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:03 smsl-n100 sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 01:58:03 smsl-n100 sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:03 smsl-n100 sudo[1266]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:03 smsl-n100 sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 01:58:03 smsl-n100 sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:03 smsl-n100 volumio[804]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 30 01:58:03 smsl-n100 sudo[1268]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:03 smsl-n100 volumio[804]: info: Completed loading Core Plugins Dec 30 01:58:03 smsl-n100 volumio[804]: info: Preparing to generate the ALSA configuration file Dec 30 01:58:03 smsl-n100 volumio[804]: info: Volumio Network Manager: Network status updated: 1 Dec 30 01:58:03 smsl-n100 volumio[804]: info: MRS: Removed streaming files Dec 30 01:58:03 smsl-n100 volumio[804]: info: MRS: volumioStreaming STOPPED Dec 30 01:58:03 smsl-n100 volumio[804]: info: MRS: SNAPSERVER STOPPED Dec 30 01:58:03 smsl-n100 volumio[804]: info: MRS: SNAPCLIENT STOPPED Dec 30 01:58:03 smsl-n100 volumio[804]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Dec 30 01:58:03 smsl-n100 volumio[804]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 30 01:58:03 smsl-n100 volumio[804]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 30 01:58:03 smsl-n100 volumio[804]: info: Reading ALSA contributions from plugins. Dec 30 01:58:03 smsl-n100 volumio-remote-updater[564]: [2025-12-30 01:58:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767088682 101 Dec 30 01:58:04 smsl-n100 volumio[804]: 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: 1 Dec 30 01:58:04 smsl-n100 volumio[804]: info: VolumeController:: Volume=0 Mute =false Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::updateTrackBlock Dec 30 01:58:04 smsl-n100 volumio[804]: info: CorePlayQueue::getTrackBlock Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 01:58:04 smsl-n100 volumio[804]: info: Reloading queue from file Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::setRepeat null single undefined Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::setRandom false Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: Setting Device type: NanoPi NEO 3 Dec 30 01:58:04 smsl-n100 volumio[804]: info: VolumeController:: Volume=0 Mute =false Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:04 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:04 smsl-n100 sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 01:58:04 smsl-n100 sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:04 smsl-n100 volumio[804]: info: Asound.conf file unchanged, so no further update is needed Dec 30 01:58:04 smsl-n100 volumio[804]: info: Output device has changed, restarting MPD Dec 30 01:58:04 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 30 01:58:04 smsl-n100 sudo[1280]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:04 smsl-n100 sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 01:58:04 smsl-n100 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:04 smsl-n100 volumio[804]: info: Output device has changed, restarting Shairport Sync Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:04 smsl-n100 sudo[1284]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:04 smsl-n100 sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 01:58:04 smsl-n100 sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:04 smsl-n100 systemd[1]: Stopping Music Player Daemon... Dec 30 01:58:04 smsl-n100 volumio[804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 01:58:04 smsl-n100 volumio[804]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:04 smsl-n100 volumio[804]: info: ___________ START PLUGINS ___________ Dec 30 01:58:04 smsl-n100 volumio[804]: info: ControllerMpd::onStart: Initializing MPD Dec 30 01:58:04 smsl-n100 volumio[804]: info: Creating MPD Configuration file Dec 30 01:58:04 smsl-n100 sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 01:58:04 smsl-n100 sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:04 smsl-n100 volumio[804]: info: [1767088684861] CoreMusicLibrary::Adding element Media Servers Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 01:58:04 smsl-n100 sudo[1295]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:04 smsl-n100 sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 01:58:04 smsl-n100 sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:04 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 01:58:04 smsl-n100 systemd[1]: mpd.service: Succeeded. Dec 30 01:58:04 smsl-n100 systemd[1]: Stopped Music Player Daemon. Dec 30 01:58:04 smsl-n100 systemd[1]: Starting Music Player Daemon... Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding METAVOLUMIO REST API Endpoints Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 30 01:58:05 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:05 smsl-n100 sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Dec 30 01:58:05 smsl-n100 sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:05 smsl-n100 sudo[1310]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:05 smsl-n100 volumio[804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:05 smsl-n100 sudo[1309]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 01:58:05 smsl-n100 sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:05 smsl-n100 sudo[1309]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:05 smsl-n100 volumio[804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 01:58:05 smsl-n100 volumio[804]: info: Preparing CD Folders Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding CD REST API Endpoints Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 30 01:58:05 smsl-n100 volumio[804]: info: Starting UDEV Watcher for CD Dec 30 01:58:05 smsl-n100 volumio[804]: info: Detecting CD presence with UDEV Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 01:58:05 smsl-n100 volumio[804]: info: [1767088685294] CoreMusicLibrary::Adding element Last_100 Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 01:58:05 smsl-n100 volumio[804]: info: Starting RAAT Plugin Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 30 01:58:05 smsl-n100 volumio[804]: info: Additional UI Settings Added for plugin music_service/raat Dec 30 01:58:05 smsl-n100 volumio[804]: info: Registering DSP Elements listener and retrieving current ones Dec 30 01:58:05 smsl-n100 volumio[804]: info: Additional DSP elements updated Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: info: Updating RAAT Signal Path Dec 30 01:58:05 smsl-n100 volumio[804]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 30 01:58:05 smsl-n100 volumio[804]: info: Streaming services startup Dec 30 01:58:05 smsl-n100 volumio[804]: info: Starting Streaming Daemon Dec 30 01:58:05 smsl-n100 sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 01:58:05 smsl-n100 sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:05 smsl-n100 sudo[1331]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 01:58:05 smsl-n100 volumio[804]: info: [1767088685561] CoreMusicLibrary::Adding element Webradio Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 01:58:05 smsl-n100 volumio[804]: info: Initializing BBC Radios Dec 30 01:58:05 smsl-n100 sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 01:58:05 smsl-n100 sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:05 smsl-n100 sudo[1338]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 30 01:58:05 smsl-n100 volumio[804]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 01:58:05 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:05 smsl-n100 volumio[804]: error: Hi Res Audio Failed Login: Missing Login Data Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 01:58:05 smsl-n100 volumio[804]: info: Initializing Serial Communication on port /dev/ttyS1 Dec 30 01:58:05 smsl-n100 volumio[804]: info: Touch Event Listener Process Starting Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding inputs REST Endpoints Dec 30 01:58:05 smsl-n100 volumio[804]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 30 01:58:05 smsl-n100 volumio[804]: info: Scanning Audio Inputs Dec 30 01:58:05 smsl-n100 sudo[1355]: pam_unix(sudo:auth): conversation failed Dec 30 01:58:05 smsl-n100 sudo[1355]: pam_unix(sudo:auth): auth could not identify password for [volumio] Dec 30 01:58:06 smsl-n100 volumio[804]: info: Checking against Known Cards name Dec 30 01:58:06 smsl-n100 volumio[804]: info: Adding Server instance for streaming Dec 30 01:58:06 smsl-n100 volumio[804]: info: Refreshing TIDAL token Dec 30 01:58:06 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 01:58:06 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:06 smsl-n100 volumio[804]: info: Initializing GPIOs Dec 30 01:58:06 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 Dec 30 01:58:06 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 Dec 30 01:58:06 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 Dec 30 01:58:06 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 Dec 30 01:58:06 smsl-n100 volumio[804]: info: Starting Button Listener Dec 30 01:58:06 smsl-n100 volumio[804]: info: Turning off Green LED Dec 30 01:58:06 smsl-n100 volumio[804]: info: Tuning on Blue LED Dec 30 01:58:06 smsl-n100 volumio[804]: info: Signaling System Ready Dec 30 01:58:06 smsl-n100 volumio[804]: info: Volumio Calling Home Dec 30 01:58:06 smsl-n100 volumio[804]: (node:804) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 30 01:58:06 smsl-n100 volumio[804]: (Use `node --trace-deprecation ...` to show where the warning was created) Dec 30 01:58:06 smsl-n100 volumio[804]: info: Stopping AccessToken refresher cron for QOBUZ Dec 30 01:58:06 smsl-n100 volumio[804]: info: AccessToken refresher cron started for QOBUZ Dec 30 01:58:06 smsl-n100 volumio[804]: info: Adding QOBUZ REST API Endpoints Dec 30 01:58:06 smsl-n100 volumio[804]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 30 01:58:06 smsl-n100 volumio[804]: info: Discovery: adding 7fcee92b-d6b0-4df6-82b4-f92f7afa085f Dec 30 01:58:06 smsl-n100 volumio[804]: info: Discovery: Found device SMSL N100 Dec 30 01:58:06 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetState Dec 30 01:58:06 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:06 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output for this device Dec 30 01:58:06 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:07 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:07 smsl-n100 volumio[804]: info: Serial port opened successfully Dec 30 01:58:07 smsl-n100 volumio[804]: info: Sending serial start messages Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: Reporting MCU Network Status: 1 Dec 30 01:58:07 smsl-n100 volumio[804]: info: Touch Event Listener Process Closed Dec 30 01:58:07 smsl-n100 volumio[804]: error: Cannot start Volumio Streaming Daemon Dec 30 01:58:07 smsl-n100 volumio[804]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 01:58:07 smsl-n100 volumio[804]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 01:58:07 smsl-n100 volumio[804]: info: RAAT Albumart path created successfully Dec 30 01:58:07 smsl-n100 volumio[804]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Dec 30 01:58:07 smsl-n100 volumio[804]: Can't get device info: No such device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MPD Permissions set Dec 30 01:58:07 smsl-n100 volumio[804]: info: MPD Permissions set Dec 30 01:58:07 smsl-n100 volumio[804]: info: Upmpdcli Daemon Started Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 30 01:58:07 smsl-n100 volumio[804]: info: Setting Device Volume Override Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 01:58:07 smsl-n100 volumio[804]: info: Updating Volume Controller Parameters: Device: 5 Name: Gustard USB Audio 2.0 Mixer: Gustard USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 01:58:07 smsl-n100 volumio[804]: info: Disabling external Volume Control Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:07 smsl-n100 volumio[804]: info: Volumio called home Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 1, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:07 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:07 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:07 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:07 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:08 smsl-n100 volumio[804]: 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 30 01:58:08 smsl-n100 sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 30 01:58:08 smsl-n100 sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:08 smsl-n100 systemd[1]: Started Volumio Bluetooth Module. Dec 30 01:58:08 smsl-n100 volumio[804]: info: Access Token successfully retrieved Dec 30 01:58:08 smsl-n100 sudo[1406]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:08 smsl-n100 sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 30 01:58:08 smsl-n100 sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:08 smsl-n100 sudo[1410]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:08 smsl-n100 volumiobt[1409]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 30 01:58:08 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 30 01:58:08 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Dec 30 01:58:08 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Dec 30 01:58:08 smsl-n100 sudo[1297]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:08 smsl-n100 sudo[1287]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:08 smsl-n100 systemd[1]: Starting Music Player Daemon... Dec 30 01:58:08 smsl-n100 volumio[804]: info: VolumeController:: Volume=0 Mute =false Dec 30 01:58:08 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:08 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:08 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:08 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:08 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:08 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:08 smsl-n100 sudo[1415]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 01:58:08 smsl-n100 sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:08 smsl-n100 sudo[1415]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:08 smsl-n100 volumio[804]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 30 01:58:08 smsl-n100 volumio[804]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 30 01:58:08 smsl-n100 volumio[804]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 30 01:58:08 smsl-n100 volumio[804]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 30 01:58:08 smsl-n100 volumio[804]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 30 01:58:08 smsl-n100 volumio[804]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 30 01:58:08 smsl-n100 volumio[804]: info: Volumio BT Module successfully started Dec 30 01:58:08 smsl-n100 volumio[804]: info: Starting Shairport Sync Dec 30 01:58:09 smsl-n100 sudo[1421]: 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.154/Music /mnt/NAS/Music Dec 30 01:58:09 smsl-n100 sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:09 smsl-n100 volumio[804]: info: Starting Shairport Sync Dec 30 01:58:09 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.154/Music Dec 30 01:58:09 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. Dec 30 01:58:09 smsl-n100 sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 01:58:09 smsl-n100 sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:09 smsl-n100 volumio[804]: info: Starting Shairport Sync Dec 30 01:58:09 smsl-n100 sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Found cast device: Q700B-447b286f536522e01c8b83ddf1f13d18 Dec 30 01:58:09 smsl-n100 sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:09 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 01:58:09 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:09 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:09 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 01:58:09 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:09 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:09 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:09 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:09 smsl-n100 sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:09 smsl-n100 sudo[1429]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:09 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:09 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:09 smsl-n100 sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 01:58:09 smsl-n100 sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:09 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 01:58:09 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 30 01:58:09 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Dec 30 01:58:09 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 01:58:09 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 30 01:58:09 smsl-n100 sudo[1432]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:09 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:09 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 01:58:09 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetState Dec 30 01:58:09 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:09 smsl-n100 sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 30 01:58:09 smsl-n100 sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:09 smsl-n100 volumiobt[1409]: pulseaudio: no process found Dec 30 01:58:09 smsl-n100 sudo[1437]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:09 smsl-n100 volumio[804]: info: TidalConnect service stoped! Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Found cast device: Chromecast-8144dd793ba102f16a073c4d5941e2a9 Dec 30 01:58:09 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:09 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1448 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 30 01:58:09 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:09 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 01:58:09 smsl-n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 30 01:58:09 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetState Dec 30 01:58:09 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 30 01:58:09 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:09 smsl-n100 systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Successfully called chroot. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Successfully dropped privileges. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Successfully limited resources. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Running. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Canary thread running. Dec 30 01:58:09 smsl-n100 rtkit-daemon[1449]: Watchdog thread running. Dec 30 01:58:09 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1449 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 30 01:58:09 smsl-n100 systemd[1]: Starting Authorization Manager... Dec 30 01:58:09 smsl-n100 volumio[804]: info: Shairport-Sync Started Dec 30 01:58:09 smsl-n100 volumio[804]: Error adding Membership: Error: addMembership EINVAL Dec 30 01:58:09 smsl-n100 volumio[804]: info: Shairport-Sync Started Dec 30 01:58:09 smsl-n100 volumio[804]: info: Shairport-Sync Started Dec 30 01:58:09 smsl-n100 volumio[804]: info: MRS: Found cast device: Google-Cast-Group-ac1a6b199443477c88da02377ee57dd3 Dec 30 01:58:09 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:10 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:10 smsl-n100 polkitd[1452]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 30 01:58:10 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 30 01:58:10 smsl-n100 systemd[1]: Started Authorization Manager. Dec 30 01:58:10 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:10 smsl-n100 volumio[804]: info: Successfully retrieved User Session From TIDAL Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Found cast device: Chromecast-b968232c3ffc513768b5f46eca2a8052 Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Found cast device: SHIELD-Android-TV-61645d6d61836f7bd0d03a77664190db Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Getting audio outputs on start Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Requesting all other devices output Dec 30 01:58:10 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:10 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:10 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:10 smsl-n100 volumio[804]: error: Help! Some callbacks for volumioPushState are crashing! Dec 30 01:58:10 smsl-n100 volumio[804]: error: Cannot read property 'sendVolumeMute' of undefined Dec 30 01:58:10 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:10 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:10 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:10 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:10 smsl-n100 sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 01:58:10 smsl-n100 sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Found cast device: Google-Cast-Group-1C6D7838146743F19847D190294D7904 Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:10 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Dec 30 01:58:10 smsl-n100 volumio[804]: info: MRS: Found cast device: HK-Citation-300-326308d82f6ba99454d9ded1276417b0 Dec 30 01:58:10 smsl-n100 volumio[804]: info: Adding audio output: Dec 30 01:58:10 smsl-n100 sudo[1462]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:10 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.43' (uid=1000 pid=1448 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 30 01:58:11 smsl-n100 volumio[804]: info: Successfully retrieved User Subscription From TIDAL Dec 30 01:58:11 smsl-n100 systemd[1]: Starting Bluetooth service... Dec 30 01:58:11 smsl-n100 volumio[804]: info: Adding TIDAL to Browse Sources Dec 30 01:58:11 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 01:58:11 smsl-n100 volumio[804]: info: [1767088691050] CoreMusicLibrary::Adding element TIDAL Dec 30 01:58:11 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 01:58:11 smsl-n100 volumio[804]: Cannot find translation for source TIDAL Dec 30 01:58:11 smsl-n100 volumio[804]: info: Stopping AccessToken refresher cron Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: Bluetooth daemon 5.50 Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 30 01:58:11 smsl-n100 pulseaudio[1448]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 30 01:58:11 smsl-n100 pulseaudio[1448]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 30 01:58:11 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.bluez' Dec 30 01:58:11 smsl-n100 systemd[1]: Started Bluetooth service. Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Applying permissions Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Setting BT discoverable and pairable Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: Starting SDP server Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: Excluding (cli) sap Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: kernel lacks bnep-protocol support Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: System does not support network plugin Dec 30 01:58:11 smsl-n100 bluetoothd[1468]: Bluetooth management interface 1.14 initialized Dec 30 01:58:11 smsl-n100 dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.44' (uid=0 pid=1468 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 30 01:58:11 smsl-n100 volumio[804]: info: AccessToken refresher cron started Dec 30 01:58:11 smsl-n100 volumio[804]: info: Adding TIDAL REST API Endpoints Dec 30 01:58:11 smsl-n100 volumio[804]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 30 01:58:11 smsl-n100 pulseaudio[1448]: 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 Dec 30 01:58:11 smsl-n100 systemd[1]: Starting Hostname Service... Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# -e power on Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Invalid command in menu main: -e Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Use "help" for a list of available commands in a menu. Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Use "menu " if you want to enter any submenu. Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Use "back" if you want to return to menu main. Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# agent on Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Failed to register agent object Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# discoverable on Dec 30 01:58:11 smsl-n100 volumiobt[1409]: No default controller available Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# pairable on Dec 30 01:58:11 smsl-n100 volumiobt[1409]: No default controller available Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# agent NoInputNoOutput Dec 30 01:58:11 smsl-n100 volumiobt[1409]: Failed to register agent object Dec 30 01:58:11 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# default-agent Dec 30 01:58:11 smsl-n100 volumiobt[1409]: No agent is registered Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [bluetooth]# quit Dec 30 01:58:11 smsl-n100 volumiobt[1409]: [59B blob data] Dec 30 01:58:11 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:11 smsl-n100 dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 30 01:58:11 smsl-n100 systemd[1]: Started Hostname Service. Dec 30 01:58:12 smsl-n100 volumio[804]: info: Executing endpoint tc_getconfig Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 30 01:58:12 smsl-n100 vtcs[1465]: STARTING TidalConnect services, version: 1.4.0.34 Dec 30 01:58:12 smsl-n100 vtcs[1465]: STARTED TidalConnect services. Dec 30 01:58:12 smsl-n100 volumio[804]: info: Executing endpoint tc_connect Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 30 01:58:12 smsl-n100 volumio[804]: info: Connecting to TidalConnect Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::servicePushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:12 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:12 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:12 smsl-n100 volumio[804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::servicePushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:12 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:12 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:12 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:12 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:12 smsl-n100 volumio[804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 30 01:58:13 smsl-n100 volumio[804]: info: TidalConnect service started! Dec 30 01:58:13 smsl-n100 volumio[804]: info: Completed starting Core Plugins Dec 30 01:58:13 smsl-n100 volumio[804]: info: ------------------------------------------- Dec 30 01:58:13 smsl-n100 volumio[804]: info: ----- MyVolumio plugins startup ---- Dec 30 01:58:13 smsl-n100 volumio[804]: info: ------------------------------------------- Dec 30 01:58:13 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 30 01:58:14 smsl-n100 mpd[1419]: Dec 30 01:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 01:58:14 smsl-n100 systemd[1]: Started Music Player Daemon. Dec 30 01:58:14 smsl-n100 volumio[804]: Upnp client error: Error: This socket has been ended by the other party Dec 30 01:58:14 smsl-n100 mpd_monitor.sh[553]: MPD restarted due to no mpc output. Dec 30 01:58:14 smsl-n100 volumio[804]: error: MPD error: The expression evaluated to a falsy value: Dec 30 01:58:14 smsl-n100 volumio[804]: assert.ok(self.idling) Dec 30 01:58:14 smsl-n100 volumio[804]: error: The expression evaluated to a falsy value: Dec 30 01:58:14 smsl-n100 volumio[804]: assert.ok(self.idling) Dec 30 01:58:14 smsl-n100 volumio[804]: Upnp client error: Error: This socket has been ended by the other party Dec 30 01:58:14 smsl-n100 volumio[804]: error: updateQueue error: null Dec 30 01:58:14 smsl-n100 volumio[1283]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 30 01:58:15 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Dec 30 01:58:15 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Dec 30 01:58:15 smsl-n100 sudo[1421]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:15 smsl-n100 volumio[804]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Dec 30 01:58:19 smsl-n100 volumio[804]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 30 01:58:20 smsl-n100 sudo[1562]: 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.154/Music /mnt/NAS/Music Dec 30 01:58:20 smsl-n100 sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:20 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.154/Music Dec 30 01:58:20 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. Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 30 01:58:22 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 30 01:58:22 smsl-n100 volumio[1283]: ...............................................................................................................++++ Dec 30 01:58:24 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 30 01:58:24 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 30 01:58:24 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:24 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:24 smsl-n100 volumio[804]: info: Starting MyVolumio Remote Streaming Endpoints Dec 30 01:58:24 smsl-n100 volumio[804]: info: MyVolumio login type: Token Dec 30 01:58:24 smsl-n100 volumio[804]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 30 01:58:24 smsl-n100 volumio[804]: 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 30 01:58:24 smsl-n100 volumio[804]: error: MyVolumio Custom Token format not valid, refreshing it Dec 30 01:58:25 smsl-n100 volumio[804]: info: MyVolumio login type: Token Dec 30 01:58:25 smsl-n100 volumio[804]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 30 01:58:26 smsl-n100 volumio[804]: info: MyVolumio token set successfully Dec 30 01:58:26 smsl-n100 volumio[804]: info: MYVOLUMIO: Adding device Dec 30 01:58:26 smsl-n100 volumio[804]: info: MYVOLUMIO: Evaluating Server Dec 30 01:58:26 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Dec 30 01:58:26 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Dec 30 01:58:26 smsl-n100 sudo[1562]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:27 smsl-n100 volumio[804]: info: MyVolumio status changed Dec 30 01:58:27 smsl-n100 volumio[804]: info: Streaming services startup Dec 30 01:58:27 smsl-n100 volumio[804]: info: Starting Streaming Daemon Dec 30 01:58:27 smsl-n100 volumio[804]: info: Removing browser output: myVolumio user plan is not superstar Dec 30 01:58:27 smsl-n100 volumio[804]: info: Removing audio output: Dec 30 01:58:27 smsl-n100 volumio[804]: info: Stoppping Tunnel 1 Dec 30 01:58:27 smsl-n100 sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 01:58:27 smsl-n100 sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:27 smsl-n100 sudo[1604]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:27 smsl-n100 sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 30 01:58:27 smsl-n100 sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:27 smsl-n100 volumio[804]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Dec 30 01:58:27 smsl-n100 sudo[1607]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:27 smsl-n100 volumio[804]: info: Setting Geolocation for MyVolumio to us2 Dec 30 01:58:27 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:27 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:27 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:27 smsl-n100 volumio[804]: error: Cannot start Volumio Streaming Daemon Dec 30 01:58:27 smsl-n100 volumio[804]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 01:58:27 smsl-n100 volumio[804]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 01:58:27 smsl-n100 volumio[804]: info: Remote SSH Stopped Dec 30 01:58:27 smsl-n100 volumio[804]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 30 01:58:28 smsl-n100 volumio[804]: info: Updating MyVolumio device info Dec 30 01:58:28 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:28 smsl-n100 volumio[804]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 30 01:58:31 smsl-n100 volumio[804]: info: MYVOLUMIO: Adding device Dec 30 01:58:31 smsl-n100 volumio[804]: info: MYVOLUMIO: Evaluating Server Dec 30 01:58:32 smsl-n100 sudo[1628]: 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.154/Music /mnt/NAS/Music Dec 30 01:58:32 smsl-n100 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:32 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.154/Music Dec 30 01:58:32 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. Dec 30 01:58:32 smsl-n100 volumio[804]: info: Setting Geolocation for MyVolumio to us2 Dec 30 01:58:32 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:32 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:32 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:32 smsl-n100 volumio[804]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 30 01:58:33 smsl-n100 volumio[804]: info: Updating MyVolumio device info Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:33 smsl-n100 volumio[804]: error: MyVolumio Plugin failed to start in a timely fashion Dec 30 01:58:33 smsl-n100 volumio[804]: [Metrics] CommandRouter: 64s 739.95ms Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::volumiosetStartupVolume Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:33 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:34 smsl-n100 volumio[804]: info: CoreCommandRouter::Close All Modals sent Dec 30 01:58:34 smsl-n100 volumio[804]: info: CoreCommandRouter::Close All Modals sent Dec 30 01:58:34 smsl-n100 volumio[804]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 30 01:58:35 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 30 01:58:35 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 01:58:35 smsl-n100 volumio[804]: xcb_connection_has_error() returned true Dec 30 01:58:35 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 30 01:58:36 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 01:58:36 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 01:58:36 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 01:58:36 smsl-n100 volumio-remote-updater[564]: No test mode Dec 30 01:58:36 smsl-n100 volumio-remote-updater[564]: No alpha test mode Dec 30 01:58:36 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 30 01:58:37 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Dec 30 01:58:37 smsl-n100 dhcpcd[890]: eth0: carrier lost Dec 30 01:58:37 smsl-n100 dhcpcd[932]: eth0: carrier lost Dec 30 01:58:37 smsl-n100 avahi-daemon[560]: Withdrawing address record for 192.168.0.204 on eth0. Dec 30 01:58:37 smsl-n100 avahi-daemon[560]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.204. Dec 30 01:58:37 smsl-n100 avahi-daemon[560]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 30 01:58:37 smsl-n100 dhcpcd[932]: eth0: deleting route to 192.168.0.0/24 Dec 30 01:58:37 smsl-n100 dhcpcd[932]: eth0: deleting default route via 192.168.0.1 Dec 30 01:58:37 smsl-n100 volumio[804]: info: Discovery: A device disappeared from network Dec 30 01:58:37 smsl-n100 dhcpcd[890]: eth0: deleting route to 192.168.0.0/24 Dec 30 01:58:37 smsl-n100 dhcpcd[890]: eth0: deleting default route via 192.168.0.1 Dec 30 01:58:37 smsl-n100 ifplugd(eth0)[768]: Link beat lost. Dec 30 01:58:39 smsl-n100 volumio[1283]: ...........................................................................................................................................................................................................................++++ Dec 30 01:58:39 smsl-n100 volumio[1283]: e is 65537 (0x010001) Dec 30 01:58:39 smsl-n100 volumio[1283]: writing RSA key Dec 30 01:58:39 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 01:58:39 smsl-n100 volumio[804]: 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 30 01:58:39 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetState Dec 30 01:58:39 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:39 smsl-n100 ntpd[931]: Deleting interface #3 eth0, 192.168.0.204#123, interface stats: received=122, sent=122, dropped=0, active_time=69 secs Dec 30 01:58:39 smsl-n100 ntpd[931]: 66.59.198.178 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 23.142.248.8 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 24.144.88.190 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 104.131.155.175 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 45.63.54.13 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 172.235.60.8 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 172.234.37.140 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 97.107.136.23 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 172.232.15.202 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 173.11.101.155 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 158.51.99.19 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 96.245.170.99 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 172.104.28.175 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 172.233.177.198 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 75.186.74.61 local addr 192.168.0.204 -> Dec 30 01:58:39 smsl-n100 ntpd[931]: 23.95.49.216 local addr 192.168.0.204 -> Dec 30 01:58:40 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 30 01:58:40 smsl-n100 dhcpcd[890]: eth0: carrier acquired Dec 30 01:58:40 smsl-n100 dhcpcd[932]: eth0: carrier acquired Dec 30 01:58:40 smsl-n100 dhcpcd[890]: eth0: IAID 2d:4b:ac:99 Dec 30 01:58:40 smsl-n100 dhcpcd[932]: eth0: IAID 2d:4b:ac:99 Dec 30 01:58:41 smsl-n100 volumio[804]: info: BOOT COMPLETED Dec 30 01:58:41 smsl-n100 ifplugd(eth0)[768]: Link beat detected. Dec 30 01:58:41 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Dec 30 01:58:41 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -115 Dec 30 01:58:41 smsl-n100 sudo[1628]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 01:58:41 smsl-n100 volumio[804]: info: Not Reporting Auto name since its the default one Dec 30 01:58:41 smsl-n100 volumio[804]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 30 01:58:41 smsl-n100 volumio[804]: xcb_connection_has_error() returned true Dec 30 01:58:41 smsl-n100 sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 30 01:58:41 smsl-n100 sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:41 smsl-n100 sudo[1722]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:41 smsl-n100 sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 30 01:58:41 smsl-n100 sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:41 smsl-n100 dhcpcd[932]: eth0: soliciting a DHCP lease Dec 30 01:58:41 smsl-n100 dhcpcd[932]: eth0: offered 192.168.0.204 from 192.168.0.1 Dec 30 01:58:41 smsl-n100 dhcpcd[932]: eth0: probing address 192.168.0.204/24 Dec 30 01:58:41 smsl-n100 systemd[1]: Started RAAT DAEMON. Dec 30 01:58:41 smsl-n100 sudo[1724]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:41 smsl-n100 volumio[804]: info: Raat Daemon started successfully Dec 30 01:58:41 smsl-n100 dhcpcd[932]: eth0: soliciting an IPv6 router Dec 30 01:58:41 smsl-n100 volumio[804]: info: Executing endpoint restartRAATSocket Dec 30 01:58:41 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 30 01:58:41 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:41 smsl-n100 volumio[804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 30 01:58:41 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded. Dec 30 01:58:41 smsl-n100 dhcpcd[890]: eth0: soliciting a DHCP lease Dec 30 01:58:41 smsl-n100 dhcpcd[890]: eth0: offered 192.168.0.204 from 192.168.0.1 Dec 30 01:58:41 smsl-n100 dhcpcd[890]: eth0: soliciting an IPv6 router Dec 30 01:58:41 smsl-n100 dhcpcd[890]: eth0: probing address 192.168.0.204/24 Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 01:58:44 smsl-n100 volumio[804]: info: RAAT: Requesting Headphone Status Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:44 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:44 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:44 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:44 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:44 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:44 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:44 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:44 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:45 smsl-n100 volumio[804]: info: Reporting MCU Network Status: 0 Dec 30 01:58:45 smsl-n100 volumio[804]: info: Volumio Network Manager: Network status updated: 0 Dec 30 01:58:45 smsl-n100 volumio[804]: info: CoreStateMachine::pushState Dec 30 01:58:45 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:45 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioPushState Dec 30 01:58:45 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output update for this device Dec 30 01:58:45 smsl-n100 volumio[804]: info: MRS: Pushing multiroomSync output Dec 30 01:58:45 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:45 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 30 01:58:45 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 100, message: undefined Dec 30 01:58:45 smsl-n100 volumio[804]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 01:58:45 smsl-n100 volumio[804]: error: Serial API: Failed to decode command: 0, message: undefined Dec 30 01:58:46 smsl-n100 sudo[1757]: 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.154/Music /mnt/NAS/Music Dec 30 01:58:46 smsl-n100 sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 01:58:46 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.154/Music Dec 30 01:58:46 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. Dec 30 01:58:46 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Dec 30 01:58:46 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Dec 30 01:58:46 smsl-n100 sudo[1757]: pam_unix(sudo:session): session closed for user root Dec 30 01:58:46 smsl-n100 volumio[804]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 30 01:58:46 smsl-n100 dhcpcd[932]: eth0: leased 192.168.0.204 for 86400 seconds Dec 30 01:58:46 smsl-n100 dhcpcd[932]: eth0: adding route to 192.168.0.0/24 Dec 30 01:58:46 smsl-n100 dhcpcd[932]: eth0: adding default route via 192.168.0.1 Dec 30 01:58:46 smsl-n100 avahi-daemon[560]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.204. Dec 30 01:58:46 smsl-n100 avahi-daemon[560]: New relevant interface eth0.IPv4 for mDNS. Dec 30 01:58:46 smsl-n100 avahi-daemon[560]: Registering new address record for 192.168.0.204 on eth0.IPv4. Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: Q700B-447b286f536522e01c8b83ddf1f13d18 Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: SHIELD-Android-TV-61645d6d61836f7bd0d03a77664190db Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: Chromecast-b968232c3ffc513768b5f46eca2a8052 Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: Google-Cast-Group-ac1a6b199443477c88da02377ee57dd3 Dec 30 01:58:46 smsl-n100 ntpd[931]: ntpd exiting on signal 15 (Terminated) Dec 30 01:58:46 smsl-n100 systemd[1]: Stopping Network Time Service... Dec 30 01:58:46 smsl-n100 systemd[1]: ntp.service: Succeeded. Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: HK-Citation-300-326308d82f6ba99454d9ded1276417b0 Dec 30 01:58:46 smsl-n100 systemd[1]: Stopped Network Time Service. Dec 30 01:58:46 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:46 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 01:58:46 smsl-n100 systemd[1]: Starting Network Time Service... Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: Google-Cast-Group-1C6D7838146743F19847D190294D7904 Dec 30 01:58:46 smsl-n100 volumio[804]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 01:58:46 smsl-n100 volumio[804]: info: MRS: Found cast device: Chromecast-8144dd793ba102f16a073c4d5941e2a9 Dec 30 01:58:46 smsl-n100 ntpd[1801]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 30 01:58:46 smsl-n100 ntpd[1801]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 30 01:58:46 smsl-n100 systemd[1]: Started Network Time Service. Dec 30 01:58:46 smsl-n100 ntpd[1808]: proto: precision = 1.167 usec (-20) Dec 30 01:58:46 smsl-n100 ntpd[1808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 30 01:58:46 smsl-n100 ntpd[1808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 30 01:58:46 smsl-n100 ntpd[1808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1099 days ago Dec 30 01:58:46 smsl-n100 ntpd[1808]: Listen and drop on 0 v6wildcard [::]:123 Dec 30 01:58:46 smsl-n100 ntpd[1808]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 30 01:58:46 smsl-n100 ntpd[1808]: Listen normally on 2 lo 127.0.0.1:123 Dec 30 01:58:46 smsl-n100 ntpd[1808]: Listen normally on 3 eth0 192.168.0.204:123 Dec 30 01:58:46 smsl-n100 ntpd[1808]: Listening on routing socket on fd #20 for interface updates Dec 30 01:58:46 smsl-n100 ntpd[1808]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 30 01:58:46 smsl-n100 ntpd[1808]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 30 01:58:47 smsl-n100 dhcpcd[890]: eth0: leased 192.168.0.204 for 86400 seconds Dec 30 01:58:47 smsl-n100 dhcpcd[890]: eth0: adding route to 192.168.0.0/24 Dec 30 01:58:47 smsl-n100 dhcpcd[890]: eth0: adding default route via 192.168.0.1 Dec 30 01:58:47 smsl-n100 volumio[804]: info: Discovery: adding 7fcee92b-d6b0-4df6-82b4-f92f7afa085f Dec 30 01:58:47 smsl-n100 volumio[804]: info: Discovery: Found device SMSL N100 Dec 30 01:58:47 smsl-n100 volumio[804]: info: CoreCommandRouter::volumioGetState Dec 30 01:58:47 smsl-n100 volumio[804]: info: CorePlayQueue::getTrack 0 Dec 30 01:58:47 smsl-n100 volumio[804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 01:58:47 smsl-n100 volumio[804]: Error: The value NaN is not a number Dec 30 01:58:47 smsl-n100 volumio[804]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Dec 30 01:58:47 smsl-n100 volumio[804]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Dec 30 01:58:47 smsl-n100 volumio[804]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Dec 30 01:58:47 smsl-n100 volumio[804]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Dec 30 01:58:47 smsl-n100 volumio[804]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Dec 30 01:58:47 smsl-n100 volumio[804]: at Browser.emit (events.js:400:28) Dec 30 01:58:47 smsl-n100 volumio[804]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Dec 30 01:58:47 smsl-n100 volumio[804]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Dec 30 01:58:47 smsl-n100 volumio[804]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Dec 30 01:58:47 smsl-n100 volumio[804]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Dec 30 01:58:47 smsl-n100 volumio[804]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Dec 30 01:58:47 smsl-n100 volumio[804]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Dec 30 01:58:47 smsl-n100 volumio[804]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Dec 30 01:58:47 smsl-n100 volumio[804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 01:58:47 smsl-n100 ntpd[1808]: ntpd exiting on signal 15 (Terminated) Dec 30 01:58:47 smsl-n100 systemd[1]: Stopping Network Time Service... Dec 30 01:58:47 smsl-n100 systemd[1]: ntp.service: Succeeded. Dec 30 01:58:47 smsl-n100 systemd[1]: Stopped Network Time Service. Dec 30 01:58:47 smsl-n100 systemd[1]: Starting Network Time Service... Dec 30 01:58:47 smsl-n100 ntpd[1849]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 30 01:58:47 smsl-n100 ntpd[1849]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 30 01:58:47 smsl-n100 systemd[1]: Started Network Time Service. Dec 30 01:58:47 smsl-n100 ntpd[1855]: proto: precision = 1.166 usec (-20) Dec 30 01:58:47 smsl-n100 ntpd[1855]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 30 01:58:47 smsl-n100 ntpd[1855]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 30 01:58:47 smsl-n100 ntpd[1855]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1099 days ago Dec 30 01:58:47 smsl-n100 ntpd[1855]: Listen and drop on 0 v6wildcard [::]:123 Dec 30 01:58:47 smsl-n100 ntpd[1855]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 30 01:58:47 smsl-n100 ntpd[1855]: Listen normally on 2 lo 127.0.0.1:123 Dec 30 01:58:47 smsl-n100 ntpd[1855]: Listen normally on 3 eth0 192.168.0.204:123 Dec 30 01:58:47 smsl-n100 ntpd[1855]: Listening on routing socket on fd #20 for interface updates Dec 30 01:58:47 smsl-n100 ntpd[1855]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 30 01:58:47 smsl-n100 ntpd[1855]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 30 01:58:48 smsl-n100 sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 01:57 Dec 30 01:58:48 smsl-n100 sudo[1859]: 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"