-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-10-20 14:15:00 CST. -- Oct 20 14:14:00 smsl-n100 sudo[9333]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 volumio[9293]: info: Applying required configuration parameters for plugin multiroom Oct 20 14:14:01 smsl-n100 sudo[9376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 20 14:14:01 smsl-n100 sudo[9376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:01 smsl-n100 sudo[9376]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 volumio[9293]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Oct 20 14:14:01 smsl-n100 volumio[9293]: info: MRS: MultiRoom plugin initialized Oct 20 14:14:01 smsl-n100 volumio[9293]: info: MRS: STOPPING SNAPCLIENT Oct 20 14:14:01 smsl-n100 volumio[9293]: info: MRS: Snap server stop Oct 20 14:14:01 smsl-n100 sudo[9392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 20 14:14:01 smsl-n100 sudo[9392]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:01 smsl-n100 volumio[9293]: info: MRS: STOPPING volumioStreaming Oct 20 14:14:01 smsl-n100 sudo[9395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 20 14:14:01 smsl-n100 sudo[9395]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:01 smsl-n100 sudo[9392]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 sudo[9397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 20 14:14:01 smsl-n100 volumio[9293]: info: Loading plugin "outputs"... Oct 20 14:14:01 smsl-n100 sudo[9397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:01 smsl-n100 volumio[9293]: info: Loading plugin "albumart"... Oct 20 14:14:01 smsl-n100 sudo[9400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 20 14:14:01 smsl-n100 sudo[9395]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 sudo[9400]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:01 smsl-n100 sudo[9400]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 sudo[9397]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:01 smsl-n100 volumio[9293]: info: Plugin example_plugin is not enabled Oct 20 14:14:01 smsl-n100 volumio[9293]: info: Loading plugin "hi_res_audio"... Oct 20 14:14:02 smsl-n100 volumio[9293]: Forking 3 albumart workers Oct 20 14:14:02 smsl-n100 ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 14:14:04 smsl-n100 volumio[9293]: Starting albumart workers Oct 20 14:14:04 smsl-n100 volumio[9293]: Starting albumart workers Oct 20 14:14:04 smsl-n100 volumio[9293]: Starting albumart workers Oct 20 14:14:04 smsl-n100 volumio[9293]: info: Applying required configuration parameters for plugin hi_res_audio Oct 20 14:14:04 smsl-n100 volumio[9293]: info: Loading plugin "inputs"... Oct 20 14:14:06 smsl-n100 volumio[9293]: info: Loading plugin "qobuz"... Oct 20 14:14:08 smsl-n100 volumio[9293]: info: Loading plugin "smart_inputs"... Oct 20 14:14:08 smsl-n100 volumio[9293]: info: Loading plugin "tidal"... Oct 20 14:14:09 smsl-n100 volumio-remote-updater[584]: [2025-10-20 14:14:09] [connect] Successful connection Oct 20 14:14:10 smsl-n100 volumio[9293]: info: Loading plugin "oem_helper"... Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Applying required configuration parameters for plugin oem_helper Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Loading plugin "smslcontrol"... Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Adding this device properties Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Setting Additional Device Volumio Properties: [object Object] Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Loading plugin "updater_comm"... Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Plugin mpdemulation is not enabled Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Loading plugin "rest_api"... Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Loading plugin "websocket"... Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Starting Socket.io Server version 2.3.0 Oct 20 14:14:12 smsl-n100 volumio[9293]: info: Loading i18n strings for locale en Oct 20 14:14:12 smsl-n100 volumio[9293]: Updating browse sources language Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::initPlayerControls Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 14:14:12 smsl-n100 volumio[9293]: Express server listening on port 3000 Oct 20 14:14:12 smsl-n100 volumio[9293]: [Metrics] WebUI: 26s 629.54ms Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreStateMachine::resetVolumioState Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreStateMachine::getcurrentVolume Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioRetrievevolume Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:12 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:13 smsl-n100 sudo[9524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 14:14:13 smsl-n100 sudo[9524]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:13 smsl-n100 sudo[9524]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:13 smsl-n100 sudo[9527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:13 smsl-n100 sudo[9527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:13 smsl-n100 volumio[9293]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Completed loading Core Plugins Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Preparing to generate the ALSA configuration file Oct 20 14:14:13 smsl-n100 sudo[9527]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Volumio Network Manager: Network status updated: 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: MRS: Removed streaming files Oct 20 14:14:13 smsl-n100 volumio[9293]: info: MRS: volumioStreaming STOPPED Oct 20 14:14:13 smsl-n100 volumio[9293]: info: MRS: SNAPSERVER STOPPED Oct 20 14:14:13 smsl-n100 volumio[9293]: info: MRS: SNAPCLIENT STOPPED Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 20 14:14:13 smsl-n100 volumio[9293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Reading ALSA contributions from plugins. Oct 20 14:14:13 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.161 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Oct 20 14:14:13 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.161 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Oct 20 14:14:13 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.161 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Oct 20 14:14:13 smsl-n100 volumio-remote-updater[584]: [2025-10-20 14:14:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1760940849 101 Oct 20 14:14:13 smsl-n100 volumio[9293]: 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: 4 Oct 20 14:14:13 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.161 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:13 smsl-n100 volumio[9293]: info: Reloading queue from file Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreStateMachine::setRepeat null single undefined Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreStateMachine::setRandom null Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:13 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Setting Device type: NanoPi NEO 3 Oct 20 14:14:14 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.161 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 20 14:14:14 smsl-n100 sudo[9535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 20 14:14:14 smsl-n100 sudo[9535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Asound.conf file unchanged, so no further update is needed Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Output device has changed, restarting MPD Oct 20 14:14:14 smsl-n100 sudo[9535]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:14 smsl-n100 sudo[9538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 20 14:14:14 smsl-n100 sudo[9538]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 sudo[9538]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Output device has changed, restarting Shairport Sync Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:14 smsl-n100 sudo[9541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 20 14:14:14 smsl-n100 sudo[9541]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 systemd[1]: Stopping Music Player Daemon... Oct 20 14:14:14 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 20 14:14:14 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 14:14:14 smsl-n100 volumio[9293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 20 14:14:14 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:14 smsl-n100 volumio[9293]: info: ___________ START PLUGINS ___________ Oct 20 14:14:14 smsl-n100 volumio[9293]: info: ControllerMpd::onStart: Initializing MPD Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Creating MPD Configuration file Oct 20 14:14:14 smsl-n100 sudo[9551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 20 14:14:14 smsl-n100 sudo[9551]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 14:14:14 smsl-n100 sudo[9551]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 20 14:14:14 smsl-n100 volumio[9293]: info: [1760940854625] CoreMusicLibrary::Adding element Media Servers Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 14:14:14 smsl-n100 sudo[9546]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 20 14:14:14 smsl-n100 sudo[9546]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 sudo[9554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 20 14:14:14 smsl-n100 sudo[9546]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:14 smsl-n100 sudo[9554]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 20 14:14:14 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 20 14:14:14 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 20 14:14:14 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Adding METAVOLUMIO REST API Endpoints Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:14 smsl-n100 sudo[9560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Oct 20 14:14:14 smsl-n100 sudo[9560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 sudo[9560]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:14 smsl-n100 volumio[9293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:14 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:14 smsl-n100 sudo[9558]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 20 14:14:14 smsl-n100 sudo[9558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:14 smsl-n100 sudo[9558]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Preparing CD Folders Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding CD REST API Endpoints Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Starting UDEV Watcher for CD Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Detecting CD presence with UDEV Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 20 14:14:15 smsl-n100 volumio[9293]: info: [1760940855059] CoreMusicLibrary::Adding element Last_100 Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Starting RAAT Plugin Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Additional UI Settings Added for plugin music_service/raat Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Registering DSP Elements listener and retrieving current ones Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Additional DSP elements updated Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Updating RAAT Signal Path Oct 20 14:14:15 smsl-n100 volumio[9293]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Streaming services startup Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Starting Streaming Daemon Oct 20 14:14:15 smsl-n100 sudo[9571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 20 14:14:15 smsl-n100 sudo[9571]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:15 smsl-n100 sudo[9571]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 20 14:14:15 smsl-n100 volumio[9293]: info: [1760940855315] CoreMusicLibrary::Adding element Webradio Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 14:14:15 smsl-n100 sudo[9578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 20 14:14:15 smsl-n100 sudo[9578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Initializing BBC Radios Oct 20 14:14:15 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Oct 20 14:14:15 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 20 14:14:15 smsl-n100 systemd[1]: vtcs.service: Succeeded. Oct 20 14:14:15 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Oct 20 14:14:15 smsl-n100 sudo[9578]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 20 14:14:15 smsl-n100 volumio[9293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 20 14:14:15 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:15 smsl-n100 volumio[9293]: error: Hi Res Audio Failed Login: Missing Login Data Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Initializing Serial Communication on port /dev/ttyS1 Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Touch Event Listener Process Starting Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding inputs REST Endpoints Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Scanning Audio Inputs Oct 20 14:14:15 smsl-n100 sudo[9596]: pam_unix(sudo:auth): conversation failed Oct 20 14:14:15 smsl-n100 sudo[9596]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Checking against Known Cards name Oct 20 14:14:15 smsl-n100 volumio[9293]: info: Adding Server instance for streaming Oct 20 14:14:16 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 14:14:16 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Initializing GPIOs Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Starting Button Listener Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Turning off Green LED Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Tuning on Blue LED Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Signaling System Ready Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Volumio Calling Home Oct 20 14:14:16 smsl-n100 volumio[9293]: (node:9293) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 20 14:14:16 smsl-n100 volumio[9293]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Stopping AccessToken refresher cron for QOBUZ Oct 20 14:14:16 smsl-n100 volumio[9293]: info: AccessToken refresher cron started for QOBUZ Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Stopping AccessToken refresher cron Oct 20 14:14:16 smsl-n100 volumio[9293]: info: AccessToken refresher cron started Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Adding TIDAL REST API Endpoints Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Adding QOBUZ REST API Endpoints Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 20 14:14:16 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Date not found in response Oct 20 14:14:16 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Retrying in 5 seconds... Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Serial port opened successfully Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Sending serial start messages Oct 20 14:14:16 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Reporting MCU Network Status: 0 Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Touch Event Listener Process Closed Oct 20 14:14:16 smsl-n100 volumio[9293]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 20 14:14:16 smsl-n100 volumio[9293]: error: Cannot start Volumio Streaming Daemon Oct 20 14:14:16 smsl-n100 volumio[9293]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 20 14:14:16 smsl-n100 volumio[9293]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 20 14:14:16 smsl-n100 volumio[9293]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Oct 20 14:14:16 smsl-n100 volumio[9293]: Can't get device info: No such device Oct 20 14:14:16 smsl-n100 volumio[9293]: info: MPD Permissions set Oct 20 14:14:16 smsl-n100 volumio[9293]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 20 14:14:16 smsl-n100 volumio[9293]: info: MPD Permissions set Oct 20 14:14:16 smsl-n100 volumio[9293]: info: Upmpdcli Daemon Started Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Setting Device Volume Override Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Disabling external Volume Control Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 0, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Starting Shairport Sync Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Starting Shairport Sync Oct 20 14:14:17 smsl-n100 sudo[9628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 20 14:14:17 smsl-n100 sudo[9628]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:17 smsl-n100 volumio[9293]: info: Starting Shairport Sync Oct 20 14:14:17 smsl-n100 sudo[9631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 20 14:14:17 smsl-n100 sudo[9631]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:17 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:17 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:17 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:17 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:17 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 20 14:14:17 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 20 14:14:18 smsl-n100 sudo[9634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 20 14:14:18 smsl-n100 sudo[9634]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 20 14:14:18 smsl-n100 sudo[9631]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9628]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:18 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:18 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:18 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:18 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:18 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 20 14:14:18 smsl-n100 sudo[9638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 20 14:14:18 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 20 14:14:18 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 20 14:14:18 smsl-n100 sudo[9638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Shairport-Sync Started Oct 20 14:14:18 smsl-n100 volumio[9293]: Error adding Membership: Error: addMembership EINVAL Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Shairport-Sync Started Oct 20 14:14:18 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 20 14:14:18 smsl-n100 sudo[9634]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9638]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Volumio BT Module successfully started Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Shairport-Sync Started Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 20 14:14:18 smsl-n100 sudo[9644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 20 14:14:18 smsl-n100 sudo[9644]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9644]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:18 smsl-n100 sudo[9649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9649]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:18 smsl-n100 sudo[9657]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9657]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:18 smsl-n100 sudo[9663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9663]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 sudo[9669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 14:14:18 smsl-n100 sudo[9669]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9669]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 volumio[9293]: info: TidalConnect service stoped! Oct 20 14:14:18 smsl-n100 sudo[9673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:18 smsl-n100 sudo[9673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:18 smsl-n100 sudo[9673]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 20 14:14:18 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 20 14:14:18 smsl-n100 volumio[9293]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 20 14:14:19 smsl-n100 volumio[9293]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:19 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:19 smsl-n100 sudo[9684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 20 14:14:19 smsl-n100 sudo[9684]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 20 14:14:19 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Oct 20 14:14:19 smsl-n100 sudo[9684]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:19 smsl-n100 volumio[9293]: info: Executing endpoint tc_getconfig Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 20 14:14:19 smsl-n100 vtcs[9686]: STARTING TidalConnect services, version: 1.4.0.34 Oct 20 14:14:19 smsl-n100 vtcs[9686]: STARTED TidalConnect services. Oct 20 14:14:19 smsl-n100 volumio[9293]: info: Executing endpoint tc_connect Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 20 14:14:19 smsl-n100 volumio[9293]: info: Connecting to TidalConnect Oct 20 14:14:19 smsl-n100 volumio[9293]: info: CoreCommandRouter::servicePushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:20 smsl-n100 volumio[9293]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreCommandRouter::servicePushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:20 smsl-n100 volumio[9293]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 20 14:14:20 smsl-n100 mpd[9567]: Oct 20 14:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 20 14:14:20 smsl-n100 systemd[1]: Started Music Player Daemon. Oct 20 14:14:20 smsl-n100 sudo[9554]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 sudo[9541]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 sudo[9708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 14:14:20 smsl-n100 sudo[9708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:20 smsl-n100 sudo[9708]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 sudo[9711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:20 smsl-n100 sudo[9711]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:20 smsl-n100 sudo[9711]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 volumio[9293]: error: MPD error: The expression evaluated to a falsy value: Oct 20 14:14:20 smsl-n100 volumio[9293]: assert.ok(self.idling) Oct 20 14:14:20 smsl-n100 volumio[9293]: error: The expression evaluated to a falsy value: Oct 20 14:14:20 smsl-n100 volumio[9293]: assert.ok(self.idling) Oct 20 14:14:20 smsl-n100 volumio[9293]: info: MPD running with PID9567 Oct 20 14:14:20 smsl-n100 volumio[9293]: ,establishing connection Oct 20 14:14:20 smsl-n100 volumio[9293]: error: updateQueue error: null Oct 20 14:14:20 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:20 smsl-n100 volumio[9293]: error: updateQueue error: null Oct 20 14:14:20 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 14:14:20 smsl-n100 volumio[9293]: info: MRS: Getting audio outputs on start Oct 20 14:14:20 smsl-n100 volumio[9293]: info: MRS: Requesting all other devices output Oct 20 14:14:20 smsl-n100 sudo[9716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 14:14:20 smsl-n100 sudo[9716]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:20 smsl-n100 sudo[9716]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 sudo[9719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:20 smsl-n100 sudo[9719]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:20 smsl-n100 sudo[9719]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:20 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 20 14:14:21 smsl-n100 volumio[9293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 20 14:14:21 smsl-n100 volumio[9293]: info: Received Get System Info Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:21 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:21 smsl-n100 volumio[9293]: info: Listing playlists Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 14:14:21 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 20 14:14:21 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Fetching time from Volumio... Oct 20 14:14:22 smsl-n100 volumio[9293]: info: TidalConnect service started! Oct 20 14:14:22 smsl-n100 volumio[9293]: info: Completed starting Core Plugins Oct 20 14:14:22 smsl-n100 volumio[9293]: info: ------------------------------------------- Oct 20 14:14:22 smsl-n100 volumio[9293]: info: ----- MyVolumio plugins startup ---- Oct 20 14:14:22 smsl-n100 volumio[9293]: info: ------------------------------------------- Oct 20 14:14:22 smsl-n100 volumio[9293]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 20 14:14:22 smsl-n100 ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 14:14:22 smsl-n100 volumio[9293]: info: Received Get System Info Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 14:14:22 smsl-n100 volumio[9293]: info: Discovery: Getting this device information Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 14:14:22 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 20 14:14:25 smsl-n100 volumio[9293]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 14:14:25 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 20 14:14:25 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 20 14:14:25 smsl-n100 sudo[9745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 20 14:14:25 smsl-n100 sudo[9745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9745]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 sudo[9751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:25 smsl-n100 sudo[9751]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9751]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 sudo[9758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:25 smsl-n100 sudo[9758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9758]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 sudo[9764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 20 14:14:25 smsl-n100 sudo[9764]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9764]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 sudo[9771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 14:14:25 smsl-n100 sudo[9771]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9771]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 20 14:14:25 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 20 14:14:25 smsl-n100 sudo[9774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:25 smsl-n100 sudo[9774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:25 smsl-n100 sudo[9774]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:25 smsl-n100 sudo[9777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 20 14:14:25 smsl-n100 sudo[9777]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:26 smsl-n100 sudo[9777]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:30 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 20 14:14:39 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 20 14:14:39 smsl-n100 volumio[9293]: info: Saving new wireless network Oct 20 14:14:39 smsl-n100 sudo[9799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 20 14:14:39 smsl-n100 sudo[9799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:39 smsl-n100 sudo[9799]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:39 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 20 14:14:39 smsl-n100 volumio[9293]: info: Discovery: Restarting Advertising due to device name change Oct 20 14:14:39 smsl-n100 sudo[9802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 20 14:14:39 smsl-n100 sudo[9802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:39 smsl-n100 systemd[1]: Starting Wireless Services... Oct 20 14:14:39 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 20 14:14:40 smsl-n100 wireless.js[9804]: Cleaning previous... Oct 20 14:14:40 smsl-n100 volumio[9293]: info: Discovery: A device disappeared from network Oct 20 14:14:40 smsl-n100 volumio[9293]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 20 14:14:40 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Oct 20 14:14:40 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 20 14:14:40 smsl-n100 dnsmasq[2753]: exiting on receipt of SIGTERM Oct 20 14:14:40 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Oct 20 14:14:40 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Oct 20 14:14:40 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 20 14:14:40 smsl-n100 dhcpcd[941]: wlan0: carrier lost Oct 20 14:14:41 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 20 14:14:41 smsl-n100 systemd[1]: hostapd.service: Succeeded. Oct 20 14:14:41 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 20 14:14:41 smsl-n100 dhcpcd[941]: wlan0: deleting route to 169.254.0.0/16 Oct 20 14:14:41 smsl-n100 dhcpcd[941]: wlan0: deleting default route Oct 20 14:14:41 smsl-n100 avahi-daemon[582]: Withdrawing address record for 169.254.226.189 on wlan0. Oct 20 14:14:41 smsl-n100 sudo[9836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 20 14:14:41 smsl-n100 sudo[9836]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 avahi-daemon[582]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 20 14:14:41 smsl-n100 avahi-daemon[582]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 20 14:14:41 smsl-n100 avahi-daemon[582]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 20 14:14:41 smsl-n100 sudo[9836]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:41 smsl-n100 sudo[9846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 20 14:14:41 smsl-n100 sudo[9846]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 sudo[9846]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:41 smsl-n100 wireless.js[9804]: Stopped aP Oct 20 14:14:41 smsl-n100 sudo[9867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 20 14:14:41 smsl-n100 sudo[9867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 sudo[9867]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:41 smsl-n100 sudo[9869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 20 14:14:41 smsl-n100 sudo[9869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 sudo[9869]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:41 smsl-n100 sudo[9876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 20 14:14:41 smsl-n100 sudo[9876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 sudo[9876]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:41 smsl-n100 sudo[9878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 20 14:14:41 smsl-n100 sudo[9878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:41 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Date not found in response Oct 20 14:14:41 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Retrying in 5 seconds... Oct 20 14:14:41 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 20 14:14:41 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:41 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:41 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 20 14:14:41 smsl-n100 dhcpcd[941]: wlan0: carrier acquired Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:42 smsl-n100 dhcpcd[941]: wlan0: IAID 2b:ee:c7:9f Oct 20 14:14:42 smsl-n100 volumio[9293]: error: MyVolumio Plugin failed to start in a timely fashion Oct 20 14:14:42 smsl-n100 volumio[9293]: [Metrics] CommandRouter: 55s 10.31ms Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumiosetStartupVolume Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::Close All Modals sent Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::Close All Modals sent Oct 20 14:14:42 smsl-n100 volumio[9293]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 20 14:14:42 smsl-n100 volumio[9293]: xcb_connection_has_error() returned true Oct 20 14:14:42 smsl-n100 volumio[9293]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 20 14:14:42 smsl-n100 volumio[9293]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 20 14:14:42 smsl-n100 volumio[9293]: aplay: main:828: audio open error: No such device Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 20 14:14:42 smsl-n100 dhcpcd[941]: wlan0: soliciting an IPv6 router Oct 20 14:14:42 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:42 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:42 smsl-n100 dhcpcd[941]: wlan0: soliciting a DHCP lease Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 20 14:14:43 smsl-n100 volumio[9293]: xcb_connection_has_error() returned true Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 20 14:14:43 smsl-n100 volumio[9293]: info: Stopping RAAT Plugin Oct 20 14:14:43 smsl-n100 ntpd[683]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=1161 secs Oct 20 14:14:43 smsl-n100 ntpd[683]: Deleting interface #4 wlan0, 169.254.226.189#123, interface stats: received=0, sent=0, dropped=0, active_time=1150 secs Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 20 14:14:43 smsl-n100 sudo[9892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 20 14:14:43 smsl-n100 sudo[9892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:43 smsl-n100 sudo[9892]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:43 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 20 14:14:43 smsl-n100 volumio[9293]: info: Raat Daemon stopped successfully Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:43 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:44 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:44 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:44 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:44 smsl-n100 volumio[9293]: info: Discovery: Started advertising with name: SMSL N100 Oct 20 14:14:45 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:45 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:45 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:45 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:45 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:45 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:46 smsl-n100 sudo[9878]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:46 smsl-n100 wireless.js[9804]: Start wireless flow Oct 20 14:14:46 smsl-n100 wireless.js[9804]: Stopped hotspot (if there).. Oct 20 14:14:46 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 14:14:46 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 14:14:46 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 14:14:46 smsl-n100 sudo[9897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 20 14:14:46 smsl-n100 sudo[9897]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:46 smsl-n100 sudo[9897]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:46 smsl-n100 sudo[9900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 20 14:14:46 smsl-n100 sudo[9900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:46 smsl-n100 dhcpcd[941]: wlan0: carrier lost Oct 20 14:14:46 smsl-n100 sudo[9900]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:46 smsl-n100 wireless.js[9804]: DHCP IP Oct 20 14:14:46 smsl-n100 volumio[9293]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=39277305911774f0970d138626c386fd" http://updates.volumio.org/downloader-v1/track-device Oct 20 14:14:46 smsl-n100 volumio[9293]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 20 14:14:46 smsl-n100 volumio[9293]: Dload Upload Total Spent Left Speed Oct 20 14:14:46 smsl-n100 volumio[9293]: [1.6K blob data] Oct 20 14:14:46 smsl-n100 volumio[9293]: retrying in 5 seconds, trial 0 Oct 20 14:14:46 smsl-n100 volumio[9293]: info: Volumio Calling Home Oct 20 14:14:46 smsl-n100 wireless.js[9804]: Start ap Oct 20 14:14:46 smsl-n100 wpa_supplicant[9905]: Successfully initialized wpa_supplicant Oct 20 14:14:46 smsl-n100 sudo[9910]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 20 14:14:46 smsl-n100 dhcpcd[941]: wlan0: carrier acquired Oct 20 14:14:46 smsl-n100 sudo[9910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:46 smsl-n100 dhcpcd[9919]: sending commands to master dhcpcd process Oct 20 14:14:46 smsl-n100 sudo[9910]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:46 smsl-n100 dhcpcd[941]: wlan0: IAID 2b:ee:c7:9f Oct 20 14:14:46 smsl-n100 dhcpcd[941]: wlan0: carrier lost Oct 20 14:14:46 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Fetching time from Volumio... Oct 20 14:14:46 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Date not found in response Oct 20 14:14:46 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Retrying in 5 seconds... Oct 20 14:14:47 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:47 smsl-n100 dhcpcd[941]: control command: /sbin/dhcpcd Oct 20 14:14:47 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:47 smsl-n100 sudo[9937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:47 smsl-n100 sudo[9937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:47 smsl-n100 sudo[9937]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:47 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:47 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:48 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:48 smsl-n100 sudo[9940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:48 smsl-n100 sudo[9940]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:48 smsl-n100 sudo[9940]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:48 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:48 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:48 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:49 smsl-n100 volumio[9293]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Oct 20 14:14:49 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:49 smsl-n100 sudo[9943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:49 smsl-n100 sudo[9943]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:49 smsl-n100 sudo[9943]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:49 smsl-n100 volumio[9293]: info: BOOT COMPLETED Oct 20 14:14:49 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 20 14:14:49 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:49 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:50 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:50 smsl-n100 sudo[9946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:50 smsl-n100 sudo[9946]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:50 smsl-n100 sudo[9946]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:50 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:50 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:50 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:51 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:51 smsl-n100 sudo[9952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:51 smsl-n100 sudo[9952]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:51 smsl-n100 sudo[9952]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 14:14:51 smsl-n100 volumio[9293]: info: Not Reporting Auto name since its the default one Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 20 14:14:51 smsl-n100 volumio[9293]: xcb_connection_has_error() returned true Oct 20 14:14:51 smsl-n100 volumio[9293]: info: Configured Audio card not found, not starting RAAT Oct 20 14:14:51 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:51 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:51 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Fetching time from Volumio... Oct 20 14:14:51 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Date not found in response Oct 20 14:14:51 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Retrying in 5 seconds... Oct 20 14:14:51 smsl-n100 wpa_supplicant[9922]: wlan0: Trying to associate with f0:a7:31:b4:1d:3c (SSID='TaviSiDenisa' freq=5200 MHz) Oct 20 14:14:52 smsl-n100 wpa_supplicant[9922]: wlan0: Associated with f0:a7:31:b4:1d:3c Oct 20 14:14:52 smsl-n100 wpa_supplicant[9922]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 20 14:14:52 smsl-n100 wpa_supplicant[9922]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 20 14:14:52 smsl-n100 wpa_supplicant[9922]: wlan0: WPA: Key negotiation completed with f0:a7:31:b4:1d:3c [PTK=CCMP GTK=CCMP] Oct 20 14:14:52 smsl-n100 wpa_supplicant[9922]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:a7:31:b4:1d:3c completed [id=0 id_str=] Oct 20 14:14:52 smsl-n100 dhcpcd[941]: wlan0: carrier acquired Oct 20 14:14:52 smsl-n100 dhcpcd[941]: wlan0: IAID 2b:ee:c7:9f Oct 20 14:14:52 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:52 smsl-n100 sudo[9983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:52 smsl-n100 sudo[9983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:52 smsl-n100 sudo[9983]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:52 smsl-n100 dhcpcd[941]: wlan0: soliciting an IPv6 router Oct 20 14:14:52 smsl-n100 dhcpcd[941]: wlan0: soliciting a DHCP lease Oct 20 14:14:52 smsl-n100 dhcpcd[941]: wlan0: offered 192.168.1.134 from 192.168.1.1 Oct 20 14:14:52 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:52 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:52 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:53 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:53 smsl-n100 sudo[9986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:53 smsl-n100 sudo[9986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:53 smsl-n100 sudo[9986]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:53 smsl-n100 dhcpcd[941]: wlan0: probing address 192.168.1.134/24 Oct 20 14:14:54 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:54 smsl-n100 sudo[9989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:54 smsl-n100 sudo[9989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:54 smsl-n100 sudo[9989]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:54 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:54 smsl-n100 volumio[9293]: info: RAAT: Requesting Headphone Status Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CoreStateMachine::pushState Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:54 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioPushState Oct 20 14:14:54 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:54 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 20 14:14:54 smsl-n100 volumio[9293]: error: Serial API: Failed to decode command: 100, message: undefined Oct 20 14:14:54 smsl-n100 volumio[9293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 20 14:14:55 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:55 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:55 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:55 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:55 smsl-n100 sudo[9992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:55 smsl-n100 sudo[9992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:55 smsl-n100 sudo[9992]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:56 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:56 smsl-n100 sudo[9995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:56 smsl-n100 sudo[9995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:56 smsl-n100 sudo[9995]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:56 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:56 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:56 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:56 smsl-n100 volumio[9293]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=39277305911774f0970d138626c386fd" http://updates.volumio.org/downloader-v1/track-device Oct 20 14:14:56 smsl-n100 volumio[9293]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 20 14:14:56 smsl-n100 volumio[9293]: Dload Upload Total Spent Left Speed Oct 20 14:14:56 smsl-n100 volumio[9293]: [132B blob data] Oct 20 14:14:56 smsl-n100 volumio[9293]: retrying in 5 seconds, trial 1 Oct 20 14:14:56 smsl-n100 volumio[9293]: info: Volumio Calling Home Oct 20 14:14:56 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Fetching time from Volumio... Oct 20 14:14:56 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Date not found in response Oct 20 14:14:56 smsl-n100 volumio-time-update[558]: volumio-time-update-util: Retrying in 5 seconds... Oct 20 14:14:57 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:57 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:57 smsl-n100 sudo[10006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:57 smsl-n100 sudo[10006]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:57 smsl-n100 sudo[10006]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:57 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:57 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:58 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:58 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:58 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:58 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:58 smsl-n100 sudo[10009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:58 smsl-n100 sudo[10009]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:58 smsl-n100 sudo[10009]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:58 smsl-n100 dhcpcd[941]: wlan0: leased 192.168.1.134 for 86400 seconds Oct 20 14:14:58 smsl-n100 dhcpcd[941]: wlan0: adding route to 192.168.1.0/24 Oct 20 14:14:58 smsl-n100 avahi-daemon[582]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.134. Oct 20 14:14:58 smsl-n100 avahi-daemon[582]: New relevant interface wlan0.IPv4 for mDNS. Oct 20 14:14:58 smsl-n100 dhcpcd[941]: wlan0: adding default route via 192.168.1.1 Oct 20 14:14:58 smsl-n100 avahi-daemon[582]: Registering new address record for 192.168.1.134 on wlan0.IPv4. Oct 20 14:14:58 smsl-n100 ntpd[683]: ntpd exiting on signal 15 (Terminated) Oct 20 14:14:58 smsl-n100 systemd[1]: Stopping Network Time Service... Oct 20 14:14:58 smsl-n100 systemd[1]: ntp.service: Succeeded. Oct 20 14:14:58 smsl-n100 systemd[1]: Stopped Network Time Service. Oct 20 14:14:58 smsl-n100 systemd[1]: Starting Network Time Service... Oct 20 14:14:58 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 20 14:14:58 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 20 14:14:58 smsl-n100 volumio[9293]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 20 14:14:58 smsl-n100 volumio[9293]: info: MRS: Found cast device: Philips-UHD-Android--ee76a2ac6eea76deba220dd337cdff10 Oct 20 14:14:58 smsl-n100 volumio[9293]: info: Adding audio output: Oct 20 14:14:58 smsl-n100 ntpd[10047]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 20 14:14:58 smsl-n100 ntpd[10047]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 20 14:14:58 smsl-n100 systemd[1]: Started Network Time Service. Oct 20 14:14:58 smsl-n100 ntpd[10053]: proto: precision = 1.166 usec (-20) Oct 20 14:14:58 smsl-n100 ntpd[10053]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 20 14:14:58 smsl-n100 ntpd[10053]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 20 14:14:58 smsl-n100 ntpd[10053]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1028 days ago Oct 20 14:14:58 smsl-n100 ntpd[10053]: Listen and drop on 0 v6wildcard [::]:123 Oct 20 14:14:58 smsl-n100 ntpd[10053]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 20 14:14:58 smsl-n100 ntpd[10053]: Listen normally on 2 lo 127.0.0.1:123 Oct 20 14:14:58 smsl-n100 ntpd[10053]: Listen normally on 3 wlan0 192.168.1.134:123 Oct 20 14:14:58 smsl-n100 ntpd[10053]: Listening on routing socket on fd #20 for interface updates Oct 20 14:14:58 smsl-n100 ntpd[10053]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 20 14:14:58 smsl-n100 ntpd[10053]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 20 14:14:58 smsl-n100 volumio[9293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 20 14:14:58 smsl-n100 volumio[9293]: info: MRS: Found cast device: Nest-Audio-b061434c58b41c6e1b6c12dfea550c15 Oct 20 14:14:58 smsl-n100 volumio[9293]: info: Adding audio output: Oct 20 14:14:58 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:58 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:59 smsl-n100 wireless.js[9804]: trying... Oct 20 14:14:59 smsl-n100 volumio[9293]: info: Discovery: adding ce782533-357c-4e1e-a7a2-60857598c56f Oct 20 14:14:59 smsl-n100 volumio[9293]: info: Discovery: Found device SMSL N100 Oct 20 14:14:59 smsl-n100 volumio[9293]: info: CoreCommandRouter::volumioGetState Oct 20 14:14:59 smsl-n100 volumio[9293]: info: CorePlayQueue::getTrack 0 Oct 20 14:14:59 smsl-n100 volumio[9293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 14:14:59 smsl-n100 sudo[10056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 20 14:14:59 smsl-n100 sudo[10056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:59 smsl-n100 volumio[9293]: Error: The value NaN is not a number Oct 20 14:14:59 smsl-n100 volumio[9293]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 20 14:14:59 smsl-n100 volumio[9293]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 20 14:14:59 smsl-n100 volumio[9293]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 20 14:14:59 smsl-n100 volumio[9293]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 20 14:14:59 smsl-n100 volumio[9293]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 20 14:14:59 smsl-n100 volumio[9293]: at Browser.emit (events.js:400:28) Oct 20 14:14:59 smsl-n100 volumio[9293]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 20 14:14:59 smsl-n100 volumio[9293]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 20 14:14:59 smsl-n100 volumio[9293]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 20 14:14:59 smsl-n100 volumio[9293]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 20 14:14:59 smsl-n100 volumio[9293]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 20 14:14:59 smsl-n100 volumio[9293]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 20 14:14:59 smsl-n100 volumio[9293]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 20 14:14:59 smsl-n100 volumio[9293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 14:14:59 smsl-n100 sudo[10056]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:59 smsl-n100 wireless.js[9804]: Connected to: ----TaviSiDenisa Oct 20 14:14:59 smsl-n100 wireless.js[9804]: ---- Oct 20 14:14:59 smsl-n100 sudo[10060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 14:14:59 smsl-n100 sudo[10060]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 14:14:59 smsl-n100 sudo[10060]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:59 smsl-n100 wireless.js[9804]: ... joined AP, wlan0 IPv4 is 192.168.1.134, ipV6 is undefined Oct 20 14:14:59 smsl-n100 wireless.js[9804]: It's done! AP Oct 20 14:14:59 smsl-n100 iwconfig[10070]: Error for wireless request "Set Power Management" (8B2C) : Oct 20 14:14:59 smsl-n100 iwconfig[10070]: SET failed on device wlan0 ; Operation not permitted. Oct 20 14:14:59 smsl-n100 systemd[1]: Started Wireless Services. Oct 20 14:14:59 smsl-n100 sudo[9802]: pam_unix(sudo:session): session closed for user root Oct 20 14:14:59 smsl-n100 ntpd[10053]: Soliciting pool server 217.156.67.138 Oct 20 14:15:00 smsl-n100 sudo[10073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-20 14:14 Oct 20 14:15:00 smsl-n100 sudo[10073]: 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"