-- Logs begin at Tue 2024-11-26 16:51:00 CST, end at Wed 2024-11-27 17:09:02 CST. -- Nov 27 17:08:16 smsl--n100 systemd-timedated[1171]: Changed local time to Wed Nov 27 17:08:16 2024 Nov 27 17:08:16 smsl--n100 sudo[1167]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:16 smsl--n100 volumio-time-update[722]: volumio-time-update-util: System time updated successfully. Nov 27 17:08:16 smsl--n100 systemd[1]: Starting Daily apt download activities... Nov 27 17:08:16 smsl--n100 systemd[1]: Started Volumio Time Update Utility. Nov 27 17:08:16 smsl--n100 volumio-remote-updater[712]: [2024-11-27 17:08:16] [connect] Successful connection Nov 27 17:08:16 smsl--n100 volumio[1117]: info: MYVOLUMIO Environment detected Nov 27 17:08:16 smsl--n100 systemd[1]: Started Samba SMB Daemon. Nov 27 17:08:16 smsl--n100 smbd[1173]: [2024/11/27 17:08:16.559320, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 27 17:08:16 smsl--n100 smbd[1173]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 27 17:08:16 smsl--n100 systemd[1]: Reached target Multi-User System. Nov 27 17:08:16 smsl--n100 systemd[1]: Reached target Graphical Interface. Nov 27 17:08:16 smsl--n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Plugin folders cleanup Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning into folder /volumio/app/plugins/ Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category audio_interface Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category miscellanea Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category music_service Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category plugins.json Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category system_controller Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning category user_interface Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Scanning into folder /data/plugins/ Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Plugin folders cleanup completed Nov 27 17:08:16 smsl--n100 volumio[1117]: info: ------------------------------------------- Nov 27 17:08:16 smsl--n100 volumio[1117]: info: ----- Core plugins startup ---- Nov 27 17:08:16 smsl--n100 volumio[1117]: info: ------------------------------------------- Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin bluetooth to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin multiroom to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin upnp to MyMusic Plugins Nov 27 17:08:16 smsl--n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin metavolumio to MyMusic Plugins Nov 27 17:08:16 smsl--n100 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin cd_controller to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin raat to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Loading plugins from folder /data/plugins/ Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Loading plugin "system"... Nov 27 17:08:16 smsl--n100 volumio[1117]: info: Loading plugin "appearance"... Nov 27 17:08:17 smsl--n100 systemd[1]: apt-daily.service: Succeeded. Nov 27 17:08:17 smsl--n100 systemd[1]: Started Daily apt download activities. Nov 27 17:08:17 smsl--n100 systemd[1]: Starting Daily apt upgrade and clean activities... Nov 27 17:08:18 smsl--n100 systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 27 17:08:18 smsl--n100 systemd[1]: Started Daily apt upgrade and clean activities. Nov 27 17:08:18 smsl--n100 systemd[1]: Startup finished in 9.063s (kernel) + 33.728s (userspace) = 42.792s. Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Loading plugin "network"... Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Refreshing Cached IP Addresses Nov 27 17:08:18 smsl--n100 sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 17:08:18 smsl--n100 sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:18 smsl--n100 sudo[1289]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:18 smsl--n100 sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 17:08:18 smsl--n100 sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Loading plugin "services"... Nov 27 17:08:18 smsl--n100 sudo[1291]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Loading plugin "alsa_controller"... Nov 27 17:08:18 smsl--n100 sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 27 17:08:18 smsl--n100 sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:18 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Loading plugin "wizard"... Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Loading plugin "networkfs"... Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Starting Udev Watcher for removable devices Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Ignoring mount for partition: boot Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Ignoring mount for partition: volumio Nov 27 17:08:18 smsl--n100 volumio[1117]: info: Ignoring mount for partition: volumio_data Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Mounting Device ESDUSB Nov 27 17:08:19 smsl--n100 sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ESDUSB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 27 17:08:19 smsl--n100 sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:19 smsl--n100 sudo[1300]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:19 smsl--n100 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Nov 27 17:08:19 smsl--n100 sudo[1318]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:19 smsl--n100 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Nov 27 17:08:19 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Loading plugin "volumio_command_line_client"... Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Loading plugin "upnp"... Nov 27 17:08:19 smsl--n100 volumio[1117]: info: [1732698499321] Starting Upmpd Daemon Nov 27 17:08:19 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Loading plugin "my_music"... Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Loading plugin "mpd"... Nov 27 17:08:19 smsl--n100 volumio[1117]: info: Loading plugin "upnp_browser"... Nov 27 17:08:20 smsl--n100 volumio[1117]: info: Loading plugin "bluetooth"... Nov 27 17:08:20 smsl--n100 volumio[1117]: info: [1732698500674] Starting BluetoothController Nov 27 17:08:20 smsl--n100 volumio[1117]: info: Loading plugin "alarm-clock"... Nov 27 17:08:21 smsl--n100 volumio[1117]: info: Loading plugin "manifestui"... Nov 27 17:08:21 smsl--n100 volumio[1117]: info: Loading plugin "metavolumio"... Nov 27 17:08:22 smsl--n100 systemd[1]: systemd-fsckd.service: Succeeded. Nov 27 17:08:22 smsl--n100 sh[640]: timed out Nov 27 17:08:22 smsl--n100 dhcpcd[683]: timed out Nov 27 17:08:22 smsl--n100 sh[640]: dhcpcd exited Nov 27 17:08:22 smsl--n100 dhcpcd[683]: dhcpcd exited Nov 27 17:08:22 smsl--n100 sh[640]: ifup: failed to bring up eth0 Nov 27 17:08:22 smsl--n100 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 27 17:08:22 smsl--n100 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Loading plugin "airplay_emulation"... Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Starting Shairport Sync Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Loading plugin "cd_controller"... Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Loading plugin "last_100"... Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Loading plugin "raat"... Nov 27 17:08:23 smsl--n100 volumio[1117]: info: RAAT Plugin loaded Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Adding restartRAATSocket REST API Endpoint Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Nov 27 17:08:23 smsl--n100 volumio[1117]: info: Loading plugin "streaming_services"... Nov 27 17:08:25 smsl--n100 volumio[1117]: info: Starting Streaming Service Transparent Proxy Nov 27 17:08:25 smsl--n100 volumio[1117]: info: Loading plugin "tidalconnect"... Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Loading plugin "webradio"... Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Loading plugin "i2s_dacs"... Nov 27 17:08:26 smsl--n100 volumio[1117]: info: I2S DAC not set, start Auto-detection Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Loading plugin "volumiodiscovery"... Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** For more information see Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 17:08:26 smsl--n100 volumio[1117]: *** WARNING *** For more information see Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** For more information see Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 17:08:26 smsl--n100 node[1117]: *** WARNING *** For more information see Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Discovery: Started advertising with name: SMSL N100 Nov 27 17:08:26 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 17:08:26 smsl--n100 volumio[1117]: info: Loading plugin "multiroom"... Nov 27 17:08:27 smsl--n100 volumio[1117]: STREAMING PROXY: Starting server on port 3245 Nov 27 17:08:27 smsl--n100 volumio[1117]: Node JS runtime: 14 Nov 27 17:08:28 smsl--n100 volumio[1117]: info: Applying required configuration parameters for plugin multiroom Nov 27 17:08:28 smsl--n100 sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 27 17:08:28 smsl--n100 sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:28 smsl--n100 sudo[1335]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:28 smsl--n100 volumio[1117]: info: MRS: MultiRoom plugin initialized Nov 27 17:08:28 smsl--n100 volumio[1117]: info: MRS: STOPPING SNAPCLIENT Nov 27 17:08:28 smsl--n100 volumio[1117]: info: MRS: Snap server stop Nov 27 17:08:28 smsl--n100 volumio[1117]: info: MRS: STOPPING volumioStreaming Nov 27 17:08:28 smsl--n100 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 27 17:08:28 smsl--n100 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:28 smsl--n100 sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 27 17:08:28 smsl--n100 sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:28 smsl--n100 volumio[1117]: info: Loading plugin "outputs"... Nov 27 17:08:28 smsl--n100 sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 27 17:08:28 smsl--n100 sudo[1351]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:28 smsl--n100 volumio[1117]: info: Loading plugin "albumart"... Nov 27 17:08:28 smsl--n100 sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:28 smsl--n100 sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 27 17:08:28 smsl--n100 sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:28 smsl--n100 sudo[1363]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:28 smsl--n100 sudo[1353]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:28 smsl--n100 volumio[1117]: info: Plugin example_plugin is not enabled Nov 27 17:08:28 smsl--n100 volumio[1117]: info: Loading plugin "hi_res_audio"... Nov 27 17:08:28 smsl--n100 sudo[1356]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:29 smsl--n100 volumio[1117]: Forking 3 albumart workers Nov 27 17:08:30 smsl--n100 volumio[1117]: Starting albumart workers Nov 27 17:08:30 smsl--n100 volumio[1117]: Starting albumart workers Nov 27 17:08:30 smsl--n100 volumio[1117]: Starting albumart workers Nov 27 17:08:30 smsl--n100 volumio[1117]: info: Applying required configuration parameters for plugin hi_res_audio Nov 27 17:08:31 smsl--n100 volumio[1117]: info: Loading plugin "inputs"... Nov 27 17:08:31 smsl--n100 volumio-remote-updater[712]: [2024-11-27 17:08:31] [connect] Successful connection Nov 27 17:08:32 smsl--n100 volumio[1117]: info: Loading plugin "qobuz"... Nov 27 17:08:34 smsl--n100 volumio[1117]: info: Loading plugin "smart_inputs"... Nov 27 17:08:34 smsl--n100 volumio[1117]: info: Loading plugin "tidal"... Nov 27 17:08:36 smsl--n100 volumio[1117]: info: Loading plugin "oem_helper"... Nov 27 17:08:37 smsl--n100 volumio[1117]: info: Applying required configuration parameters for plugin oem_helper Nov 27 17:08:37 smsl--n100 volumio[1117]: info: Loading plugin "smslcontrol"... Nov 27 17:08:37 smsl--n100 volumio[1117]: info: Adding this device properties Nov 27 17:08:37 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Nov 27 17:08:37 smsl--n100 volumio[1117]: info: Setting Additional Device Volumio Properties: [object Object] Nov 27 17:08:37 smsl--n100 volumio[1117]: info: Loading plugin "updater_comm"... Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Plugin mpdemulation is not enabled Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Loading plugin "rest_api"... Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Loading plugin "websocket"... Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Starting Socket.io Server version 2.3.0 Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Loading i18n strings for locale zh Nov 27 17:08:38 smsl--n100 volumio[1117]: Updating browse sources language Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::initPlayerControls Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 17:08:38 smsl--n100 volumio[1117]: Express server listening on port 3000 Nov 27 17:08:38 smsl--n100 volumio[1117]: [Metrics] WebUI: 24s 990.10ms Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreStateMachine::resetVolumioState Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreStateMachine::getcurrentVolume Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:38 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:38 smsl--n100 sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 17:08:38 smsl--n100 sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:38 smsl--n100 sudo[1446]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:38 smsl--n100 volumio[1117]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Completed loading Core Plugins Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Preparing to generate the ALSA configuration file Nov 27 17:08:38 smsl--n100 sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 17:08:38 smsl--n100 sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:38 smsl--n100 sudo[1448]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Volumio Network Manager: Network status updated: 2 Nov 27 17:08:38 smsl--n100 volumio[1117]: info: MRS: Removed streaming files Nov 27 17:08:38 smsl--n100 volumio[1117]: info: MRS: volumioStreaming STOPPED Nov 27 17:08:38 smsl--n100 volumio[1117]: info: MRS: SNAPSERVER STOPPED Nov 27 17:08:38 smsl--n100 volumio[1117]: info: MRS: SNAPCLIENT STOPPED Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 27 17:08:38 smsl--n100 volumio[1117]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 27 17:08:38 smsl--n100 volumio[1117]: info: Reading ALSA contributions from plugins. Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.215 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 12 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 13 Nov 27 17:08:39 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 14 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Reloading queue from file Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreStateMachine::setRepeat true single undefined Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreStateMachine::setRandom true Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Asound.conf file unchanged, so no further update is needed Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Output device has changed, restarting MPD Nov 27 17:08:39 smsl--n100 sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 27 17:08:39 smsl--n100 sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 17:08:39 smsl--n100 sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Output device has changed, restarting Shairport Sync Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:39 smsl--n100 sudo[1474]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:39 smsl--n100 sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 17:08:39 smsl--n100 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 27 17:08:39 smsl--n100 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 sudo[1471]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:39 smsl--n100 systemd[1]: Stopping Music Player Daemon... Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:39 smsl--n100 volumio[1117]: info: ___________ START PLUGINS ___________ Nov 27 17:08:39 smsl--n100 volumio[1117]: info: ControllerMpd::onStart: Initializing MPD Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Creating MPD Configuration file Nov 27 17:08:39 smsl--n100 sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 17:08:39 smsl--n100 sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 17:08:39 smsl--n100 volumio[1117]: info: [1732698519710] CoreMusicLibrary::Adding element 媒体服务器 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 17:08:39 smsl--n100 systemd[1]: mpd.service: Succeeded. Nov 27 17:08:39 smsl--n100 sudo[1486]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:39 smsl--n100 systemd[1]: Stopped Music Player Daemon. Nov 27 17:08:39 smsl--n100 sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 17:08:39 smsl--n100 sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 17:08:39 smsl--n100 systemd[1]: Starting Music Player Daemon... Nov 27 17:08:39 smsl--n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 17:08:39 smsl--n100 systemd[1]: mpd.service: Succeeded. Nov 27 17:08:39 smsl--n100 systemd[1]: Stopped Music Player Daemon. Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding METAVOLUMIO REST API Endpoints Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:39 smsl--n100 systemd[1]: Starting Music Player Daemon... Nov 27 17:08:39 smsl--n100 sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Nov 27 17:08:39 smsl--n100 sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:39 smsl--n100 kernel: Bluetooth: Core ver 2.22 Nov 27 17:08:39 smsl--n100 kernel: NET: Registered PF_BLUETOOTH protocol family Nov 27 17:08:39 smsl--n100 kernel: Bluetooth: HCI device and connection manager initialized Nov 27 17:08:39 smsl--n100 kernel: Bluetooth: HCI socket layer initialized Nov 27 17:08:39 smsl--n100 kernel: Bluetooth: L2CAP socket layer initialized Nov 27 17:08:39 smsl--n100 kernel: Bluetooth: SCO socket layer initialized Nov 27 17:08:39 smsl--n100 sudo[1494]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:39 smsl--n100 sudo[1495]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 17:08:39 smsl--n100 sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Preparing CD Folders Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding CD REST API Endpoints Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Starting UDEV Watcher for CD Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Detecting CD presence with UDEV Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 27 17:08:39 smsl--n100 sudo[1495]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 17:08:39 smsl--n100 volumio[1117]: info: [1732698519986] CoreMusicLibrary::Adding element Last_100 Nov 27 17:08:39 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 17:08:39 smsl--n100 volumio[1117]: info: Starting RAAT Plugin Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Additional UI Settings Added for plugin music_service/raat Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Registering DSP Elements listener and retrieving current ones Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Additional DSP elements updated Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Updating RAAT Signal Path Nov 27 17:08:40 smsl--n100 volumio[1117]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Streaming services startup Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Starting Streaming Daemon Nov 27 17:08:40 smsl--n100 sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 27 17:08:40 smsl--n100 sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:40 smsl--n100 sudo[1510]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 17:08:40 smsl--n100 volumio[1117]: info: [1732698520154] CoreMusicLibrary::Adding element Webradio Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Initializing BBC Radios Nov 27 17:08:40 smsl--n100 sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 17:08:40 smsl--n100 sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:40 smsl--n100 sudo[1517]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 27 17:08:40 smsl--n100 volumio[1117]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:40 smsl--n100 volumio[1117]: error: Hi Res Audio Failed Login: Missing Login Data Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Initializing Serial Communication on port /dev/ttyS1 Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Touch Event Listener Process Starting Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding inputs REST Endpoints Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Scanning Audio Inputs Nov 27 17:08:40 smsl--n100 sudo[1534]: pam_unix(sudo:auth): conversation failed Nov 27 17:08:40 smsl--n100 sudo[1534]: pam_unix(sudo:auth): auth could not identify password for [volumio] Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Checking against Known Cards name Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding Server instance for streaming Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 17:08:40 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Initializing GPIOs Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Starting Button Listener Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Turning off Green LED Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Tuning on Blue LED Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Signaling System Ready Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Volumio Calling Home Nov 27 17:08:40 smsl--n100 volumio[1117]: (node:1117) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Nov 27 17:08:40 smsl--n100 volumio[1117]: (Use `node --trace-deprecation ...` to show where the warning was created) Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Stopping AccessToken refresher cron for QOBUZ Nov 27 17:08:40 smsl--n100 volumio[1117]: info: AccessToken refresher cron started for QOBUZ Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Stopping AccessToken refresher cron Nov 27 17:08:40 smsl--n100 volumio[1117]: info: AccessToken refresher cron started Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding TIDAL REST API Endpoints Nov 27 17:08:40 smsl--n100 volumio[1117]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Adding QOBUZ REST API Endpoints Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Serial port opened successfully Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Sending serial start messages Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Reporting MCU Network Status: 2 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Touch Event Listener Process Closed Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Cannot start Volumio Streaming Daemon Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 27 17:08:41 smsl--n100 volumio[1117]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 27 17:08:41 smsl--n100 volumio[1117]: info: RAAT Albumart path created successfully Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Nov 27 17:08:41 smsl--n100 volumio[1117]: Can't get device info: No such device Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MPD Permissions set Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MPD Permissions set Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Upmpdcli Daemon Started Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Setting Device Volume Override Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Disabling external Volume Control Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 2, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Starting Shairport Sync Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Starting Shairport Sync Nov 27 17:08:41 smsl--n100 sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Starting Shairport Sync Nov 27 17:08:41 smsl--n100 sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:41 smsl--n100 sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 17:08:41 smsl--n100 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:41 smsl--n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 17:08:41 smsl--n100 systemd[1]: shairport-sync.service: Succeeded. Nov 27 17:08:41 smsl--n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 17:08:41 smsl--n100 sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 17:08:41 smsl--n100 sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:41 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:41 smsl--n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 17:08:41 smsl--n100 sudo[1577]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 27 17:08:41 smsl--n100 volumio[1117]: error: Serial API: Failed to decode command: 100, message: undefined Nov 27 17:08:41 smsl--n100 volumio[1117]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Shairport-Sync Started Nov 27 17:08:41 smsl--n100 volumio[1117]: Error adding Membership: Error: addMembership EINVAL Nov 27 17:08:41 smsl--n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 17:08:41 smsl--n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 27 17:08:41 smsl--n100 systemd[1]: shairport-sync.service: Succeeded. Nov 27 17:08:41 smsl--n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 17:08:41 smsl--n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 17:08:41 smsl--n100 sudo[1579]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Shairport-Sync Started Nov 27 17:08:41 smsl--n100 sudo[1582]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:41 smsl--n100 volumio[1117]: info: Shairport-Sync Started Nov 27 17:08:42 smsl--n100 mpd[1506]: Nov 27 17:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 17:08:42 smsl--n100 systemd[1]: Started Music Player Daemon. Nov 27 17:08:42 smsl--n100 volumio[1117]: Upnp client error: Error: This socket has been ended by the other party Nov 27 17:08:42 smsl--n100 sudo[1477]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:42 smsl--n100 sudo[1488]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:42 smsl--n100 volumio[1117]: error: MPD error: The expression evaluated to a falsy value: Nov 27 17:08:42 smsl--n100 volumio[1117]: assert.ok(self.idling) Nov 27 17:08:42 smsl--n100 volumio[1117]: error: The expression evaluated to a falsy value: Nov 27 17:08:42 smsl--n100 volumio[1117]: assert.ok(self.idling) Nov 27 17:08:42 smsl--n100 volumio[1117]: error: updateQueue error: null Nov 27 17:08:42 smsl--n100 volumio[1117]: info: MPD running with PID1506 Nov 27 17:08:42 smsl--n100 volumio[1117]: ,establishing connection Nov 27 17:08:42 smsl--n100 volumio[1117]: error: updateQueue error: null Nov 27 17:08:42 smsl--n100 sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 27 17:08:42 smsl--n100 sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:42 smsl--n100 systemd[1]: Started Volumio Bluetooth Module. Nov 27 17:08:42 smsl--n100 sudo[1594]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:42 smsl--n100 volumio[1117]: info: Volumio BT Module successfully started Nov 27 17:08:42 smsl--n100 sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 27 17:08:42 smsl--n100 sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:42 smsl--n100 volumiobt[1596]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 27 17:08:42 smsl--n100 sudo[1597]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:43 smsl--n100 volumio[1117]: info: TidalConnect service stoped! Nov 27 17:08:43 smsl--n100 volumio[1117]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 17:08:43 smsl--n100 volumio[1117]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 17:08:43 smsl--n100 sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 17:08:43 smsl--n100 sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:43 smsl--n100 systemd[1]: Started Volumio Tidal Connect Service. Nov 27 17:08:43 smsl--n100 sudo[1609]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:43 smsl--n100 volumio[1476]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 27 17:08:43 smsl--n100 volumio[1117]: info: Executing endpoint tc_getconfig Nov 27 17:08:43 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 27 17:08:43 smsl--n100 vtcs[1611]: STARTING TidalConnect services, version: 1.4.0.34 Nov 27 17:08:43 smsl--n100 vtcs[1611]: STARTED TidalConnect services. Nov 27 17:08:43 smsl--n100 sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 27 17:08:43 smsl--n100 sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 17:08:44 smsl--n100 volumio[1117]: info: Executing endpoint tc_connect Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 27 17:08:44 smsl--n100 volumiobt[1596]: pulseaudio: no process found Nov 27 17:08:44 smsl--n100 volumio[1117]: info: Connecting to TidalConnect Nov 27 17:08:44 smsl--n100 sudo[1630]: pam_unix(sudo:session): session closed for user root Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreCommandRouter::servicePushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:44 smsl--n100 volumio[1117]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreCommandRouter::servicePushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreStateMachine::pushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioPushState Nov 27 17:08:44 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:44 smsl--n100 volumio[1117]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1649 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 27 17:08:44 smsl--n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 27 17:08:44 smsl--n100 volumio[1117]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 27 17:08:44 smsl--n100 systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Successfully called chroot. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Successfully dropped privileges. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Successfully limited resources. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Running. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Canary thread running. Nov 27 17:08:44 smsl--n100 rtkit-daemon[1650]: Watchdog thread running. Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1650 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 27 17:08:44 smsl--n100 systemd[1]: Starting Authorization Manager... Nov 27 17:08:44 smsl--n100 volumio[1117]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 17:08:44 smsl--n100 polkitd[1653]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 27 17:08:44 smsl--n100 systemd[1]: Started Authorization Manager. Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.37' (uid=1000 pid=1649 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 27 17:08:44 smsl--n100 systemd[1]: Starting Bluetooth service... Nov 27 17:08:44 smsl--n100 volumio[1117]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 17:08:44 smsl--n100 pulseaudio[1649]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 27 17:08:44 smsl--n100 pulseaudio[1649]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Applying permissions Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Setting BT discoverable and pairable Nov 27 17:08:44 smsl--n100 bluetoothd[1658]: Bluetooth daemon 5.50 Nov 27 17:08:44 smsl--n100 bluetoothd[1658]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Successfully activated service 'org.bluez' Nov 27 17:08:44 smsl--n100 systemd[1]: Started Bluetooth service. Nov 27 17:08:44 smsl--n100 bluetoothd[1658]: Starting SDP server Nov 27 17:08:44 smsl--n100 bluetoothd[1658]: Excluding (cli) sap Nov 27 17:08:44 smsl--n100 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 27 17:08:44 smsl--n100 kernel: Bluetooth: BNEP filters: protocol multicast Nov 27 17:08:44 smsl--n100 kernel: Bluetooth: BNEP socket layer initialized Nov 27 17:08:44 smsl--n100 dbus-daemon[711]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.38' (uid=0 pid=1658 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 27 17:08:44 smsl--n100 bluetoothd[1658]: Bluetooth management interface 1.22 initialized Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# -e power on Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Invalid command in menu main: -e Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Use "help" for a list of available commands in a menu. Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Use "menu " if you want to enter any submenu. Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Use "back" if you want to return to menu main. Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# agent on Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Failed to register agent object Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# discoverable on Nov 27 17:08:44 smsl--n100 volumiobt[1596]: No default controller available Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# pairable on Nov 27 17:08:44 smsl--n100 volumiobt[1596]: No default controller available Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# agent NoInputNoOutput Nov 27 17:08:44 smsl--n100 volumiobt[1596]: Failed to register agent object Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# default-agent Nov 27 17:08:44 smsl--n100 volumiobt[1596]: No agent is registered Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [bluetooth]# quit Nov 27 17:08:44 smsl--n100 pulseaudio[1649]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 27 17:08:44 smsl--n100 volumiobt[1596]: [59B blob data] Nov 27 17:08:44 smsl--n100 systemd[1]: Starting Hostname Service... Nov 27 17:08:44 smsl--n100 volumio[1117]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 17:08:45 smsl--n100 volumio[1117]: info: MRS: Getting audio outputs on start Nov 27 17:08:45 smsl--n100 volumio[1117]: info: MRS: Requesting all other devices output Nov 27 17:08:46 smsl--n100 systemd[1]: systemd-timedated.service: Succeeded. Nov 27 17:08:46 smsl--n100 dbus-daemon[711]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 27 17:08:46 smsl--n100 systemd[1]: Started Hostname Service. Nov 27 17:08:46 smsl--n100 volumio-remote-updater[712]: [2024-11-27 17:08:46] [connect] Successful connection Nov 27 17:08:46 smsl--n100 volumio-remote-updater[712]: [2024-11-27 17:08:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732698526 101 Nov 27 17:08:46 smsl--n100 volumio[1117]: 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: 16 Nov 27 17:08:46 smsl--n100 volumio[1117]: info: TidalConnect service started! Nov 27 17:08:46 smsl--n100 volumio[1117]: info: Completed starting Core Plugins Nov 27 17:08:46 smsl--n100 volumio[1117]: info: ------------------------------------------- Nov 27 17:08:46 smsl--n100 volumio[1117]: info: ----- MyVolumio plugins startup ---- Nov 27 17:08:46 smsl--n100 volumio[1117]: info: ------------------------------------------- Nov 27 17:08:46 smsl--n100 volumio[1117]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 27 17:08:46 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 17 Nov 27 17:08:46 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 18 Nov 27 17:08:47 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 19 Nov 27 17:08:48 smsl--n100 volumio[1476]: ...................................................................++++ Nov 27 17:08:48 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioGetState Nov 27 17:08:48 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:08:48 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 27 17:08:48 smsl--n100 volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 27 17:08:49 smsl--n100 volumio[1476]: ....++++ Nov 27 17:08:49 smsl--n100 volumio[1476]: e is 65537 (0x010001) Nov 27 17:08:49 smsl--n100 volumio[1476]: writing RSA key Nov 27 17:08:57 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 20 Nov 27 17:09:01 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to 192.168.2.142 from 192.168.2.38 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 21 Nov 27 17:09:01 smsl--n100 ntpd[1146]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 27 17:09:01 smsl--n100 ntpd[1146]: Soliciting pool server 193.182.111.14 Nov 27 17:09:01 smsl--n100 ntpd[1146]: Soliciting pool server 193.182.111.143 Nov 27 17:09:01 smsl--n100 volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Nov 27 17:09:01 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioGetState Nov 27 17:09:01 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:09:01 smsl--n100 ntpd[1146]: Soliciting pool server 162.159.200.1 Nov 27 17:09:01 smsl--n100 volumio[1117]: info: Discovery: adding 24125e11-6d00-4707-9d49-54d751f1b8d6 Nov 27 17:09:01 smsl--n100 volumio[1117]: info: Discovery: Found device SMSL N100 Nov 27 17:09:01 smsl--n100 volumio[1117]: info: CoreCommandRouter::volumioGetState Nov 27 17:09:01 smsl--n100 volumio[1117]: info: CorePlayQueue::getTrack 0 Nov 27 17:09:01 smsl--n100 volumio[1117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 17:09:01 smsl--n100 volumio[1117]: Error: The value NaN is not a number Nov 27 17:09:01 smsl--n100 volumio[1117]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 27 17:09:01 smsl--n100 volumio[1117]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 27 17:09:01 smsl--n100 volumio[1117]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 27 17:09:01 smsl--n100 volumio[1117]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 27 17:09:01 smsl--n100 volumio[1117]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 27 17:09:01 smsl--n100 volumio[1117]: at Browser.emit (events.js:400:28) Nov 27 17:09:01 smsl--n100 volumio[1117]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 27 17:09:01 smsl--n100 volumio[1117]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 27 17:09:01 smsl--n100 volumio[1117]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 27 17:09:01 smsl--n100 volumio[1117]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 27 17:09:01 smsl--n100 volumio[1117]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 27 17:09:01 smsl--n100 volumio[1117]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 27 17:09:01 smsl--n100 volumio[1117]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 27 17:09:01 smsl--n100 volumio[1117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 17:09:02 smsl--n100 sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 17:08 Nov 27 17:09:02 smsl--n100 sudo[1890]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 14 Nov 2024 10:37:31 AM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="693350a73c5f32caa813f2d9ff9306ea"