-- Logs begin at Sun 2025-05-11 08:32:06 JST, end at Sun 2025-05-11 08:34:21 JST. -- May 11 08:33:01 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:01 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:01 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:01 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:01 smsl-n100 sudo[1034]: pam_unix(sudo:session): session closed for user root May 11 08:33:01 smsl-n100 sudo[1032]: pam_unix(sudo:session): session closed for user root May 11 08:33:02 smsl-n100 volumio[962]: info: Starting Streaming Service Transparent Proxy May 11 08:33:02 smsl-n100 volumio[962]: info: Plugin tidalconnect is not enabled May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "webradio"... May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "i2s_dacs"... May 11 08:33:02 smsl-n100 volumio[962]: info: I2S DAC not set, start Auto-detection May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "volumiodiscovery"... May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** For more information see May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 08:33:02 smsl-n100 volumio[962]: *** WARNING *** For more information see May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** For more information see May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 08:33:02 smsl-n100 node[962]: *** WARNING *** For more information see May 11 08:33:02 smsl-n100 volumio[962]: info: Applying required configuration parameters for plugin volumiodiscovery May 11 08:33:02 smsl-n100 volumio[962]: info: Discovery: Started advertising with name: SMSL N100 May 11 08:33:02 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 08:33:02 smsl-n100 volumio[962]: info: Plugin multiroom is not enabled May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "outputs"... May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "albumart"... May 11 08:33:02 smsl-n100 volumio[962]: info: Plugin example_plugin is not enabled May 11 08:33:02 smsl-n100 volumio[962]: info: Loading plugin "hi_res_audio"... May 11 08:33:03 smsl-n100 volumio[962]: Forking 3 albumart workers May 11 08:33:05 smsl-n100 volumio[962]: STREAMING PROXY: Starting server on port 3245 May 11 08:33:05 smsl-n100 volumio[962]: Node JS runtime: 14 May 11 08:33:06 smsl-n100 volumio[962]: Starting albumart workers May 11 08:33:06 smsl-n100 volumio[962]: Starting albumart workers May 11 08:33:06 smsl-n100 volumio[962]: Starting albumart workers May 11 08:33:06 smsl-n100 volumio[962]: info: Applying required configuration parameters for plugin hi_res_audio May 11 08:33:06 smsl-n100 volumio[962]: info: Loading plugin "inputs"... May 11 08:33:07 smsl-n100 volumio-remote-updater[504]: [2025-05-11 08:33:07] [connect] Successful connection May 11 08:33:08 smsl-n100 volumio[962]: info: Loading plugin "qobuz"... May 11 08:33:11 smsl-n100 volumio[962]: info: Loading plugin "smart_inputs"... May 11 08:33:11 smsl-n100 volumio[962]: info: Loading plugin "tidal"... May 11 08:33:14 smsl-n100 volumio[962]: info: Loading plugin "oem_helper"... May 11 08:33:15 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Date not found in response May 11 08:33:15 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Retrying in 5 seconds... May 11 08:33:16 smsl-n100 volumio[962]: info: Applying required configuration parameters for plugin oem_helper May 11 08:33:16 smsl-n100 volumio[962]: info: Loading plugin "smslcontrol"... May 11 08:33:16 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 May 11 08:33:16 smsl-n100 volumio[962]: info: Adding this device properties May 11 08:33:16 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 11 08:33:16 smsl-n100 volumio[962]: info: Setting Additional Device Volumio Properties: [object Object] May 11 08:33:16 smsl-n100 volumio[962]: info: Loading plugin "updater_comm"... May 11 08:33:16 smsl-n100 volumio[962]: info: Plugin mpdemulation is not enabled May 11 08:33:16 smsl-n100 volumio[962]: info: Loading plugin "rest_api"... May 11 08:33:17 smsl-n100 volumio[962]: info: Loading plugin "websocket"... May 11 08:33:17 smsl-n100 volumio[962]: info: Starting Socket.io Server version 2.3.0 May 11 08:33:17 smsl-n100 volumio[962]: info: Loading i18n strings for locale ja May 11 08:33:17 smsl-n100 volumio[962]: Updating browse sources language May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::initPlayerControls May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:33:17 smsl-n100 volumio[962]: Express server listening on port 3000 May 11 08:33:17 smsl-n100 volumio[962]: [Metrics] WebUI: 30s 116.39ms May 11 08:33:17 smsl-n100 volumio[962]: info: CoreStateMachine::resetVolumioState May 11 08:33:17 smsl-n100 volumio[962]: info: CoreStateMachine::getcurrentVolume May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioRetrievevolume May 11 08:33:17 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:17 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:17 smsl-n100 sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 08:33:17 smsl-n100 sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:17 smsl-n100 sudo[1218]: pam_unix(sudo:session): session closed for user root May 11 08:33:17 smsl-n100 volumio[962]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 11 08:33:17 smsl-n100 sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 08:33:17 smsl-n100 sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:17 smsl-n100 volumio[962]: info: Completed loading Core Plugins May 11 08:33:17 smsl-n100 volumio[962]: info: Preparing to generate the ALSA configuration file May 11 08:33:17 smsl-n100 sudo[1220]: pam_unix(sudo:session): session closed for user root May 11 08:33:17 smsl-n100 volumio[962]: info: Volumio Network Manager: Network status updated: 0 May 11 08:33:17 smsl-n100 volumio[962]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds May 11 08:33:17 smsl-n100 volumio[962]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds May 11 08:33:18 smsl-n100 volumio[962]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 11 08:33:18 smsl-n100 volumio[962]: info: Reloading queue from file May 11 08:33:18 smsl-n100 volumio[962]: info: Asound.conf file unchanged, so no further update is needed May 11 08:33:18 smsl-n100 volumio[962]: info: Output device has changed, restarting MPD May 11 08:33:18 smsl-n100 sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 08:33:18 smsl-n100 sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 volumio[962]: info: Output device has changed, restarting Shairport Sync May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:18 smsl-n100 sudo[1232]: pam_unix(sudo:session): session closed for user root May 11 08:33:18 smsl-n100 sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 08:33:18 smsl-n100 sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 systemd[1]: Stopping Music Player Daemon... May 11 08:33:18 smsl-n100 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 08:33:18 smsl-n100 volumio[962]: info: ___________ START PLUGINS ___________ May 11 08:33:18 smsl-n100 volumio[962]: info: ControllerMpd::onStart: Initializing MPD May 11 08:33:18 smsl-n100 volumio[962]: info: Creating MPD Configuration file May 11 08:33:18 smsl-n100 sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:33:18 smsl-n100 sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 08:33:18 smsl-n100 sudo[1241]: pam_unix(sudo:session): session closed for user root May 11 08:33:18 smsl-n100 volumio[962]: info: [1746919998583] CoreMusicLibrary::Adding element DLNAサーバー May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 08:33:18 smsl-n100 sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 08:33:18 smsl-n100 sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 volumio[962]: info: Adding METAVOLUMIO REST API Endpoints May 11 08:33:18 smsl-n100 volumio[962]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 11 08:33:18 smsl-n100 volumio[962]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 11 08:33:18 smsl-n100 volumio[962]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 11 08:33:18 smsl-n100 volumio[962]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:18 smsl-n100 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:18 smsl-n100 systemd[1]: mpd.service: Succeeded. May 11 08:33:18 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 11 08:33:18 smsl-n100 systemd[1]: Starting Music Player Daemon... May 11 08:33:18 smsl-n100 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 08:33:18 smsl-n100 volumio[962]: info: [1746919998866] CoreMusicLibrary::Adding element Last_100 May 11 08:33:18 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 08:33:18 smsl-n100 volumio[962]: info: Streaming services startup May 11 08:33:18 smsl-n100 volumio[962]: info: Starting Streaming Daemon May 11 08:33:18 smsl-n100 sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 08:33:18 smsl-n100 sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 sudo[1250]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 08:33:18 smsl-n100 sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:18 smsl-n100 sudo[1250]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 11 08:33:19 smsl-n100 sudo[1250]: pam_unix(sudo:session): session closed for user root May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 08:33:19 smsl-n100 sudo[1254]: pam_unix(sudo:session): session closed for user root May 11 08:33:19 smsl-n100 volumio[962]: info: [1746919999006] CoreMusicLibrary::Adding element Webradio May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:33:19 smsl-n100 volumio[962]: info: Initializing BBC Radios May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:19 smsl-n100 volumio[962]: error: Hi Res Audio Failed Login: Missing Login Data May 11 08:33:19 smsl-n100 volumio[962]: info: Adding HIGHRESAUDIO REST API Endpoints May 11 08:33:19 smsl-n100 volumio[962]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 11 08:33:19 smsl-n100 volumio[962]: info: Initializing Serial Communication on port /dev/ttyS1 May 11 08:33:19 smsl-n100 volumio[962]: info: Touch Event Listener Process Starting May 11 08:33:19 smsl-n100 volumio[962]: info: Adding inputs REST Endpoints May 11 08:33:19 smsl-n100 volumio[962]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 11 08:33:19 smsl-n100 volumio[962]: info: Scanning Audio Inputs May 11 08:33:19 smsl-n100 sudo[1273]: pam_unix(sudo:auth): conversation failed May 11 08:33:19 smsl-n100 sudo[1273]: pam_unix(sudo:auth): auth could not identify password for [volumio] May 11 08:33:19 smsl-n100 volumio[962]: info: Checking against Known Cards name May 11 08:33:19 smsl-n100 volumio[962]: info: Adding Server instance for streaming May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:33:19 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:19 smsl-n100 volumio[962]: info: Initializing GPIOs May 11 08:33:19 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 May 11 08:33:19 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 May 11 08:33:19 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 May 11 08:33:19 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 May 11 08:33:19 smsl-n100 volumio[962]: info: Starting Button Listener May 11 08:33:19 smsl-n100 volumio[962]: info: Turning off Green LED May 11 08:33:19 smsl-n100 volumio[962]: info: Tuning on Blue LED May 11 08:33:19 smsl-n100 volumio[962]: info: Signaling System Ready May 11 08:33:19 smsl-n100 volumio[962]: info: Volumio Calling Home May 11 08:33:19 smsl-n100 volumio[962]: info: Stopping AccessToken refresher cron for QOBUZ May 11 08:33:20 smsl-n100 volumio[962]: info: AccessToken refresher cron started for QOBUZ May 11 08:33:20 smsl-n100 volumio[962]: info: Stopping AccessToken refresher cron May 11 08:33:20 smsl-n100 volumio[962]: info: AccessToken refresher cron started May 11 08:33:20 smsl-n100 volumio[962]: info: Adding TIDAL REST API Endpoints May 11 08:33:20 smsl-n100 volumio[962]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 11 08:33:20 smsl-n100 volumio[962]: info: Adding QOBUZ REST API Endpoints May 11 08:33:20 smsl-n100 volumio[962]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::setRepeat false single undefined May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::setRandom null May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 volumio[962]: info: Setting Device type: NanoPi NEO 3 May 11 08:33:20 smsl-n100 volumio[962]: info: Serial port opened successfully May 11 08:33:20 smsl-n100 volumio[962]: info: Sending serial start messages May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: Reporting MCU Network Status: 0 May 11 08:33:20 smsl-n100 volumio[962]: info: Touch Event Listener Process Closed May 11 08:33:20 smsl-n100 volumio[962]: error: Cannot start Volumio Streaming Daemon May 11 08:33:20 smsl-n100 volumio[962]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 08:33:20 smsl-n100 volumio[962]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 08:33:20 smsl-n100 volumio[962]: info: MPD Permissions set May 11 08:33:20 smsl-n100 volumio[962]: info: MPD Permissions set May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 11 08:33:20 smsl-n100 volumio[962]: info: Setting Device Volume Override May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 11 08:33:20 smsl-n100 volumio[962]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 11 08:33:20 smsl-n100 volumio[962]: info: Disabling external Volume Control May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Fetching time from Volumio... May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:20 smsl-n100 sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 11 08:33:20 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:20 smsl-n100 sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:20 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:20 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 0, message: undefined May 11 08:33:20 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. May 11 08:33:20 smsl-n100 volumio[962]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 sudo[1305]: pam_unix(sudo:session): session closed for user root May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:20 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:21 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:21 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:21 smsl-n100 volumio[962]: info: Upmpdcli Daemon Started May 11 08:33:21 smsl-n100 volumio[962]: info: Starting Shairport Sync May 11 08:33:21 smsl-n100 volumio[962]: info: Starting Shairport Sync May 11 08:33:21 smsl-n100 volumio[962]: info: Starting Shairport Sync May 11 08:33:21 smsl-n100 sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 08:33:21 smsl-n100 sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:21 smsl-n100 sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 08:33:21 smsl-n100 sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:21 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:21 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:21 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 08:33:21 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:21 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:21 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:21 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:21 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:21 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 11 08:33:21 smsl-n100 sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 08:33:21 smsl-n100 sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:21 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 08:33:21 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 08:33:21 smsl-n100 sudo[1325]: pam_unix(sudo:session): session closed for user root May 11 08:33:21 smsl-n100 volumio[962]: info: Shairport-Sync Started May 11 08:33:21 smsl-n100 volumio[962]: Error adding Membership: Error: addMembership EINVAL May 11 08:33:21 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 08:33:21 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 11 08:33:21 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 11 08:33:21 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 08:33:21 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 08:33:21 smsl-n100 sudo[1330]: pam_unix(sudo:session): session closed for user root May 11 08:33:21 smsl-n100 sudo[1327]: pam_unix(sudo:session): session closed for user root May 11 08:33:21 smsl-n100 volumio[962]: info: Shairport-Sync Started May 11 08:33:21 smsl-n100 volumio[962]: info: Shairport-Sync Started May 11 08:33:22 smsl-n100 volumio-remote-updater[504]: [2025-05-11 08:33:22] [connect] Successful connection May 11 08:33:22 smsl-n100 volumio-remote-updater[504]: [2025-05-11 08:33:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746920002 101 May 11 08:33:23 smsl-n100 volumio[962]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 11 08:33:23 smsl-n100 mpd[1261]: May 11 08:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 08:33:23 smsl-n100 sudo[1338]: 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.12/USB /mnt/NAS/USB May 11 08:33:23 smsl-n100 sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:23 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/USB May 11 08:33:23 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 11 08:33:23 smsl-n100 sudo[1340]: 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.12/NAS /mnt/NAS/NAS May 11 08:33:23 smsl-n100 sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:23 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/NAS May 11 08:33:23 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 11 08:33:23 smsl-n100 systemd[1]: Started Music Player Daemon. May 11 08:33:23 smsl-n100 volumio[962]: Upnp client error: Error: This socket has been ended by the other party May 11 08:33:23 smsl-n100 sudo[1243]: pam_unix(sudo:session): session closed for user root May 11 08:33:23 smsl-n100 sudo[1234]: pam_unix(sudo:session): session closed for user root May 11 08:33:23 smsl-n100 volumio[962]: info: Completed starting Core Plugins May 11 08:33:23 smsl-n100 volumio[962]: info: ------------------------------------------- May 11 08:33:23 smsl-n100 volumio[962]: info: ----- MyVolumio plugins startup ---- May 11 08:33:23 smsl-n100 volumio[962]: info: ------------------------------------------- May 11 08:33:23 smsl-n100 volumio[962]: info: [MyVolumio PluginManager] Fetching plans data.... May 11 08:33:23 smsl-n100 volumio[962]: info: MPD running with PID1261 May 11 08:33:23 smsl-n100 volumio[962]: ,establishing connection May 11 08:33:23 smsl-n100 volumio[962]: error: MPD error: The expression evaluated to a falsy value: May 11 08:33:23 smsl-n100 volumio[962]: assert.ok(self.idling) May 11 08:33:23 smsl-n100 volumio[962]: error: The expression evaluated to a falsy value: May 11 08:33:23 smsl-n100 volumio[962]: assert.ok(self.idling) May 11 08:33:23 smsl-n100 volumio[962]: error: MPD error: The expression evaluated to a falsy value: May 11 08:33:23 smsl-n100 volumio[962]: assert.ok(self.idling) May 11 08:33:23 smsl-n100 volumio[962]: error: The expression evaluated to a falsy value: May 11 08:33:23 smsl-n100 volumio[962]: assert.ok(self.idling) May 11 08:33:23 smsl-n100 volumio[962]: error: updateQueue error: null May 11 08:33:24 smsl-n100 volumio[962]: info: Reporting MCU Network Status: 2 May 11 08:33:24 smsl-n100 volumio[962]: info: Volumio Network Manager: Network status updated: 2 May 11 08:33:24 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:24 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:24 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:24 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:24 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:24 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:24 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:24 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 2, message: undefined May 11 08:33:25 smsl-n100 volumio[1311]: Generating RSA private key, 4096 bit long modulus (2 primes) May 11 08:33:28 smsl-n100 volumio[962]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 11 08:33:29 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:29 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:29 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:29 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:29 smsl-n100 sudo[1340]: pam_unix(sudo:session): session closed for user root May 11 08:33:29 smsl-n100 sudo[1338]: pam_unix(sudo:session): session closed for user root May 11 08:33:29 smsl-n100 volumio[962]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds May 11 08:33:29 smsl-n100 volumio[962]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds May 11 08:33:31 smsl-n100 volumio[1311]: .........................................................................................++++ May 11 08:33:34 smsl-n100 sudo[1398]: 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.12/NAS /mnt/NAS/NAS May 11 08:33:34 smsl-n100 sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:34 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/NAS May 11 08:33: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. May 11 08:33:34 smsl-n100 sudo[1401]: 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.12/USB /mnt/NAS/USB May 11 08:33:34 smsl-n100 sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:34 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/USB May 11 08:33: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. May 11 08:33:38 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off May 11 08:33:38 smsl-n100 dhcpcd[550]: eth0: carrier acquired May 11 08:33:38 smsl-n100 dhcpcd[550]: eth0: IAID 18:d7:dd:48 May 11 08:33:38 smsl-n100 dhcpcd[550]: eth0: soliciting an IPv6 router May 11 08:33:38 smsl-n100 dhcpcd[550]: eth0: rebinding lease of 192.168.11.2 May 11 08:33:38 smsl-n100 ifplugd(eth0)[760]: Link beat detected. May 11 08:33:39 smsl-n100 ifplugd(eth0)[760]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 11 08:33:39 smsl-n100 ifplugd(eth0)[760]: client: sending commands to master dhcpcd process May 11 08:33:39 smsl-n100 dhcpcd[1430]: sending commands to master dhcpcd process May 11 08:33:39 smsl-n100 dhcpcd[550]: control command: /sbin/dhcpcd eth0 May 11 08:33:39 smsl-n100 ifplugd(eth0)[760]: Program executed successfully. May 11 08:33:39 smsl-n100 ntpd[749]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 11 08:33:39 smsl-n100 volumio[1311]: ...............................................................................................................++++ May 11 08:33:39 smsl-n100 volumio[1311]: e is 65537 (0x010001) May 11 08:33:39 smsl-n100 volumio[1311]: writing RSA key May 11 08:33:40 smsl-n100 volumio[962]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 11 08:33:40 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Date not found in response May 11 08:33:40 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Retrying in 5 seconds... May 11 08:33:41 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:41 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:41 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:41 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:41 smsl-n100 sudo[1398]: pam_unix(sudo:session): session closed for user root May 11 08:33:41 smsl-n100 sudo[1401]: pam_unix(sudo:session): session closed for user root May 11 08:33:41 smsl-n100 volumio[962]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds May 11 08:33:41 smsl-n100 volumio[962]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds May 11 08:33:43 smsl-n100 volumio[962]: error: MyVolumio Plugin failed to start in a timely fashion May 11 08:33:43 smsl-n100 volumio[962]: [Metrics] CommandRouter: 54s 507.24ms May 11 08:33:43 smsl-n100 volumio[962]: info: CoreCommandRouter::volumiosetStartupVolume May 11 08:33:43 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:33:43 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:43 smsl-n100 volumio[962]: info: CoreCommandRouter::Close All Modals sent May 11 08:33:43 smsl-n100 volumio[962]: info: CoreCommandRouter::Close All Modals sent May 11 08:33:43 smsl-n100 dhcpcd[550]: eth0: probing for an IPv4LL address May 11 08:33:43 smsl-n100 dhcpcd[550]: eth0: DHCP lease expired May 11 08:33:43 smsl-n100 dhcpcd[550]: eth0: soliciting a DHCP lease May 11 08:33:44 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 11 08:33:44 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 11 08:33:44 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 11 08:33:45 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Fetching time from Volumio... May 11 08:33:46 smsl-n100 sudo[1499]: 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.12/NAS /mnt/NAS/NAS May 11 08:33:46 smsl-n100 sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:46 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/NAS May 11 08:33: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. May 11 08:33:46 smsl-n100 sudo[1501]: 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.12/USB /mnt/NAS/USB May 11 08:33:46 smsl-n100 sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:46 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/USB May 11 08:33: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. May 11 08:33:48 smsl-n100 dhcpcd[550]: eth0: using IPv4LL address 169.254.91.138 May 11 08:33:48 smsl-n100 dhcpcd[550]: eth0: adding route to 169.254.0.0/16 May 11 08:33:48 smsl-n100 dhcpcd[550]: eth0: adding default route May 11 08:33:48 smsl-n100 dhcpcd[550]: wlan0: deleting default route May 11 08:33:48 smsl-n100 avahi-daemon[529]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.91.138. May 11 08:33:48 smsl-n100 avahi-daemon[529]: New relevant interface eth0.IPv4 for mDNS. May 11 08:33:48 smsl-n100 avahi-daemon[529]: Registering new address record for 169.254.91.138 on eth0.IPv4. May 11 08:33:49 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:33:49 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:33:49 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 08:33:49 smsl-n100 volumio[962]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=84c0790b080955305557a380828caabf" http://updates.volumio.org/downloader-v1/track-device May 11 08:33:49 smsl-n100 volumio[962]: % Total % Received % Xferd Average Speed Time Time Time Current May 11 08:33:49 smsl-n100 volumio[962]: Dload Upload Total Spent Left Speed May 11 08:33:49 smsl-n100 volumio[962]: [1.6K blob data] May 11 08:33:49 smsl-n100 volumio[962]: retrying in 5 seconds, trial 0 May 11 08:33:49 smsl-n100 volumio[962]: info: Volumio Calling Home May 11 08:33:50 smsl-n100 ntpd[749]: Listen normally on 4 eth0 169.254.91.138:123 May 11 08:33:50 smsl-n100 ntpd[749]: new interface(s) found: waking up resolver May 11 08:33:50 smsl-n100 volumio[962]: info: BOOT COMPLETED May 11 08:33:52 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:52 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:33:52 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:52 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:33:52 smsl-n100 sudo[1501]: pam_unix(sudo:session): session closed for user root May 11 08:33:52 smsl-n100 sudo[1499]: pam_unix(sudo:session): session closed for user root May 11 08:33:52 smsl-n100 volumio[962]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds May 11 08:33:52 smsl-n100 volumio[962]: info: Cannot mount NAS USB at system boot, trial number 4 ,retrying in 5 seconds May 11 08:33:54 smsl-n100 volumio[962]: info: Reporting MCU Network Status: 3 May 11 08:33:54 smsl-n100 volumio[962]: info: Volumio Network Manager: Network status updated: 3 May 11 08:33:54 smsl-n100 volumio[962]: info: CoreStateMachine::pushState May 11 08:33:54 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:33:54 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioPushState May 11 08:33:54 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:54 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: MAXVOL, message: May 11 08:33:54 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 100, message: undefined May 11 08:33:54 smsl-n100 volumio[962]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 11 08:33:54 smsl-n100 volumio[962]: error: Serial API: Failed to decode command: 3, message: undefined May 11 08:33:57 smsl-n100 sudo[1540]: 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.12/NAS /mnt/NAS/NAS May 11 08:33:57 smsl-n100 sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:57 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/NAS May 11 08:33:57 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 11 08:33:57 smsl-n100 sudo[1542]: 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.12/USB /mnt/NAS/USB May 11 08:33:57 smsl-n100 sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:33:57 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.12/USB May 11 08:33:57 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 11 08:33:59 smsl-n100 ntpd[749]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 11 08:34:03 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 11 08:34:03 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:34:03 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -113 May 11 08:34:03 smsl-n100 sudo[1542]: pam_unix(sudo:session): session closed for user root May 11 08:34:03 smsl-n100 sudo[1540]: pam_unix(sudo:session): session closed for user root May 11 08:34:03 smsl-n100 volumio[962]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 11 08:34:03 smsl-n100 volumio[962]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 11 08:34:05 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Date not found in response May 11 08:34:05 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Retrying in 5 seconds... May 11 08:34:10 smsl-n100 volumio-time-update[502]: volumio-time-update-util: Fetching time from Volumio... May 11 08:34:12 smsl-n100 dhcpcd[550]: eth0: offered 192.168.1.19 from 192.168.1.1 `IX2106' May 11 08:34:12 smsl-n100 dhcpcd[550]: eth0: probing address 192.168.1.19/24 May 11 08:34:17 smsl-n100 dhcpcd[550]: eth0: leased 192.168.1.19 for 14400 seconds May 11 08:34:17 smsl-n100 dhcpcd[550]: eth0: adding route to 192.168.1.0/24 May 11 08:34:17 smsl-n100 dhcpcd[550]: eth0: changing default route via 192.168.1.1 May 11 08:34:17 smsl-n100 avahi-daemon[529]: Registering new address record for 192.168.1.19 on eth0.IPv4. May 11 08:34:17 smsl-n100 ntpd[749]: ntpd exiting on signal 15 (Terminated) May 11 08:34:17 smsl-n100 systemd[1]: Stopping Network Time Service... May 11 08:34:17 smsl-n100 systemd[1]: ntp.service: Succeeded. May 11 08:34:17 smsl-n100 systemd[1]: Stopped Network Time Service. May 11 08:34:17 smsl-n100 systemd[1]: Starting Network Time Service... May 11 08:34:17 smsl-n100 ntpd[1655]: ntpd 4.2.8p12@1.3728-o (1): Starting May 11 08:34:17 smsl-n100 ntpd[1655]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 11 08:34:17 smsl-n100 systemd[1]: Started Network Time Service. May 11 08:34:17 smsl-n100 dhcpcd[550]: eth0: deleting route to 169.254.0.0/16 May 11 08:34:17 smsl-n100 ntpd[1661]: proto: precision = 1.166 usec (-20) May 11 08:34:17 smsl-n100 avahi-daemon[529]: Withdrawing address record for 169.254.91.138 on eth0. May 11 08:34:17 smsl-n100 avahi-daemon[529]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.91.138. May 11 08:34:17 smsl-n100 avahi-daemon[529]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.19. May 11 08:34:17 smsl-n100 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 11 08:34:17 smsl-n100 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 11 08:34:17 smsl-n100 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 865 days ago May 11 08:34:17 smsl-n100 ntpd[1661]: Listen and drop on 0 v6wildcard [::]:123 May 11 08:34:17 smsl-n100 ntpd[1661]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 11 08:34:17 smsl-n100 ntpd[1661]: Listen normally on 2 lo 127.0.0.1:123 May 11 08:34:17 smsl-n100 ntpd[1661]: Listen normally on 3 eth0 192.168.1.19:123 May 11 08:34:17 smsl-n100 ntpd[1661]: Listen normally on 4 wlan0 169.254.33.207:123 May 11 08:34:17 smsl-n100 ntpd[1661]: Listening on routing socket on fd #21 for interface updates May 11 08:34:17 smsl-n100 ntpd[1661]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 11 08:34:17 smsl-n100 ntpd[1661]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 08:34:17 smsl-n100 volumio[962]: info: Discovery: Getting this device information May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioGetState May 11 08:34:17 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 08:34:17 smsl-n100 volumio[962]: verbose: New Socket.io Connection to 192.168.1.19:3000 from 192.168.1.8 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioGetState May 11 08:34:17 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 11 08:34:17 smsl-n100 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 11 08:34:18 smsl-n100 ntpd[1661]: Soliciting pool server 129.250.35.251 May 11 08:34:19 smsl-n100 ntpd[1661]: Soliciting pool server 160.16.113.133 May 11 08:34:19 smsl-n100 volumio[962]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=84c0790b080955305557a380828caabf" http://updates.volumio.org/downloader-v1/track-device May 11 08:34:19 smsl-n100 volumio[962]: % Total % Received % Xferd Average Speed Time Time Time Current May 11 08:34:19 smsl-n100 volumio[962]: Dload Upload Total Spent Left Speed May 11 08:34:19 smsl-n100 volumio[962]: [1.6K blob data] May 11 08:34:19 smsl-n100 volumio[962]: retrying in 5 seconds, trial 1 May 11 08:34:19 smsl-n100 volumio[962]: info: Volumio Calling Home May 11 08:34:20 smsl-n100 ntpd[1661]: Soliciting pool server 133.130.121.141 May 11 08:34:20 smsl-n100 volumio[962]: info: Discovery: adding 2a96ea59-075c-4c4c-8bcd-0a4edfd023cf May 11 08:34:20 smsl-n100 volumio[962]: info: Discovery: Found device SMSL N100 May 11 08:34:20 smsl-n100 volumio[962]: info: CoreCommandRouter::volumioGetState May 11 08:34:20 smsl-n100 volumio[962]: info: CorePlayQueue::getTrack 0 May 11 08:34:20 smsl-n100 volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:34:20 smsl-n100 volumio[962]: Error: The value NaN is not a number May 11 08:34:20 smsl-n100 volumio[962]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 11 08:34:20 smsl-n100 volumio[962]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 11 08:34:20 smsl-n100 volumio[962]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 11 08:34:20 smsl-n100 volumio[962]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 11 08:34:20 smsl-n100 volumio[962]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 11 08:34:20 smsl-n100 volumio[962]: at Browser.emit (events.js:400:28) May 11 08:34:20 smsl-n100 volumio[962]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 11 08:34:20 smsl-n100 volumio[962]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 11 08:34:20 smsl-n100 volumio[962]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 11 08:34:20 smsl-n100 volumio[962]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 11 08:34:20 smsl-n100 volumio[962]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 11 08:34:20 smsl-n100 volumio[962]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 11 08:34:20 smsl-n100 volumio[962]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 11 08:34:20 smsl-n100 volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:34:20 smsl-n100 ntpd[1661]: Soliciting pool server 172.237.12.44 May 11 08:34:20 smsl-n100 ntpd[1661]: Soliciting pool server 162.159.200.123 May 11 08:34:20 smsl-n100 ntpd[1661]: Soliciting pool server 202.181.103.212 May 11 08:34:21 smsl-n100 sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 08:33 May 11 08:34:21 smsl-n100 sudo[1691]: 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"