-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-08-06 11:50:05 CEST. -- Aug 06 11:49:01 smsl-n100 volumio[941]: info: Loading plugin "network"... Aug 06 11:49:01 smsl-n100 volumio[941]: info: Refreshing Cached IP Addresses Aug 06 11:49:01 smsl-n100 sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 06 11:49:01 smsl-n100 sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:01 smsl-n100 sudo[985]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:01 smsl-n100 sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 06 11:49:01 smsl-n100 sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:01 smsl-n100 sudo[987]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:01 smsl-n100 volumio[941]: info: Loading plugin "services"... Aug 06 11:49:01 smsl-n100 volumio[941]: info: Loading plugin "alsa_controller"... Aug 06 11:49:01 smsl-n100 sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 06 11:49:01 smsl-n100 sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:01 smsl-n100 volumio[941]: warn: Unable to locate the audio output device Kii Control. Please configure a valid output device. Aug 06 11:49:01 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:01 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:01 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:01 smsl-n100 volumio[941]: Invalid card number. Aug 06 11:49:01 smsl-n100 volumio[941]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Aug 06 11:49:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 06 11:49:01 smsl-n100 volumio[941]: Invalid card number. Aug 06 11:49:01 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 06 11:49:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 11:49:01 smsl-n100 volumio[941]: info: Loading plugin "wizard"... Aug 06 11:49:01 smsl-n100 volumio[941]: info: Loading plugin "networkfs"... Aug 06 11:49:02 smsl-n100 volumio[941]: info: Starting Udev Watcher for removable devices Aug 06 11:49:02 smsl-n100 volumio[941]: info: Ignoring mount for partition: boot Aug 06 11:49:02 smsl-n100 volumio[941]: info: Ignoring mount for partition: volumio Aug 06 11:49:02 smsl-n100 volumio[941]: info: Ignoring mount for partition: volumio_data Aug 06 11:49:02 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 11:49:02 smsl-n100 volumio[941]: info: Loading plugin "volumio_command_line_client"... Aug 06 11:49:02 smsl-n100 volumio[941]: info: Loading plugin "upnp"... Aug 06 11:49:02 smsl-n100 volumio[941]: info: [1754473742112] Starting Upmpd Daemon Aug 06 11:49:02 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 11:49:02 smsl-n100 volumio[941]: info: Loading plugin "my_music"... Aug 06 11:49:02 smsl-n100 volumio[941]: info: Loading plugin "mpd"... Aug 06 11:49:02 smsl-n100 volumio[941]: info: Loading plugin "upnp_browser"... Aug 06 11:49:03 smsl-n100 volumio[941]: info: Plugin bluetooth is not enabled Aug 06 11:49:03 smsl-n100 volumio[941]: info: Loading plugin "alarm-clock"... Aug 06 11:49:04 smsl-n100 volumio[941]: info: Loading plugin "manifestui"... Aug 06 11:49:04 smsl-n100 volumio[941]: info: Loading plugin "metavolumio"... Aug 06 11:49:06 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:06 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:06 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:07 smsl-n100 sudo[996]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:07 smsl-n100 volumio[941]: info: Loading plugin "airplay_emulation"... Aug 06 11:49:07 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:07 smsl-n100 volumio[941]: info: Loading plugin "cd_controller"... Aug 06 11:49:08 smsl-n100 volumio[941]: info: Loading plugin "last_100"... Aug 06 11:49:08 smsl-n100 volumio[941]: info: Loading plugin "raat"... Aug 06 11:49:08 smsl-n100 volumio[941]: info: RAAT Plugin loaded Aug 06 11:49:08 smsl-n100 volumio[941]: info: Adding restartRAATSocket REST API Endpoint Aug 06 11:49:08 smsl-n100 volumio[941]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 06 11:49:08 smsl-n100 volumio[941]: info: Loading plugin "streaming_services"... Aug 06 11:49:10 smsl-n100 volumio[941]: info: Starting Streaming Service Transparent Proxy Aug 06 11:49:10 smsl-n100 volumio[941]: info: Loading plugin "tidalconnect"... Aug 06 11:49:11 smsl-n100 volumio[941]: info: Loading plugin "webradio"... Aug 06 11:49:11 smsl-n100 volumio[941]: info: Loading plugin "i2s_dacs"... Aug 06 11:49:11 smsl-n100 volumio[941]: info: I2S DAC not set, start Auto-detection Aug 06 11:49:11 smsl-n100 volumio[941]: info: Loading plugin "volumiodiscovery"... Aug 06 11:49:11 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:11 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:11 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** For more information see Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 11:49:11 smsl-n100 volumio[941]: *** WARNING *** For more information see Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** For more information see Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 11:49:11 smsl-n100 node[941]: *** WARNING *** For more information see Aug 06 11:49:11 smsl-n100 volumio[941]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 06 11:49:11 smsl-n100 volumio[941]: info: Discovery: Started advertising with name: SMSL N100 Aug 06 11:49:11 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 11:49:12 smsl-n100 volumio[941]: info: Loading plugin "multiroom"... Aug 06 11:49:13 smsl-n100 volumio-remote-updater[560]: [2025-08-06 11:49:13] [connect] Successful connection Aug 06 11:49:13 smsl-n100 volumio[941]: STREAMING PROXY: Starting server on port 3245 Aug 06 11:49:13 smsl-n100 volumio[941]: Node JS runtime: 14 Aug 06 11:49:15 smsl-n100 volumio[941]: info: Applying required configuration parameters for plugin multiroom Aug 06 11:49:15 smsl-n100 sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 06 11:49:15 smsl-n100 sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:15 smsl-n100 sudo[1080]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:15 smsl-n100 volumio[941]: info: MRS: MultiRoom plugin initialized Aug 06 11:49:15 smsl-n100 volumio[941]: info: MRS: STOPPING SNAPCLIENT Aug 06 11:49:15 smsl-n100 volumio[941]: info: MRS: Snap server stop Aug 06 11:49:15 smsl-n100 volumio[941]: info: MRS: STOPPING volumioStreaming Aug 06 11:49:15 smsl-n100 sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 06 11:49:15 smsl-n100 sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:15 smsl-n100 sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 06 11:49:15 smsl-n100 sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:15 smsl-n100 volumio[941]: info: Loading plugin "outputs"... Aug 06 11:49:15 smsl-n100 sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 06 11:49:15 smsl-n100 sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:15 smsl-n100 sudo[1096]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:15 smsl-n100 volumio[941]: info: Loading plugin "albumart"... Aug 06 11:49:15 smsl-n100 sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 06 11:49:15 smsl-n100 sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:15 smsl-n100 sudo[1104]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:15 smsl-n100 sudo[1098]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:15 smsl-n100 volumio[941]: info: Plugin example_plugin is not enabled Aug 06 11:49:15 smsl-n100 volumio[941]: info: Loading plugin "hi_res_audio"... Aug 06 11:49:15 smsl-n100 sudo[1101]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:16 smsl-n100 volumio[941]: Forking 3 albumart workers Aug 06 11:49:16 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:16 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:16 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:18 smsl-n100 volumio[941]: Starting albumart workers Aug 06 11:49:18 smsl-n100 volumio[941]: Starting albumart workers Aug 06 11:49:18 smsl-n100 volumio[941]: Starting albumart workers Aug 06 11:49:20 smsl-n100 volumio[941]: info: Applying required configuration parameters for plugin hi_res_audio Aug 06 11:49:20 smsl-n100 volumio[941]: info: Loading plugin "inputs"... Aug 06 11:49:21 smsl-n100 ntpd[750]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 06 11:49:21 smsl-n100 volumio[941]: info: Loading plugin "qobuz"... Aug 06 11:49:22 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:22 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:22 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:23 smsl-n100 ntpd[750]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 06 11:49:24 smsl-n100 volumio[941]: info: Loading plugin "smart_inputs"... Aug 06 11:49:24 smsl-n100 ntpd[750]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 06 11:49:24 smsl-n100 volumio[941]: info: Loading plugin "tidal"... Aug 06 11:49:25 smsl-n100 ntpd[750]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 06 11:49:27 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:27 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:27 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:27 smsl-n100 volumio[941]: info: Loading plugin "oem_helper"... Aug 06 11:49:28 smsl-n100 volumio-remote-updater[560]: [2025-08-06 11:49:28] [connect] Successful connection Aug 06 11:49:29 smsl-n100 volumio[941]: info: Applying required configuration parameters for plugin oem_helper Aug 06 11:49:29 smsl-n100 volumio[941]: info: Loading plugin "smslcontrol"... Aug 06 11:49:29 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 Aug 06 11:49:29 smsl-n100 volumio[941]: info: Adding this device properties Aug 06 11:49:29 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 06 11:49:29 smsl-n100 volumio[941]: info: Setting Additional Device Volumio Properties: [object Object] Aug 06 11:49:29 smsl-n100 volumio[941]: info: Loading plugin "updater_comm"... Aug 06 11:49:30 smsl-n100 volumio[941]: info: Plugin mpdemulation is not enabled Aug 06 11:49:30 smsl-n100 volumio[941]: info: Loading plugin "rest_api"... Aug 06 11:49:30 smsl-n100 volumio[941]: info: Loading plugin "websocket"... Aug 06 11:49:30 smsl-n100 volumio[941]: info: Starting Socket.io Server version 2.3.0 Aug 06 11:49:30 smsl-n100 volumio[941]: info: Loading i18n strings for locale en Aug 06 11:49:30 smsl-n100 volumio[941]: Updating browse sources language Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::initPlayerControls Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 11:49:30 smsl-n100 volumio[941]: Express server listening on port 3000 Aug 06 11:49:30 smsl-n100 volumio[941]: [Metrics] WebUI: 36s 706.10ms Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::resetVolumioState Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::getcurrentVolume Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::getcurrentVolume Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 11:49:31 smsl-n100 sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 06 11:49:31 smsl-n100 sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:31 smsl-n100 sudo[1211]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:31 smsl-n100 sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 06 11:49:31 smsl-n100 volumio[941]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 06 11:49:31 smsl-n100 sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:31 smsl-n100 volumio[941]: info: Completed loading Core Plugins Aug 06 11:49:31 smsl-n100 volumio[941]: info: Preparing to generate the ALSA configuration file Aug 06 11:49:31 smsl-n100 sudo[1213]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:31 smsl-n100 volumio[941]: info: Volumio Network Manager: Network status updated: 0 Aug 06 11:49:31 smsl-n100 volumio[941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 06 11:49:31 smsl-n100 volumio[941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 06 11:49:31 smsl-n100 volumio[941]: info: MRS: Removed streaming files Aug 06 11:49:31 smsl-n100 volumio[941]: info: MRS: volumioStreaming STOPPED Aug 06 11:49:31 smsl-n100 volumio[941]: info: MRS: SNAPSERVER STOPPED Aug 06 11:49:31 smsl-n100 volumio[941]: info: MRS: SNAPCLIENT STOPPED Aug 06 11:49:31 smsl-n100 volumio[941]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 06 11:49:31 smsl-n100 volumio[941]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 06 11:49:31 smsl-n100 volumio[941]: info: Reading ALSA contributions from plugins. Aug 06 11:49:31 smsl-n100 volumio-remote-updater[560]: [2025-08-06 11:49:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1754473768 101 Aug 06 11:49:31 smsl-n100 volumio[941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 06 11:49:31 smsl-n100 volumio[941]: info: VolumeController:: Volume=undefined Mute =false Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:31 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::updateTrackBlock Aug 06 11:49:31 smsl-n100 volumio[941]: info: CorePlayQueue::getTrackBlock Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 11:49:31 smsl-n100 volumio[941]: info: VolumeController:: Volume=undefined Mute =false Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:31 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreStateMachine::updateTrackBlock Aug 06 11:49:31 smsl-n100 volumio[941]: info: CorePlayQueue::getTrackBlock Aug 06 11:49:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 11:49:31 smsl-n100 volumio[941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 06 11:49:31 smsl-n100 volumio[941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 06 11:49:32 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:32 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:32 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:32 smsl-n100 volumio[941]: info: Reloading queue from file Aug 06 11:49:32 smsl-n100 volumio[941]: info: VolumeController:: Volume=undefined Mute =false Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::setRepeat null single undefined Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::setRandom null Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: Setting Device type: NanoPi NEO 3 Aug 06 11:49:32 smsl-n100 volumio[941]: info: VolumeController:: Volume=undefined Mute =false Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:32 smsl-n100 volumio[941]: info: Asound.conf file unchanged, so no further update is needed Aug 06 11:49:32 smsl-n100 volumio[941]: info: Output device has changed, restarting MPD Aug 06 11:49:32 smsl-n100 sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 06 11:49:32 smsl-n100 sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:32 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 06 11:49:32 smsl-n100 volumio[941]: info: Output device has changed, restarting Shairport Sync Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:32 smsl-n100 sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 11:49:32 smsl-n100 sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:32 smsl-n100 sudo[1249]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:32 smsl-n100 sudo[1246]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:32 smsl-n100 sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 11:49:32 smsl-n100 sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:32 smsl-n100 systemd[1]: Stopping Music Player Daemon... Aug 06 11:49:32 smsl-n100 volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: ___________ START PLUGINS ___________ Aug 06 11:49:32 smsl-n100 volumio[941]: info: ControllerMpd::onStart: Initializing MPD Aug 06 11:49:32 smsl-n100 volumio[941]: info: Creating MPD Configuration file Aug 06 11:49:32 smsl-n100 sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 11:49:32 smsl-n100 volumio[941]: info: [1754473772708] CoreMusicLibrary::Adding element Media Servers Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 11:49:32 smsl-n100 sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:32 smsl-n100 sudo[1261]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:32 smsl-n100 sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 11:49:32 smsl-n100 sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding METAVOLUMIO REST API Endpoints Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:32 smsl-n100 volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 11:49:32 smsl-n100 volumio[941]: info: Preparing CD Folders Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding CD REST API Endpoints Aug 06 11:49:32 smsl-n100 volumio[941]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 06 11:49:32 smsl-n100 volumio[941]: info: Starting UDEV Watcher for CD Aug 06 11:49:32 smsl-n100 volumio[941]: info: Detecting CD presence with UDEV Aug 06 11:49:32 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 11:49:33 smsl-n100 volumio[941]: info: [1754473773003] CoreMusicLibrary::Adding element Last_100 Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 11:49:33 smsl-n100 volumio[941]: info: Starting RAAT Plugin Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 06 11:49:33 smsl-n100 volumio[941]: info: Additional UI Settings Added for plugin music_service/raat Aug 06 11:49:33 smsl-n100 volumio[941]: info: Registering DSP Elements listener and retrieving current ones Aug 06 11:49:33 smsl-n100 volumio[941]: info: Additional DSP elements updated Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:33 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Aug 06 11:49:33 smsl-n100 volumio[941]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Aug 06 11:49:33 smsl-n100 volumio[941]: info: Streaming services startup Aug 06 11:49:33 smsl-n100 volumio[941]: info: Starting Streaming Daemon Aug 06 11:49:33 smsl-n100 sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 06 11:49:33 smsl-n100 sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:33 smsl-n100 sudo[1275]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 11:49:33 smsl-n100 volumio[941]: info: [1754473773258] CoreMusicLibrary::Adding element Webradio Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 11:49:33 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 06 11:49:33 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 06 11:49:33 smsl-n100 volumio[941]: info: Initializing BBC Radios Aug 06 11:49:33 smsl-n100 sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 06 11:49:33 smsl-n100 sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:33 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 06 11:49:33 smsl-n100 sudo[1282]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 06 11:49:33 smsl-n100 volumio[941]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:33 smsl-n100 sudo[1284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 11:49:33 smsl-n100 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:33 smsl-n100 sudo[1284]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:33 smsl-n100 volumio[941]: error: Hi Res Audio Failed Login: Missing Login Data Aug 06 11:49:33 smsl-n100 volumio[941]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 06 11:49:33 smsl-n100 volumio[941]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 06 11:49:33 smsl-n100 volumio[941]: info: Initializing Serial Communication on port /dev/ttyS1 Aug 06 11:49:33 smsl-n100 volumio[941]: info: Touch Event Listener Process Starting Aug 06 11:49:33 smsl-n100 volumio[941]: info: Adding inputs REST Endpoints Aug 06 11:49:33 smsl-n100 volumio[941]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 06 11:49:33 smsl-n100 volumio[941]: info: Scanning Audio Inputs Aug 06 11:49:33 smsl-n100 sudo[1302]: pam_unix(sudo:auth): conversation failed Aug 06 11:49:33 smsl-n100 sudo[1302]: pam_unix(sudo:auth): auth could not identify password for [volumio] Aug 06 11:49:33 smsl-n100 volumio[941]: info: Checking against Known Cards name Aug 06 11:49:33 smsl-n100 volumio[941]: info: Adding Server instance for streaming Aug 06 11:49:33 smsl-n100 volumio[941]: info: Refreshing TIDAL token Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 11:49:33 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:33 smsl-n100 volumio[941]: info: Initializing GPIOs Aug 06 11:49:33 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 Aug 06 11:49:34 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 Aug 06 11:49:34 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 Aug 06 11:49:34 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 Aug 06 11:49:34 smsl-n100 volumio[941]: info: Starting Button Listener Aug 06 11:49:34 smsl-n100 volumio[941]: info: Turning off Green LED Aug 06 11:49:34 smsl-n100 volumio[941]: info: Tuning on Blue LED Aug 06 11:49:34 smsl-n100 volumio[941]: info: Signaling System Ready Aug 06 11:49:34 smsl-n100 volumio[941]: info: Volumio Calling Home Aug 06 11:49:34 smsl-n100 volumio[941]: (node:941) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 06 11:49:34 smsl-n100 volumio[941]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 06 11:49:34 smsl-n100 volumio[941]: info: Stopping AccessToken refresher cron for QOBUZ Aug 06 11:49:34 smsl-n100 volumio[941]: info: AccessToken refresher cron started for QOBUZ Aug 06 11:49:34 smsl-n100 volumio[941]: info: Adding QOBUZ REST API Endpoints Aug 06 11:49:34 smsl-n100 volumio[941]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 06 11:49:34 smsl-n100 volumio[941]: Unhandled rejection Error: No sockets available, cannot start. Aug 06 11:49:34 smsl-n100 volumio[941]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 06 11:49:34 smsl-n100 volumio[941]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 06 11:49:34 smsl-n100 volumio[941]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 06 11:49:34 smsl-n100 volumio[941]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 06 11:49:34 smsl-n100 volumio[941]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 06 11:49:34 smsl-n100 volumio[941]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 06 11:49:34 smsl-n100 volumio[941]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 06 11:49:34 smsl-n100 volumio[941]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 06 11:49:34 smsl-n100 volumio[941]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 06 11:49:34 smsl-n100 volumio[941]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 06 11:49:34 smsl-n100 volumio[941]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 06 11:49:34 smsl-n100 volumio[941]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 06 11:49:34 smsl-n100 volumio[941]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 06 11:49:34 smsl-n100 volumio[941]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 06 11:49:34 smsl-n100 volumio[941]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 06 11:49:34 smsl-n100 volumio[941]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 06 11:49:34 smsl-n100 volumio[941]: info: Serial port opened successfully Aug 06 11:49:34 smsl-n100 volumio[941]: info: Sending serial start messages Aug 06 11:49:34 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:34 smsl-n100 volumio[941]: info: Reporting MCU Network Status: 0 Aug 06 11:49:34 smsl-n100 volumio[941]: error: No access token received for TIDAL Aug 06 11:49:34 smsl-n100 volumio[941]: info: Touch Event Listener Process Closed Aug 06 11:49:34 smsl-n100 volumio[941]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 06 11:49:34 smsl-n100 volumio[941]: error: Cannot start Volumio Streaming Daemon Aug 06 11:49:34 smsl-n100 volumio[941]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 06 11:49:34 smsl-n100 volumio[941]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 06 11:49:34 smsl-n100 volumio[941]: info: RAAT Albumart path created successfully Aug 06 11:49:34 smsl-n100 volumio[941]: info: MPD Permissions set Aug 06 11:49:34 smsl-n100 volumio[941]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 06 11:49:34 smsl-n100 volumio[941]: info: MPD Permissions set Aug 06 11:49:34 smsl-n100 volumio[941]: info: Upmpdcli Daemon Started Aug 06 11:49:34 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 06 11:49:34 smsl-n100 volumio[941]: info: Setting Device Volume Override Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 06 11:49:35 smsl-n100 volumio[941]: info: Updating Volume Controller Parameters: Device: 5 Name: Kii Control Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 06 11:49:35 smsl-n100 volumio[941]: info: Disabling external Volume Control Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 0, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 06 11:49:35 smsl-n100 volumio[941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:35 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:35 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:35 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 06 11:49:35 smsl-n100 volumio[941]: info: VolumeController:: Volume=undefined Mute =false Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 11:49:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:35 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:35 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:35 smsl-n100 sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 06 11:49:35 smsl-n100 sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:35 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:36 smsl-n100 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 06 11:49:36 smsl-n100 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 06 11:49:36 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:36 smsl-n100 sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 06 11:49:36 smsl-n100 sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:36 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 06 11:49:36 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:36 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:36 smsl-n100 sudo[1349]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:36 smsl-n100 sudo[1351]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Aug 06 11:49:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:36 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 06 11:49:36 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:36 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 06 11:49:36 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:36 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:36 smsl-n100 volumio[941]: info: Preparing to generate the ALSA configuration file Aug 06 11:49:36 smsl-n100 sudo[1355]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Aug 06 11:49:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:36 smsl-n100 volumio[941]: info: Shairport-Sync Started Aug 06 11:49:36 smsl-n100 volumio[941]: Error adding Membership: Error: addMembership EINVAL Aug 06 11:49:36 smsl-n100 volumio[941]: info: Shairport-Sync Started Aug 06 11:49:36 smsl-n100 volumio[941]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 06 11:49:36 smsl-n100 volumio[941]: info: Reading ALSA contributions from plugins. Aug 06 11:49:36 smsl-n100 volumio[941]: info: TidalConnect service stoped! Aug 06 11:49:36 smsl-n100 volumio[941]: info: Shairport-Sync Started Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:36 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:36 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:36 smsl-n100 volumio[941]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:36 smsl-n100 volumio[941]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:36 smsl-n100 volumio[941]: info: Asound.conf file written Aug 06 11:49:36 smsl-n100 sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 06 11:49:36 smsl-n100 sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:36 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 06 11:49:37 smsl-n100 sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 06 11:49:37 smsl-n100 sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 sudo[1372]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 sudo[1375]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 volumio[941]: info: Output device has changed, restarting MPD Aug 06 11:49:37 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:37 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:37 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:37 smsl-n100 volumio[941]: info: Output device has changed, restarting Shairport Sync Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:37 smsl-n100 sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 11:49:37 smsl-n100 sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 sudo[1387]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 11:49:37 smsl-n100 sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 11:49:37 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:37 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 06 11:49:37 smsl-n100 volumio[941]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 06 11:49:37 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 06 11:49:37 smsl-n100 volumio[941]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Aug 06 11:49:37 smsl-n100 volumio[941]: info: Preparing to generate the ALSA configuration file Aug 06 11:49:37 smsl-n100 volumio[941]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 06 11:49:37 smsl-n100 volumio[941]: info: Reading ALSA contributions from plugins. Aug 06 11:49:37 smsl-n100 volumio[941]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 06 11:49:37 smsl-n100 sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 06 11:49:37 smsl-n100 sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 volumio[941]: info: MPD Permissions set Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 sudo[1396]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 sudo[1396]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 06 11:49:37 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:37 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 06 11:49:37 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 06 11:49:37 smsl-n100 sudo[1400]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:37 smsl-n100 volumio[941]: info: Asound.conf file unchanged, so no further update is needed Aug 06 11:49:37 smsl-n100 volumio[941]: info: Output device has changed, restarting MPD Aug 06 11:49:37 smsl-n100 sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 06 11:49:37 smsl-n100 sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 11:49:37 smsl-n100 volumio[941]: info: Output device has changed, restarting Shairport Sync Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:37 smsl-n100 sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:37 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:37 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 06 11:49:37 smsl-n100 sudo[1415]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:37 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 06 11:49:37 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:38 smsl-n100 sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 11:49:38 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:38 smsl-n100 sudo[1412]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:38 smsl-n100 volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 11:49:38 smsl-n100 volumio[941]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:38 smsl-n100 systemd[1]: mpd.service: Succeeded. Aug 06 11:49:38 smsl-n100 systemd[1]: Stopped Music Player Daemon. Aug 06 11:49:38 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 06 11:49:38 smsl-n100 volumio[941]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Aug 06 11:49:38 smsl-n100 sudo[1430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 06 11:49:38 smsl-n100 sudo[1430]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 sudo[1430]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: Not Reporting Auto name since its the default one Aug 06 11:49:38 smsl-n100 sudo[1426]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 11:49:38 smsl-n100 sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:38 smsl-n100 volumio[941]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 06 11:49:38 smsl-n100 volumio[941]: info: MPD Permissions set Aug 06 11:49:38 smsl-n100 volumio[941]: info: Shairport-Sync Started Aug 06 11:49:38 smsl-n100 sudo[1426]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 06 11:49:38 smsl-n100 volumio[941]: info: Configured Audio card not found, not starting RAAT Aug 06 11:49:38 smsl-n100 volumio[941]: info: MRS: Getting audio outputs on start Aug 06 11:49:38 smsl-n100 volumio[941]: info: MRS: Requesting all other devices output Aug 06 11:49:38 smsl-n100 volumio[941]: info: Starting Shairport Sync Aug 06 11:49:38 smsl-n100 sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 06 11:49:38 smsl-n100 sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 11:49:38 smsl-n100 volumio[941]: info: Not Reporting Auto name since its the default one Aug 06 11:49:38 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 06 11:49:38 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 06 11:49:38 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 06 11:49:38 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:38 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:38 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Aug 06 11:49:38 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Aug 06 11:49:38 smsl-n100 sudo[1263]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 sudo[1418]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 sudo[1390]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 sudo[1253]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 systemd[1]: Starting Music Player Daemon... Aug 06 11:49:38 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 06 11:49:38 smsl-n100 volumio[941]: info: Configured Audio card not found, not starting RAAT Aug 06 11:49:38 smsl-n100 sudo[1448]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:38 smsl-n100 volumio[941]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 06 11:49:38 smsl-n100 volumio[941]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 06 11:49:38 smsl-n100 volumio[941]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 06 11:49:38 smsl-n100 volumio[941]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 06 11:49:38 smsl-n100 volumio[941]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 06 11:49:38 smsl-n100 volumio[941]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 06 11:49:38 smsl-n100 volumio[941]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 06 11:49:38 smsl-n100 volumio[941]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 06 11:49:38 smsl-n100 volumio[941]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 06 11:49:38 smsl-n100 volumio[941]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Aug 06 11:49:38 smsl-n100 volumio[941]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Aug 06 11:49:38 smsl-n100 volumio[941]: See "systemctl status mpd.service" and "journalctl -xe" for details. Aug 06 11:49:38 smsl-n100 volumio[941]: info: Shairport-Sync Started Aug 06 11:49:39 smsl-n100 sudo[1458]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 11:49:39 smsl-n100 sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:39 smsl-n100 sudo[1458]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:40 smsl-n100 volumio[941]: info: TidalConnect service started! Aug 06 11:49:40 smsl-n100 volumio[941]: info: Completed starting Core Plugins Aug 06 11:49:40 smsl-n100 volumio[941]: info: ------------------------------------------- Aug 06 11:49:40 smsl-n100 volumio[941]: info: ----- MyVolumio plugins startup ---- Aug 06 11:49:40 smsl-n100 volumio[941]: info: ------------------------------------------- Aug 06 11:49:40 smsl-n100 volumio[941]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 06 11:49:40 smsl-n100 volumio[941]: info: TidalConnect service stoped! Aug 06 11:49:40 smsl-n100 volumio[941]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:40 smsl-n100 volumio[941]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:41 smsl-n100 sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 06 11:49:41 smsl-n100 sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:41 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 06 11:49:41 smsl-n100 volumio[941]: info: TidalConnect service stoped! Aug 06 11:49:41 smsl-n100 sudo[1473]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:41 smsl-n100 volumio[941]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:41 smsl-n100 volumio[941]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 06 11:49:41 smsl-n100 sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 06 11:49:41 smsl-n100 volumio[941]: info: RAAT: Requesting Headphone Status Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 06 11:49:41 smsl-n100 sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:41 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:41 smsl-n100 volumio[941]: error: Help! Some callbacks for volumioPushState are crashing! Aug 06 11:49:41 smsl-n100 volumio[941]: error: Cannot read property 'sendVolumeMute' of undefined Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:41 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:41 smsl-n100 sudo[1487]: pam_unix(sudo:session): session closed for user root Aug 06 11:49:41 smsl-n100 volumio[941]: info: RAAT: Requesting Headphone Status Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:41 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:41 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:41 smsl-n100 volumio[941]: error: Help! Some callbacks for volumioPushState are crashing! Aug 06 11:49:41 smsl-n100 volumio[941]: error: Cannot read property 'sendVolumeMute' of undefined Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 06 11:49:41 smsl-n100 volumio[941]: error: Serial API: Failed to decode command: 100, message: undefined Aug 06 11:49:41 smsl-n100 volumio[941]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 06 11:49:42 smsl-n100 volumio[941]: info: Executing endpoint tc_getconfig Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 06 11:49:42 smsl-n100 vtcs[1475]: STARTING TidalConnect services, version: 1.4.0.34 Aug 06 11:49:42 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:42 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:42 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:42 smsl-n100 vtcs[1475]: STARTED TidalConnect services. Aug 06 11:49:42 smsl-n100 volumio[941]: info: Executing endpoint tc_connect Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 06 11:49:42 smsl-n100 mpd[1463]: Aug 06 11:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 06 11:49:42 smsl-n100 volumio[941]: info: Connecting to TidalConnect Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:42 smsl-n100 volumio[941]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:42 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Aug 06 11:49:42 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:49:42 smsl-n100 volumio[941]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Aug 06 11:49:42 smsl-n100 systemd[1]: Started Music Player Daemon. Aug 06 11:49:42 smsl-n100 mpd_monitor.sh[559]: MPD restarted due to no mpc output. Aug 06 11:49:42 smsl-n100 volumio[941]: Upnp client error: Error: This socket has been ended by the other party Aug 06 11:49:42 smsl-n100 volumio[941]: error: MPD error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: Upnp client error: Error: This socket has been ended by the other party Aug 06 11:49:42 smsl-n100 volumio[941]: error: MPD error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: error: MPD error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: error: The expression evaluated to a falsy value: Aug 06 11:49:42 smsl-n100 volumio[941]: assert.ok(self.idling) Aug 06 11:49:42 smsl-n100 volumio[941]: error: updateQueue error: null Aug 06 11:49:44 smsl-n100 volumio[941]: info: TidalConnect service started! Aug 06 11:49:44 smsl-n100 volumio[941]: info: TidalConnect service started! Aug 06 11:49:45 smsl-n100 volumio[941]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=2ef9d685747220a7b45001bc2aa23466" http://updates.volumio.org/downloader-v1/track-device Aug 06 11:49:45 smsl-n100 volumio[941]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 06 11:49:45 smsl-n100 volumio[941]: Dload Upload Total Spent Left Speed Aug 06 11:49:45 smsl-n100 volumio[941]: [132B blob data] Aug 06 11:49:45 smsl-n100 volumio[941]: retrying in 5 seconds, trial 0 Aug 06 11:49:45 smsl-n100 volumio[941]: info: Volumio Calling Home Aug 06 11:49:45 smsl-n100 systemd[1]: nmbd.service: Start operation timed out. Terminating. Aug 06 11:49:45 smsl-n100 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Aug 06 11:49:45 smsl-n100 systemd[1]: nmbd.service: Failed with result 'timeout'. Aug 06 11:49:45 smsl-n100 systemd[1]: Failed to start Samba NMB Daemon. Aug 06 11:49:45 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... Aug 06 11:49:46 smsl-n100 winbindd[1543]: [2025/08/06 11:49:46.358168, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 06 11:49:46 smsl-n100 winbindd[1543]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 06 11:49:46 smsl-n100 winbindd[1543]: [2025/08/06 11:49:46.384727, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 06 11:49:46 smsl-n100 winbindd[1543]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 06 11:49:46 smsl-n100 systemd[1]: Started Samba Winbind Daemon. Aug 06 11:49:46 smsl-n100 systemd[1]: Starting Samba SMB Daemon... Aug 06 11:49:47 smsl-n100 winbindd[1543]: [2025/08/06 11:49:47.217469, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Aug 06 11:49:47 smsl-n100 winbindd[1543]: res_names->count = 3, expected 4 Aug 06 11:49:47 smsl-n100 smbd[1547]: [2025/08/06 11:49:47.249168, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 06 11:49:47 smsl-n100 smbd[1547]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 06 11:49:47 smsl-n100 systemd[1]: Started Samba SMB Daemon. Aug 06 11:49:47 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:47 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:47 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:52 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:52 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:52 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:55 smsl-n100 volumio[941]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=2ef9d685747220a7b45001bc2aa23466" http://updates.volumio.org/downloader-v1/track-device Aug 06 11:49:55 smsl-n100 volumio[941]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 06 11:49:55 smsl-n100 volumio[941]: Dload Upload Total Spent Left Speed Aug 06 11:49:55 smsl-n100 volumio[941]: [132B blob data] Aug 06 11:49:55 smsl-n100 volumio[941]: retrying in 5 seconds, trial 1 Aug 06 11:49:55 smsl-n100 volumio[941]: info: Volumio Calling Home Aug 06 11:49:56 smsl-n100 wpa_supplicant[845]: wlan0: Trying to associate with 10:d7:b0:61:ee:96 (SSID='MUZIX Group' freq=2462 MHz) Aug 06 11:49:57 smsl-n100 wpa_supplicant[845]: wlan0: Associated with 10:d7:b0:61:ee:96 Aug 06 11:49:57 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 06 11:49:57 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 06 11:49:57 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:49:57 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:49:57 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:49:58 smsl-n100 wpa_supplicant[845]: wlan0: WPA: Key negotiation completed with 10:d7:b0:61:ee:96 [PTK=CCMP GTK=CCMP] Aug 06 11:49:58 smsl-n100 wpa_supplicant[845]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:d7:b0:61:ee:96 completed [id=0 id_str=] Aug 06 11:49:58 smsl-n100 dhcpcd[914]: wlan0: carrier acquired Aug 06 11:49:58 smsl-n100 dhcpcd[914]: wlan0: IAID 2b:ee:c8:e4 Aug 06 11:49:58 smsl-n100 dhcpcd[914]: wlan0: soliciting an IPv6 router Aug 06 11:49:58 smsl-n100 dhcpcd[914]: wlan0: rebinding lease of 192.168.1.90 Aug 06 11:49:58 smsl-n100 dhcpcd[914]: wlan0: probing address 192.168.1.90/24 Aug 06 11:50:00 smsl-n100 volumio[941]: error: MyVolumio Plugin failed to start in a timely fashion Aug 06 11:50:00 smsl-n100 volumio[941]: [Metrics] CommandRouter: 63s 911.35ms Aug 06 11:50:00 smsl-n100 volumio[941]: info: CoreCommandRouter::volumiosetStartupVolume Aug 06 11:50:00 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 11:50:00 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:50:00 smsl-n100 volumio[941]: info: CoreCommandRouter::Close All Modals sent Aug 06 11:50:00 smsl-n100 volumio[941]: info: CoreCommandRouter::Close All Modals sent Aug 06 11:50:00 smsl-n100 volumio[941]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 06 11:50:00 smsl-n100 volumio[941]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Aug 06 11:50:00 smsl-n100 volumio[941]: 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 Aug 06 11:50:00 smsl-n100 volumio[941]: aplay: main:828: audio open error: No such device Aug 06 11:50:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 06 11:50:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 06 11:50:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 06 11:50:01 smsl-n100 volumio[941]: info: Stopping RAAT Plugin Aug 06 11:50:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 06 11:50:01 smsl-n100 sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Aug 06 11:50:01 smsl-n100 sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 11:50:01 smsl-n100 sudo[1598]: pam_unix(sudo:session): session closed for user root Aug 06 11:50:01 smsl-n100 volumio[941]: info: Raat Daemon stopped successfully Aug 06 11:50:02 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Aug 06 11:50:02 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Date not found in response Aug 06 11:50:02 smsl-n100 volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Aug 06 11:50:04 smsl-n100 dhcpcd[914]: wlan0: leased 192.168.1.90 for 86400 seconds Aug 06 11:50:04 smsl-n100 dhcpcd[914]: wlan0: adding route to 192.168.1.0/24 Aug 06 11:50:04 smsl-n100 avahi-daemon[548]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.90. Aug 06 11:50:04 smsl-n100 avahi-daemon[548]: New relevant interface wlan0.IPv4 for mDNS. Aug 06 11:50:04 smsl-n100 dhcpcd[914]: wlan0: adding default route via 192.168.1.1 Aug 06 11:50:04 smsl-n100 avahi-daemon[548]: Registering new address record for 192.168.1.90 on wlan0.IPv4. Aug 06 11:50:04 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 11:50:04 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 11:50:04 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 06 11:50:04 smsl-n100 ntpd[750]: ntpd exiting on signal 15 (Terminated) Aug 06 11:50:04 smsl-n100 systemd[1]: Stopping Network Time Service... Aug 06 11:50:04 smsl-n100 systemd[1]: ntp.service: Succeeded. Aug 06 11:50:04 smsl-n100 systemd[1]: Stopped Network Time Service. Aug 06 11:50:04 smsl-n100 systemd[1]: Starting Network Time Service... Aug 06 11:50:04 smsl-n100 ntpd[1659]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 06 11:50:04 smsl-n100 ntpd[1659]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 06 11:50:04 smsl-n100 systemd[1]: Started Network Time Service. Aug 06 11:50:04 smsl-n100 ntpd[1665]: proto: precision = 1.166 usec (-20) Aug 06 11:50:04 smsl-n100 ntpd[1665]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 06 11:50:04 smsl-n100 ntpd[1665]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 06 11:50:04 smsl-n100 ntpd[1665]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 953 days ago Aug 06 11:50:04 smsl-n100 ntpd[1665]: Listen and drop on 0 v6wildcard [::]:123 Aug 06 11:50:04 smsl-n100 ntpd[1665]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 06 11:50:04 smsl-n100 ntpd[1665]: Listen normally on 2 lo 127.0.0.1:123 Aug 06 11:50:04 smsl-n100 ntpd[1665]: Listen normally on 3 wlan0 192.168.1.90:123 Aug 06 11:50:04 smsl-n100 ntpd[1665]: Listening on routing socket on fd #20 for interface updates Aug 06 11:50:04 smsl-n100 ntpd[1665]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 06 11:50:04 smsl-n100 ntpd[1665]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 06 11:50:04 smsl-n100 volumio[941]: info: Discovery: adding 5fcc5079-7148-4f74-bdd9-8d3f8703547e Aug 06 11:50:04 smsl-n100 volumio[941]: info: Discovery: Found device SMSL N100 Aug 06 11:50:04 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Aug 06 11:50:04 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 0 Aug 06 11:50:04 smsl-n100 volumio[941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 11:50:04 smsl-n100 volumio[941]: Error: The value NaN is not a number Aug 06 11:50:04 smsl-n100 volumio[941]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Aug 06 11:50:04 smsl-n100 volumio[941]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Aug 06 11:50:04 smsl-n100 volumio[941]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Aug 06 11:50:04 smsl-n100 volumio[941]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Aug 06 11:50:04 smsl-n100 volumio[941]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Aug 06 11:50:04 smsl-n100 volumio[941]: at Browser.emit (events.js:400:28) Aug 06 11:50:04 smsl-n100 volumio[941]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 06 11:50:04 smsl-n100 volumio[941]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 06 11:50:04 smsl-n100 volumio[941]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Aug 06 11:50:04 smsl-n100 volumio[941]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Aug 06 11:50:04 smsl-n100 volumio[941]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 06 11:50:04 smsl-n100 volumio[941]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 06 11:50:04 smsl-n100 volumio[941]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 06 11:50:04 smsl-n100 volumio[941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 11:50:05 smsl-n100 ntpd[1665]: Soliciting pool server 193.224.163.77 Aug 06 11:50:05 smsl-n100 sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-06 11:49 Aug 06 11:50:05 smsl-n100 sudo[1676]: 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"