-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-10 20:33:49 CEST. -- May 10 20:32:55 smsl-n100 systemd-timedated[988]: Changed local time to Sat May 10 20:32:55 2025 May 10 20:32:55 smsl-n100 sudo[986]: pam_unix(sudo:session): session closed for user root May 10 20:32:55 smsl-n100 volumio-time-update[543]: volumio-time-update-util: System time updated successfully. May 10 20:32:55 smsl-n100 systemd[1]: Started Volumio Time Update Utility. May 10 20:32:55 smsl-n100 ntpd[961]: Soliciting pool server 185.79.243.74 May 10 20:32:55 smsl-n100 ntpd[961]: Soliciting pool server 46.174.178.150 May 10 20:32:56 smsl-n100 ntpd[961]: Soliciting pool server 162.159.200.123 May 10 20:32:56 smsl-n100 nmbd[659]: [2025/05/10 20:32:56.521376, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 10 20:32:56 smsl-n100 nmbd[659]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 10 20:32:56 smsl-n100 systemd[1]: Started Samba NMB Daemon. May 10 20:32:56 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... May 10 20:32:56 smsl-n100 ntpd[961]: Soliciting pool server 185.157.13.96 May 10 20:32:56 smsl-n100 ntpd[961]: Soliciting pool server 162.159.200.1 May 10 20:32:56 smsl-n100 systemd[1]: systemd-fsckd.service: Succeeded. May 10 20:32:56 smsl-n100 ntpd[961]: Soliciting pool server 212.127.78.21 May 10 20:32:57 smsl-n100 winbindd[990]: [2025/05/10 20:32:57.181246, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 10 20:32:57 smsl-n100 winbindd[990]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 10 20:32:57 smsl-n100 winbindd[990]: [2025/05/10 20:32:57.219041, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 10 20:32:57 smsl-n100 systemd[1]: Started Samba Winbind Daemon. May 10 20:32:57 smsl-n100 winbindd[990]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 10 20:32:57 smsl-n100 systemd[1]: Starting Samba SMB Daemon... May 10 20:32:57 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:32:57 smsl-n100 volumio[964]: info: ----- Volumio3 ---- May 10 20:32:57 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:32:57 smsl-n100 volumio[964]: info: ----- System startup ---- May 10 20:32:57 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:32:57 smsl-n100 ntpd[961]: Soliciting pool server 46.174.178.150 May 10 20:32:57 smsl-n100 ntpd[961]: Soliciting pool server 89.25.168.148 May 10 20:32:57 smsl-n100 sh[466]: timed out May 10 20:32:57 smsl-n100 sh[466]: dhcpcd exited May 10 20:32:57 smsl-n100 dhcpcd[500]: timed out May 10 20:32:57 smsl-n100 sh[466]: ifup: failed to bring up eth0 May 10 20:32:57 smsl-n100 dhcpcd[500]: dhcpcd exited May 10 20:32:57 smsl-n100 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 10 20:32:57 smsl-n100 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 10 20:32:58 smsl-n100 smbd[1003]: [2025/05/10 20:32:58.187752, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 10 20:32:58 smsl-n100 systemd[1]: Started Samba SMB Daemon. May 10 20:32:58 smsl-n100 smbd[1003]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 10 20:32:58 smsl-n100 systemd[1]: Reached target Multi-User System. May 10 20:32:58 smsl-n100 systemd[1]: Reached target Graphical Interface. May 10 20:32:58 smsl-n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... May 10 20:32:58 smsl-n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 10 20:32:58 smsl-n100 systemd[1]: Started Update UTMP about System Runlevel Changes. May 10 20:32:58 smsl-n100 systemd[1]: Startup finished in 6.211s (kernel) + 35.072s (userspace) = 41.283s. May 10 20:32:58 smsl-n100 ntpd[961]: Soliciting pool server 2a05:9404::15e May 10 20:32:59 smsl-n100 volumio[964]: info: MYVOLUMIO Environment detected May 10 20:32:59 smsl-n100 volumio[964]: info: Plugin folders cleanup May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning into folder /volumio/app/plugins/ May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category audio_interface May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category miscellanea May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category music_service May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category plugins.json May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category system_controller May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning category user_interface May 10 20:32:59 smsl-n100 volumio[964]: info: Scanning into folder /data/plugins/ May 10 20:32:59 smsl-n100 volumio[964]: info: Plugin folders cleanup completed May 10 20:32:59 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:32:59 smsl-n100 volumio[964]: info: ----- Core plugins startup ---- May 10 20:32:59 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:32:59 smsl-n100 volumio[964]: info: Loading plugins from folder /volumio/app/plugins/ May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin bluetooth to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin multiroom to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin upnp to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin metavolumio to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin airplay_emulation to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin cd_controller to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin raat to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin smart_inputs to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin tidalconnect to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Adding plugin upnp_browser to MyMusic Plugins May 10 20:32:59 smsl-n100 volumio[964]: info: Loading plugins from folder /data/plugins/ May 10 20:32:59 smsl-n100 volumio[964]: info: Loading plugin "system"... May 10 20:33:00 smsl-n100 volumio[964]: info: Loading plugin "appearance"... May 10 20:33:02 smsl-n100 volumio-remote-updater[564]: [2025-05-10 20:33:02] [connect] Successful connection May 10 20:33:02 smsl-n100 volumio[964]: info: Loading plugin "network"... May 10 20:33:02 smsl-n100 volumio[964]: info: Refreshing Cached IP Addresses May 10 20:33:02 smsl-n100 sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 20:33:02 smsl-n100 sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:02 smsl-n100 sudo[1021]: pam_unix(sudo:session): session closed for user root May 10 20:33:02 smsl-n100 sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 20:33:02 smsl-n100 sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:02 smsl-n100 sudo[1023]: pam_unix(sudo:session): session closed for user root May 10 20:33:03 smsl-n100 ntpd[961]: receive: Unexpected origin timestamp 0xebca1a5e.a1256a95 does not match aorg 0000000000.00000000 from server@194.146.251.100 xmt 0xebca1a5f.d8cd7796 May 10 20:33:03 smsl-n100 ntpd[961]: receive: Unexpected origin timestamp 0xebca1a5e.a06dbd19 does not match aorg 0000000000.00000000 from server@46.174.178.150 xmt 0xebca1a5f.d883212e May 10 20:33:03 smsl-n100 volumio[964]: info: Loading plugin "services"... May 10 20:33:03 smsl-n100 volumio[964]: info: Loading plugin "alsa_controller"... May 10 20:33:03 smsl-n100 ntpd[961]: receive: Unexpected origin timestamp 0xebca1a5e.a10b07d8 does not match aorg 0000000000.00000000 from server@185.79.243.74 xmt 0xebca1a5f.de80e065 May 10 20:33:03 smsl-n100 sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 10 20:33:03 smsl-n100 sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:04 smsl-n100 volumio[964]: warn: Unable to locate the audio output device E30 II. Please configure a valid output device. May 10 20:33:04 smsl-n100 volumio[964]: Invalid card number. May 10 20:33:04 smsl-n100 volumio[964]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer May 10 20:33:04 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 10 20:33:04 smsl-n100 volumio[964]: Invalid card number. May 10 20:33:04 smsl-n100 sudo[1034]: pam_unix(sudo:session): session closed for user root May 10 20:33:04 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 10 20:33:04 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "wizard"... May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "networkfs"... May 10 20:33:04 smsl-n100 volumio[964]: info: Starting Udev Watcher for removable devices May 10 20:33:04 smsl-n100 sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.166/USB /mnt/NAS/USB May 10 20:33:04 smsl-n100 sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:04 smsl-n100 volumio[964]: info: Ignoring mount for partition: boot May 10 20:33:04 smsl-n100 volumio[964]: info: Ignoring mount for partition: volumio May 10 20:33:04 smsl-n100 volumio[964]: info: Ignoring mount for partition: volumio_data May 10 20:33:04 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "volumio_command_line_client"... May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "upnp"... May 10 20:33:04 smsl-n100 volumio[964]: info: [1746901984446] Starting Upmpd Daemon May 10 20:33:04 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "my_music"... May 10 20:33:04 smsl-n100 kernel: FS-Cache: Loaded May 10 20:33:04 smsl-n100 volumio[964]: info: Loading plugin "mpd"... May 10 20:33:04 smsl-n100 kernel: FS-Cache: Netfs 'cifs' registered for caching May 10 20:33:04 smsl-n100 kernel: Key type cifs.spnego registered May 10 20:33:04 smsl-n100 kernel: Key type cifs.idmap registered May 10 20:33:04 smsl-n100 kernel: CIFS: Attempting to mount //192.168.0.166/USB May 10 20:33:04 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 10 20:33:04 smsl-n100 sudo[1055]: pam_unix(sudo:session): session closed for user root May 10 20:33:05 smsl-n100 volumio[964]: info: Loading plugin "upnp_browser"... May 10 20:33:06 smsl-n100 volumio[964]: info: Loading plugin "bluetooth"... May 10 20:33:06 smsl-n100 volumio[964]: info: [1746901986392] Starting BluetoothController May 10 20:33:06 smsl-n100 volumio[964]: info: Loading plugin "alarm-clock"... May 10 20:33:07 smsl-n100 volumio[964]: info: Loading plugin "manifestui"... May 10 20:33:07 smsl-n100 volumio[964]: info: Loading plugin "metavolumio"... May 10 20:33:10 smsl-n100 volumio[964]: info: Loading plugin "airplay_emulation"... May 10 20:33:10 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:10 smsl-n100 volumio[964]: info: Loading plugin "cd_controller"... May 10 20:33:10 smsl-n100 volumio[964]: info: Loading plugin "last_100"... May 10 20:33:10 smsl-n100 volumio[964]: info: Loading plugin "raat"... May 10 20:33:11 smsl-n100 volumio[964]: info: RAAT Plugin loaded May 10 20:33:11 smsl-n100 volumio[964]: info: Adding restartRAATSocket REST API Endpoint May 10 20:33:11 smsl-n100 volumio[964]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat May 10 20:33:11 smsl-n100 volumio[964]: info: Loading plugin "streaming_services"... May 10 20:33:13 smsl-n100 volumio[964]: info: Starting Streaming Service Transparent Proxy May 10 20:33:13 smsl-n100 volumio[964]: info: Loading plugin "tidalconnect"... May 10 20:33:13 smsl-n100 volumio[964]: info: Loading plugin "webradio"... May 10 20:33:14 smsl-n100 volumio[964]: info: Loading plugin "i2s_dacs"... May 10 20:33:14 smsl-n100 volumio[964]: info: I2S DAC not set, start Auto-detection May 10 20:33:14 smsl-n100 volumio[964]: info: Loading plugin "volumiodiscovery"... May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** For more information see May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 20:33:14 smsl-n100 volumio[964]: *** WARNING *** For more information see May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** For more information see May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 20:33:14 smsl-n100 node[964]: *** WARNING *** For more information see May 10 20:33:14 smsl-n100 volumio[964]: info: Applying required configuration parameters for plugin volumiodiscovery May 10 20:33:14 smsl-n100 volumio[964]: info: Discovery: Started advertising with name: SMSL N100 May 10 20:33:14 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 20:33:14 smsl-n100 volumio[964]: info: Loading plugin "multiroom"... May 10 20:33:15 smsl-n100 volumio[964]: STREAMING PROXY: Starting server on port 3245 May 10 20:33:15 smsl-n100 volumio[964]: Node JS runtime: 14 May 10 20:33:17 smsl-n100 volumio[964]: info: Applying required configuration parameters for plugin multiroom May 10 20:33:17 smsl-n100 sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 10 20:33:17 smsl-n100 sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:17 smsl-n100 sudo[1107]: pam_unix(sudo:session): session closed for user root May 10 20:33:17 smsl-n100 volumio[964]: info: MRS: MultiRoom plugin initialized May 10 20:33:17 smsl-n100 volumio[964]: info: MRS: STOPPING SNAPCLIENT May 10 20:33:17 smsl-n100 volumio[964]: info: MRS: Snap server stop May 10 20:33:17 smsl-n100 volumio[964]: info: MRS: STOPPING volumioStreaming May 10 20:33:17 smsl-n100 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 10 20:33:17 smsl-n100 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:17 smsl-n100 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 10 20:33:17 smsl-n100 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:18 smsl-n100 volumio[964]: info: Loading plugin "outputs"... May 10 20:33:18 smsl-n100 sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 10 20:33:18 smsl-n100 sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:18 smsl-n100 volumio[964]: info: Loading plugin "albumart"... May 10 20:33:18 smsl-n100 sudo[1123]: pam_unix(sudo:session): session closed for user root May 10 20:33:18 smsl-n100 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 10 20:33:18 smsl-n100 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:18 smsl-n100 sudo[1131]: pam_unix(sudo:session): session closed for user root May 10 20:33:18 smsl-n100 volumio[964]: info: Plugin example_plugin is not enabled May 10 20:33:18 smsl-n100 volumio[964]: info: Loading plugin "hi_res_audio"... May 10 20:33:18 smsl-n100 sudo[1125]: pam_unix(sudo:session): session closed for user root May 10 20:33:18 smsl-n100 sudo[1128]: pam_unix(sudo:session): session closed for user root May 10 20:33:18 smsl-n100 volumio-remote-updater[564]: [2025-05-10 20:33:18] [connect] Successful connection May 10 20:33:18 smsl-n100 volumio[964]: Forking 3 albumart workers May 10 20:33:20 smsl-n100 volumio[964]: Starting albumart workers May 10 20:33:20 smsl-n100 volumio[964]: Starting albumart workers May 10 20:33:21 smsl-n100 volumio[964]: Starting albumart workers May 10 20:33:21 smsl-n100 volumio[964]: info: Applying required configuration parameters for plugin hi_res_audio May 10 20:33:21 smsl-n100 volumio[964]: info: Loading plugin "inputs"... May 10 20:33:23 smsl-n100 volumio[964]: info: Loading plugin "qobuz"... May 10 20:33:25 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 10 20:33:25 smsl-n100 dhcpcd[617]: eth0: carrier acquired May 10 20:33:25 smsl-n100 dhcpcd[617]: eth0: IAID 79:a6:a5:22 May 10 20:33:25 smsl-n100 dhcpcd[617]: eth0: adding address fe80::feb:fe84:5b95:8291 May 10 20:33:25 smsl-n100 dhcpcd[617]: ipv6_addaddr1: Permission denied May 10 20:33:25 smsl-n100 dhcpcd[617]: eth0: soliciting a DHCP lease May 10 20:33:25 smsl-n100 ifplugd(eth0)[740]: Link beat detected. May 10 20:33:25 smsl-n100 ifplugd(eth0)[740]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 10 20:33:25 smsl-n100 ifplugd(eth0)[740]: client: sending commands to master dhcpcd process May 10 20:33:25 smsl-n100 dhcpcd[1224]: sending commands to master dhcpcd process May 10 20:33:25 smsl-n100 dhcpcd[617]: control command: /sbin/dhcpcd eth0 May 10 20:33:26 smsl-n100 dhcpcd[617]: eth0: soliciting an IPv6 router May 10 20:33:26 smsl-n100 volumio[964]: info: Loading plugin "smart_inputs"... May 10 20:33:26 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. May 10 20:33:26 smsl-n100 ifplugd(eth0)[740]: Program executed successfully. May 10 20:33:26 smsl-n100 dhcpcd[617]: eth0: offered 192.168.1.74 from 192.168.1.1 May 10 20:33:26 smsl-n100 dhcpcd[617]: eth0: probing address 192.168.1.74/24 May 10 20:33:26 smsl-n100 volumio[964]: info: Loading plugin "tidal"... May 10 20:33:29 smsl-n100 volumio[964]: info: Loading plugin "oem_helper"... May 10 20:33:31 smsl-n100 dhcpcd[617]: eth0: leased 192.168.1.74 for 86400 seconds May 10 20:33:31 smsl-n100 avahi-daemon[540]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.74. May 10 20:33:31 smsl-n100 dhcpcd[617]: eth0: adding route to 192.168.1.0/24 May 10 20:33:31 smsl-n100 avahi-daemon[540]: New relevant interface eth0.IPv4 for mDNS. May 10 20:33:31 smsl-n100 avahi-daemon[540]: Registering new address record for 192.168.1.74 on eth0.IPv4. May 10 20:33:31 smsl-n100 volumio[964]: info: Applying required configuration parameters for plugin oem_helper May 10 20:33:31 smsl-n100 dhcpcd[617]: eth0: adding default route via 192.168.1.1 May 10 20:33:31 smsl-n100 volumio[964]: info: Loading plugin "smslcontrol"... May 10 20:33:31 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 May 10 20:33:31 smsl-n100 volumio[964]: info: Adding this device properties May 10 20:33:31 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 10 20:33:31 smsl-n100 volumio[964]: info: Setting Additional Device Volumio Properties: [object Object] May 10 20:33:31 smsl-n100 volumio[964]: info: Loading plugin "updater_comm"... May 10 20:33:31 smsl-n100 ntpd[961]: ntpd exiting on signal 15 (Terminated) May 10 20:33:31 smsl-n100 ntpd[961]: 194.146.251.100 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 systemd[1]: Stopping Network Time Service... May 10 20:33:31 smsl-n100 ntpd[961]: 212.127.95.218 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 193.70.94.182 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 185.79.243.74 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 162.159.200.123 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 systemd[1]: ntp.service: Succeeded. May 10 20:33:31 smsl-n100 ntpd[961]: 162.159.200.1 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 185.157.13.96 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 89.25.168.148 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 ntpd[961]: 46.174.178.150 local addr 192.168.0.166 -> May 10 20:33:31 smsl-n100 systemd[1]: Stopped Network Time Service. May 10 20:33:31 smsl-n100 systemd[1]: Starting Network Time Service... May 10 20:33:31 smsl-n100 ntpd[1331]: ntpd 4.2.8p12@1.3728-o (1): Starting May 10 20:33:31 smsl-n100 ntpd[1331]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 10 20:33:31 smsl-n100 systemd[1]: Started Network Time Service. May 10 20:33:31 smsl-n100 ntpd[1337]: proto: precision = 0.875 usec (-20) May 10 20:33:31 smsl-n100 ntpd[1337]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 10 20:33:31 smsl-n100 ntpd[1337]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 10 20:33:31 smsl-n100 ntpd[1337]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 865 days ago May 10 20:33:31 smsl-n100 ntpd[1337]: Listen and drop on 0 v6wildcard [::]:123 May 10 20:33:31 smsl-n100 ntpd[1337]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 10 20:33:31 smsl-n100 ntpd[1337]: Listen normally on 2 lo 127.0.0.1:123 May 10 20:33:31 smsl-n100 ntpd[1337]: Listen normally on 3 eth0 192.168.1.74:123 May 10 20:33:31 smsl-n100 ntpd[1337]: Listen normally on 4 wlan0 192.168.0.166:123 May 10 20:33:31 smsl-n100 ntpd[1337]: Listening on routing socket on fd #21 for interface updates May 10 20:33:31 smsl-n100 ntpd[1337]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 10 20:33:31 smsl-n100 ntpd[1337]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 10 20:33:32 smsl-n100 volumio[964]: info: Plugin mpdemulation is not enabled May 10 20:33:32 smsl-n100 volumio[964]: info: Loading plugin "rest_api"... May 10 20:33:32 smsl-n100 volumio[964]: info: Loading plugin "websocket"... May 10 20:33:32 smsl-n100 volumio[964]: info: Starting Socket.io Server version 2.3.0 May 10 20:33:32 smsl-n100 volumio[964]: info: Loading i18n strings for locale pl May 10 20:33:32 smsl-n100 volumio[964]: Updating browse sources language May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::initPlayerControls May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 20:33:32 smsl-n100 volumio[964]: Express server listening on port 3000 May 10 20:33:32 smsl-n100 volumio[964]: [Metrics] WebUI: 35s 852.09ms May 10 20:33:32 smsl-n100 volumio[964]: info: CoreStateMachine::resetVolumioState May 10 20:33:32 smsl-n100 volumio[964]: info: CoreStateMachine::getcurrentVolume May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioRetrievevolume May 10 20:33:32 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 10 20:33:33 smsl-n100 volumio[964]: info: CoreStateMachine::getcurrentVolume May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioRetrievevolume May 10 20:33:33 smsl-n100 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 20:33:33 smsl-n100 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:33 smsl-n100 sudo[1351]: pam_unix(sudo:session): session closed for user root May 10 20:33:33 smsl-n100 sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 20:33:33 smsl-n100 sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:33 smsl-n100 volumio[964]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 10 20:33:33 smsl-n100 volumio[964]: info: Completed loading Core Plugins May 10 20:33:33 smsl-n100 sudo[1353]: pam_unix(sudo:session): session closed for user root May 10 20:33:33 smsl-n100 volumio[964]: info: Preparing to generate the ALSA configuration file May 10 20:33:33 smsl-n100 volumio-remote-updater[564]: [2025-05-10 20:33:33] [connect] Successful connection May 10 20:33:33 smsl-n100 volumio[964]: info: Volumio Network Manager: Network status updated: 2 May 10 20:33:33 smsl-n100 volumio[964]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 10 20:33:33 smsl-n100 volumio[964]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 10 20:33:33 smsl-n100 volumio[964]: info: MRS: Removed streaming files May 10 20:33:33 smsl-n100 volumio[964]: info: MRS: volumioStreaming STOPPED May 10 20:33:33 smsl-n100 volumio[964]: info: MRS: SNAPSERVER STOPPED May 10 20:33:33 smsl-n100 volumio[964]: info: MRS: SNAPCLIENT STOPPED May 10 20:33:33 smsl-n100 volumio[964]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 10 20:33:33 smsl-n100 volumio[964]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 10 20:33:33 smsl-n100 volumio[964]: info: Reading ALSA contributions from plugins. May 10 20:33:33 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down May 10 20:33:33 smsl-n100 dhcpcd[617]: eth0: carrier lost May 10 20:33:33 smsl-n100 avahi-daemon[540]: Withdrawing address record for 192.168.1.74 on eth0. May 10 20:33:33 smsl-n100 avahi-daemon[540]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.74. May 10 20:33:33 smsl-n100 avahi-daemon[540]: Interface eth0.IPv4 no longer relevant for mDNS. May 10 20:33:33 smsl-n100 dhcpcd[617]: eth0: deleting route to 192.168.1.0/24 May 10 20:33:33 smsl-n100 dhcpcd[617]: eth0: deleting default route via 192.168.1.1 May 10 20:33:33 smsl-n100 volumio-remote-updater[564]: [2025-05-10 20:33:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746902013 101 May 10 20:33:33 smsl-n100 volumio[964]: 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 May 10 20:33:33 smsl-n100 volumio[964]: info: VolumeController:: Volume=undefined Mute =false May 10 20:33:33 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:33 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:33 smsl-n100 volumio[964]: info: CoreStateMachine::updateTrackBlock May 10 20:33:33 smsl-n100 volumio[964]: info: CorePlayQueue::getTrackBlock May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioRetrievevolume May 10 20:33:33 smsl-n100 volumio[964]: info: VolumeController:: Volume=undefined Mute =false May 10 20:33:33 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:33 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:33 smsl-n100 volumio[964]: info: CoreStateMachine::updateTrackBlock May 10 20:33:33 smsl-n100 volumio[964]: info: CorePlayQueue::getTrackBlock May 10 20:33:33 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioRetrievevolume May 10 20:33:33 smsl-n100 volumio[964]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 10 20:33:33 smsl-n100 volumio[964]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 10 20:33:34 smsl-n100 volumio[964]: info: Reloading queue from file May 10 20:33:34 smsl-n100 volumio[964]: info: VolumeController:: Volume=undefined Mute =false May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:34 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::setRepeat false single undefined May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:34 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::setRandom null May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:34 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:34 smsl-n100 volumio[964]: info: Setting Device type: NanoPi NEO 3 May 10 20:33:34 smsl-n100 volumio[964]: info: VolumeController:: Volume=undefined Mute =false May 10 20:33:34 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:34 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:34 smsl-n100 ifplugd(eth0)[740]: Link beat lost. May 10 20:33:34 smsl-n100 volumio[964]: info: Discovery: A device disappeared from network May 10 20:33:34 smsl-n100 volumio[964]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined May 10 20:33:34 smsl-n100 sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 10 20:33:34 smsl-n100 volumio[964]: info: Asound.conf file unchanged, so no further update is needed May 10 20:33:34 smsl-n100 sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 volumio[964]: info: Output device has changed, restarting MPD May 10 20:33:34 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. May 10 20:33:34 smsl-n100 sudo[1415]: pam_unix(sudo:session): session closed for user root May 10 20:33:34 smsl-n100 sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 20:33:34 smsl-n100 sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 volumio[964]: info: Output device has changed, restarting Shairport Sync May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:34 smsl-n100 sudo[1419]: pam_unix(sudo:session): session closed for user root May 10 20:33:34 smsl-n100 sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 20:33:34 smsl-n100 sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 systemd[1]: Stopping Music Player Daemon... May 10 20:33:34 smsl-n100 volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 20:33:34 smsl-n100 volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:34 smsl-n100 volumio[964]: info: ___________ START PLUGINS ___________ May 10 20:33:34 smsl-n100 volumio[964]: info: ControllerMpd::onStart: Initializing MPD May 10 20:33:34 smsl-n100 volumio[964]: info: Creating MPD Configuration file May 10 20:33:34 smsl-n100 sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 20:33:34 smsl-n100 sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 20:33:34 smsl-n100 volumio[964]: info: [1746902014760] CoreMusicLibrary::Adding element Serwery Mediów May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 20:33:34 smsl-n100 sudo[1431]: pam_unix(sudo:session): session closed for user root May 10 20:33:34 smsl-n100 sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 20:33:34 smsl-n100 sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 systemd[1]: mpd.service: Succeeded. May 10 20:33:34 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 10 20:33:34 smsl-n100 systemd[1]: Starting Music Player Daemon... May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 20:33:34 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 10 20:33:34 smsl-n100 systemd[1]: mpd.service: Succeeded. May 10 20:33:34 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 10 20:33:34 smsl-n100 systemd[1]: Starting Music Player Daemon... May 10 20:33:34 smsl-n100 volumio[964]: info: Adding METAVOLUMIO REST API Endpoints May 10 20:33:34 smsl-n100 volumio[964]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 10 20:33:34 smsl-n100 volumio[964]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 10 20:33:34 smsl-n100 volumio[964]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 10 20:33:34 smsl-n100 volumio[964]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 10 20:33:34 smsl-n100 ntpd[1337]: Deleting interface #3 eth0, 192.168.1.74#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:34 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:34 smsl-n100 sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 May 10 20:33:34 smsl-n100 sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:34 smsl-n100 sudo[1439]: pam_unix(sudo:session): session closed for user root May 10 20:33:35 smsl-n100 volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:35 smsl-n100 sudo[1438]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 20:33:35 smsl-n100 sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:35 smsl-n100 sudo[1438]: pam_unix(sudo:session): session closed for user root May 10 20:33:35 smsl-n100 volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 20:33:35 smsl-n100 volumio[964]: info: Preparing CD Folders May 10 20:33:35 smsl-n100 volumio[964]: info: Adding CD REST API Endpoints May 10 20:33:35 smsl-n100 volumio[964]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 10 20:33:35 smsl-n100 volumio[964]: info: Starting UDEV Watcher for CD May 10 20:33:35 smsl-n100 volumio[964]: info: Detecting CD presence with UDEV May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 20:33:35 smsl-n100 volumio[964]: info: [1746902015164] CoreMusicLibrary::Adding element Last_100 May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 20:33:35 smsl-n100 volumio[964]: info: Starting RAAT Plugin May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 10 20:33:35 smsl-n100 volumio[964]: info: Additional UI Settings Added for plugin music_service/raat May 10 20:33:35 smsl-n100 volumio[964]: info: Registering DSP Elements listener and retrieving current ones May 10 20:33:35 smsl-n100 volumio[964]: info: Additional DSP elements updated May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:35 smsl-n100 volumio[964]: info: Updating RAAT Signal Path May 10 20:33:35 smsl-n100 volumio[964]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 10 20:33:35 smsl-n100 volumio[964]: info: Streaming services startup May 10 20:33:35 smsl-n100 volumio[964]: info: Starting Streaming Daemon May 10 20:33:35 smsl-n100 sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 10 20:33:35 smsl-n100 sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:35 smsl-n100 sudo[1452]: pam_unix(sudo:session): session closed for user root May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 20:33:35 smsl-n100 volumio[964]: info: [1746902015433] CoreMusicLibrary::Adding element Webradio May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 20:33:35 smsl-n100 sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 10 20:33:35 smsl-n100 volumio[964]: info: Initializing BBC Radios May 10 20:33:35 smsl-n100 sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:35 smsl-n100 sudo[1459]: pam_unix(sudo:session): session closed for user root May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 10 20:33:35 smsl-n100 volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 10 20:33:35 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:35 smsl-n100 volumio[964]: error: Hi Res Audio Failed Login: Missing Login Data May 10 20:33:35 smsl-n100 volumio[964]: info: Adding HIGHRESAUDIO REST API Endpoints May 10 20:33:35 smsl-n100 volumio[964]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 10 20:33:35 smsl-n100 volumio[964]: info: Initializing Serial Communication on port /dev/ttyS1 May 10 20:33:35 smsl-n100 volumio[964]: info: Touch Event Listener Process Starting May 10 20:33:35 smsl-n100 volumio[964]: info: Adding inputs REST Endpoints May 10 20:33:35 smsl-n100 volumio[964]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 10 20:33:35 smsl-n100 volumio[964]: info: Scanning Audio Inputs May 10 20:33:35 smsl-n100 sudo[1476]: pam_unix(sudo:auth): conversation failed May 10 20:33:35 smsl-n100 sudo[1476]: pam_unix(sudo:auth): auth could not identify password for [volumio] May 10 20:33:35 smsl-n100 volumio[964]: info: Checking against Known Cards name May 10 20:33:35 smsl-n100 volumio[964]: info: Adding Server instance for streaming May 10 20:33:35 smsl-n100 volumio[964]: info: Refreshing TIDAL token May 10 20:33:36 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 20:33:36 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:36 smsl-n100 volumio[964]: info: Initializing GPIOs May 10 20:33:36 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 May 10 20:33:36 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 May 10 20:33:36 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 May 10 20:33:36 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 May 10 20:33:36 smsl-n100 volumio[964]: info: Starting Button Listener May 10 20:33:36 smsl-n100 volumio[964]: info: Turning off Green LED May 10 20:33:36 smsl-n100 volumio[964]: info: Tuning on Blue LED May 10 20:33:36 smsl-n100 volumio[964]: info: Signaling System Ready May 10 20:33:36 smsl-n100 volumio[964]: info: Volumio Calling Home May 10 20:33:36 smsl-n100 volumio[964]: (node:964) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 10 20:33:36 smsl-n100 volumio[964]: (Use `node --trace-deprecation ...` to show where the warning was created) May 10 20:33:36 smsl-n100 volumio[964]: info: Stopping AccessToken refresher cron for QOBUZ May 10 20:33:36 smsl-n100 volumio[964]: info: AccessToken refresher cron started for QOBUZ May 10 20:33:36 smsl-n100 volumio[964]: info: Adding QOBUZ REST API Endpoints May 10 20:33:36 smsl-n100 volumio[964]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 10 20:33:36 smsl-n100 volumio[964]: info: Discovery: adding fb74965d-62a3-4ccc-a848-f15079eef8e5 May 10 20:33:36 smsl-n100 volumio[964]: info: Discovery: Found device SMSL N100 May 10 20:33:36 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioGetState May 10 20:33:36 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:36 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output for this device May 10 20:33:36 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:36 smsl-n100 volumio[964]: info: Adding audio output: May 10 20:33:36 smsl-n100 volumio[964]: info: Adding audio output: May 10 20:33:36 smsl-n100 volumio[964]: info: Serial port opened successfully May 10 20:33:36 smsl-n100 volumio[964]: info: Sending serial start messages May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: Reporting MCU Network Status: 2 May 10 20:33:37 smsl-n100 volumio[964]: info: Touch Event Listener Process Closed May 10 20:33:37 smsl-n100 volumio[964]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 10 20:33:37 smsl-n100 volumio[964]: error: Cannot start Volumio Streaming Daemon May 10 20:33:37 smsl-n100 volumio[964]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 10 20:33:37 smsl-n100 volumio[964]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 10 20:33:37 smsl-n100 volumio[964]: info: RAAT Albumart path created successfully May 10 20:33:37 smsl-n100 volumio[964]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' May 10 20:33:37 smsl-n100 volumio[964]: Can't get device info: No such device May 10 20:33:37 smsl-n100 volumio[964]: info: MPD Permissions set May 10 20:33:37 smsl-n100 volumio[964]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 10 20:33:37 smsl-n100 volumio[964]: info: MPD Permissions set May 10 20:33:37 smsl-n100 volumio[964]: info: Upmpdcli Daemon Started May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 10 20:33:37 smsl-n100 volumio[964]: info: Setting Device Volume Override May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 10 20:33:37 smsl-n100 volumio[964]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 10 20:33:37 smsl-n100 volumio[964]: info: Disabling external Volume Control May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:37 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:37 smsl-n100 volumio[964]: info: Volumio called home May 10 20:33:37 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:37 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:37 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:37 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 2, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 10 20:33:37 smsl-n100 volumio[964]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:37 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:37 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:37 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:37 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:37 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:37 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:37 smsl-n100 volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 10 20:33:37 smsl-n100 ntpd[1337]: Soliciting pool server 212.127.95.218 May 10 20:33:38 smsl-n100 sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 10 20:33:38 smsl-n100 sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:38 smsl-n100 volumio[964]: info: Access Token successfully retrieved May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 194.146.251.114 May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 162.159.200.1 May 10 20:33:38 smsl-n100 systemd[1]: Started Volumio Bluetooth Module. May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 212.127.78.21 May 10 20:33:38 smsl-n100 sudo[1524]: pam_unix(sudo:session): session closed for user root May 10 20:33:38 smsl-n100 sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 10 20:33:38 smsl-n100 sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:38 smsl-n100 volumiobt[1526]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 10 20:33:38 smsl-n100 sudo[1527]: pam_unix(sudo:session): session closed for user root May 10 20:33:38 smsl-n100 volumio[964]: info: VolumeController:: Volume=undefined Mute =false May 10 20:33:38 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:38 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:38 smsl-n100 volumio[964]: info: Volumio BT Module successfully started May 10 20:33:38 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:38 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:38 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:38 smsl-n100 sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 20:33:38 smsl-n100 sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Found cast device: BRAVIA-4K-VH22-44052bf7a6cd3a64289fd6f4541c3f99 May 10 20:33:38 smsl-n100 sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 20:33:38 smsl-n100 sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:38 smsl-n100 volumio[964]: info: Adding audio output: May 10 20:33:38 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:38 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:38 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:38 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:38 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:38 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:38 smsl-n100 sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 20:33:38 smsl-n100 sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:38 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:38 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:38 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:38 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 10 20:33:38 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 20:33:38 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:38 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:38 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 20:33:38 smsl-n100 sudo[1536]: pam_unix(sudo:session): session closed for user root May 10 20:33:38 smsl-n100 sudo[1533]: pam_unix(sudo:session): session closed for user root May 10 20:33:38 smsl-n100 sudo[1531]: pam_unix(sudo:session): session closed for user root May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 156.17.20.254 May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 185.157.13.96 May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 89.161.47.131 May 10 20:33:38 smsl-n100 ntpd[1337]: Soliciting pool server 89.161.47.136 May 10 20:33:38 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioGetState May 10 20:33:38 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:38 smsl-n100 volumio[964]: info: TidalConnect service stoped! May 10 20:33:39 smsl-n100 volumio[964]: info: Preparing to generate the ALSA configuration file May 10 20:33:39 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:39 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:39 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioGetState May 10 20:33:39 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:39 smsl-n100 volumio[964]: info: Shairport-Sync Started May 10 20:33:39 smsl-n100 volumio[964]: Error adding Membership: Error: addMembership EINVAL May 10 20:33:39 smsl-n100 volumio[964]: info: Shairport-Sync Started May 10 20:33:39 smsl-n100 volumio[964]: info: Shairport-Sync Started May 10 20:33:39 smsl-n100 volumio[964]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 10 20:33:39 smsl-n100 volumio[964]: info: Reading ALSA contributions from plugins. May 10 20:33:39 smsl-n100 volumio[964]: info: Successfully retrieved User Session From TIDAL May 10 20:33:39 smsl-n100 sudo[1549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 10 20:33:39 smsl-n100 sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:39 smsl-n100 volumiobt[1526]: pulseaudio: no process found May 10 20:33:39 smsl-n100 sudo[1549]: pam_unix(sudo:session): session closed for user root May 10 20:33:39 smsl-n100 dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.17' (uid=1000 pid=1553 comm="/usr/bin/pulseaudio --high-priority=true ") May 10 20:33:39 smsl-n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 10 20:33:39 smsl-n100 dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 10 20:33:39 smsl-n100 systemd[1]: Started RealtimeKit Scheduling Policy Service. May 10 20:33:39 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Successfully called chroot. May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Successfully dropped privileges. May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Successfully limited resources. May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Running. May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Watchdog thread running. May 10 20:33:39 smsl-n100 rtkit-daemon[1555]: Canary thread running. May 10 20:33:39 smsl-n100 dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.18' (uid=0 pid=1555 comm="/usr/lib/rtkit/rtkit-daemon ") May 10 20:33:39 smsl-n100 systemd[1]: Starting Authorization Manager... May 10 20:33:39 smsl-n100 volumio[964]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:39 smsl-n100 volumio[964]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:39 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:39 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:39 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:39 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:39 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:39 smsl-n100 volumio[964]: error: Help! Some callbacks for volumioPushState are crashing! May 10 20:33:39 smsl-n100 volumio[964]: error: Cannot read property 'sendVolumeMute' of undefined May 10 20:33:39 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:39 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:39 smsl-n100 sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 10 20:33:39 smsl-n100 sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:39 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:39 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:39 smsl-n100 polkitd[1561]: started daemon version 0.105 using authority implementation `local' version `0.105' May 10 20:33:39 smsl-n100 dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 10 20:33:39 smsl-n100 ntpd[1337]: Soliciting pool server 162.159.200.123 May 10 20:33:39 smsl-n100 systemd[1]: Started Authorization Manager. May 10 20:33:39 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:39 smsl-n100 ntpd[1337]: Soliciting pool server 162.159.200.123 May 10 20:33:39 smsl-n100 ntpd[1337]: Soliciting pool server 91.212.242.21 May 10 20:33:39 smsl-n100 ntpd[1337]: Soliciting pool server 94.154.96.7 May 10 20:33:39 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. May 10 20:33:39 smsl-n100 sudo[1563]: pam_unix(sudo:session): session closed for user root May 10 20:33:40 smsl-n100 volumio[964]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 10 20:33:40 smsl-n100 volumio[964]: info: Successfully retrieved User Subscription From TIDAL May 10 20:33:40 smsl-n100 volumio[964]: info: Adding TIDAL to Browse Sources May 10 20:33:40 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 20:33:40 smsl-n100 volumio[964]: info: [1746902020159] CoreMusicLibrary::Adding element TIDAL May 10 20:33:40 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 20:33:40 smsl-n100 volumio[964]: Cannot find translation for source TIDAL May 10 20:33:40 smsl-n100 volumio[964]: info: Stopping AccessToken refresher cron May 10 20:33:40 smsl-n100 volumio[964]: info: AccessToken refresher cron started May 10 20:33:40 smsl-n100 volumio[964]: info: Adding TIDAL REST API Endpoints May 10 20:33:40 smsl-n100 volumio[964]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 10 20:33:40 smsl-n100 volumio[964]: info: Asound.conf file written May 10 20:33:40 smsl-n100 sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 10 20:33:40 smsl-n100 sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:40 smsl-n100 sudo[1572]: pam_unix(sudo:session): session closed for user root May 10 20:33:40 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:40 smsl-n100 volumio[964]: info: Output device has changed, restarting MPD May 10 20:33:40 smsl-n100 mpd[1448]: May 10 20:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 10 20:33:40 smsl-n100 systemd[1]: Started Music Player Daemon. May 10 20:33:40 smsl-n100 sudo[1422]: pam_unix(sudo:session): session closed for user root May 10 20:33:40 smsl-n100 sudo[1433]: pam_unix(sudo:session): session closed for user root May 10 20:33:40 smsl-n100 volumio[964]: info: Output device has changed, restarting Shairport Sync May 10 20:33:40 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:40 smsl-n100 sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 20:33:40 smsl-n100 sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:40 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:40 smsl-n100 sudo[1580]: pam_unix(sudo:session): session closed for user root May 10 20:33:40 smsl-n100 sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 20:33:40 smsl-n100 sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:40 smsl-n100 dbus-daemon[551]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.38' (uid=1000 pid=1553 comm="/usr/bin/pulseaudio --high-priority=true ") May 10 20:33:40 smsl-n100 systemd[1]: Starting Bluetooth service... May 10 20:33:40 smsl-n100 ntpd[1337]: Soliciting pool server 51.68.141.5 May 10 20:33:40 smsl-n100 ntpd[1337]: Soliciting pool server 51.68.141.5 May 10 20:33:40 smsl-n100 ntpd[1337]: Soliciting pool server 109.206.205.233 May 10 20:33:40 smsl-n100 systemd[1]: Stopping Music Player Daemon... May 10 20:33:40 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:40 smsl-n100 volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 20:33:40 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:40 smsl-n100 volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 10 20:33:40 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:40 smsl-n100 pulseaudio[1553]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 10 20:33:40 smsl-n100 pulseaudio[1553]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 10 20:33:40 smsl-n100 systemd[1]: mpd.service: Succeeded. May 10 20:33:41 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 10 20:33:41 smsl-n100 volumiobt[1526]: Applying permissions May 10 20:33:41 smsl-n100 volumiobt[1526]: Setting BT discoverable and pairable May 10 20:33:41 smsl-n100 bluetoothd[1590]: Bluetooth daemon 5.50 May 10 20:33:41 smsl-n100 volumio[964]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 bluetoothd[1590]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 10 20:33:41 smsl-n100 dbus-daemon[551]: [system] Successfully activated service 'org.bluez' May 10 20:33:41 smsl-n100 systemd[1]: Starting Music Player Daemon... May 10 20:33:41 smsl-n100 volumio[964]: 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)"} May 10 20:33:41 smsl-n100 systemd[1]: Started Bluetooth service. May 10 20:33:41 smsl-n100 bluetoothd[1590]: Starting SDP server May 10 20:33:41 smsl-n100 bluetoothd[1590]: Excluding (cli) sap May 10 20:33:41 smsl-n100 volumio[964]: info: Preparing to generate the ALSA configuration file May 10 20:33:41 smsl-n100 bluetoothd[1590]: kernel lacks bnep-protocol support May 10 20:33:41 smsl-n100 bluetoothd[1590]: System does not support network plugin May 10 20:33:41 smsl-n100 bluetoothd[1590]: Bluetooth management interface 1.14 initialized May 10 20:33:41 smsl-n100 dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.39' (uid=0 pid=1590 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 10 20:33:41 smsl-n100 pulseaudio[1553]: 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 May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# -e power on May 10 20:33:41 smsl-n100 volumiobt[1526]: Invalid command in menu main: -e May 10 20:33:41 smsl-n100 volumiobt[1526]: May 10 20:33:41 smsl-n100 volumiobt[1526]: Use "help" for a list of available commands in a menu. May 10 20:33:41 smsl-n100 volumiobt[1526]: Use "menu " if you want to enter any submenu. May 10 20:33:41 smsl-n100 volumiobt[1526]: Use "back" if you want to return to menu main. May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# agent on May 10 20:33:41 smsl-n100 volumiobt[1526]: Failed to register agent object May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# discoverable on May 10 20:33:41 smsl-n100 volumiobt[1526]: No default controller available May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# pairable on May 10 20:33:41 smsl-n100 volumiobt[1526]: No default controller available May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# agent NoInputNoOutput May 10 20:33:41 smsl-n100 volumiobt[1526]: Failed to register agent object May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# default-agent May 10 20:33:41 smsl-n100 volumiobt[1526]: No agent is registered May 10 20:33:41 smsl-n100 volumiobt[1526]: [bluetooth]# quit May 10 20:33:41 smsl-n100 systemd[1]: Starting Hostname Service... May 10 20:33:41 smsl-n100 volumiobt[1526]: [59B blob data] May 10 20:33:41 smsl-n100 volumio[964]: info: MRS: Getting audio outputs on start May 10 20:33:41 smsl-n100 volumio[964]: info: MRS: Requesting all other devices output May 10 20:33:41 smsl-n100 volumio[964]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 10 20:33:41 smsl-n100 volumio[964]: info: Reading ALSA contributions from plugins. May 10 20:33:41 smsl-n100 sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 10 20:33:41 smsl-n100 sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:41 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... May 10 20:33:41 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 10 20:33:41 smsl-n100 systemd[1]: vtcs.service: Succeeded. May 10 20:33:41 smsl-n100 sudo[1595]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 20:33:41 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. May 10 20:33:41 smsl-n100 sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:41 smsl-n100 sudo[1595]: pam_unix(sudo:session): session closed for user root May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:41 smsl-n100 sudo[1600]: pam_unix(sudo:session): session closed for user root May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:41 smsl-n100 dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.hostname1' May 10 20:33:41 smsl-n100 systemd[1]: Started Hostname Service. May 10 20:33:41 smsl-n100 volumio[964]: Upnp client error: Error: This socket has been ended by the other party May 10 20:33:41 smsl-n100 volumio[964]: Upnp client error: Error: This socket has been ended by the other party May 10 20:33:41 smsl-n100 volumio[964]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 10 20:33:41 smsl-n100 volumio[964]: info: MPD Permissions set May 10 20:33:41 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx May 10 20:33:41 smsl-n100 dhcpcd[617]: eth0: carrier acquired May 10 20:33:41 smsl-n100 dhcpcd[617]: eth0: IAID 79:a6:a5:22 May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 20:33:41 smsl-n100 volumio[964]: info: Not Reporting Auto name since its the default one May 10 20:33:41 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:42 smsl-n100 volumio[964]: info: Executing endpoint tc_getconfig May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 10 20:33:42 smsl-n100 volumio[964]: info: MPD running with PID1603 May 10 20:33:42 smsl-n100 volumio[964]: ,establishing connection May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 20:33:42 smsl-n100 dhcpcd[617]: eth0: rebinding lease of 192.168.1.74 May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 dhcpcd[617]: eth0: probing address 192.168.1.74/24 May 10 20:33:42 smsl-n100 ifplugd(eth0)[740]: Link beat detected. May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 10 20:33:42 smsl-n100 dhcpcd[617]: eth0: soliciting an IPv6 router May 10 20:33:42 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:42 smsl-n100 volumio[964]: info: Configured Audio card not found, not starting RAAT May 10 20:33:42 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:42 smsl-n100 volumio[964]: info: Asound.conf file unchanged, so no further update is needed May 10 20:33:42 smsl-n100 volumio[964]: info: Output device has changed, restarting MPD May 10 20:33:42 smsl-n100 sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 20:33:42 smsl-n100 sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:42 smsl-n100 sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 20:33:42 smsl-n100 sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:42 smsl-n100 volumio[964]: info: Output device has changed, restarting Shairport Sync May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:42 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:42 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 10 20:33:42 smsl-n100 sudo[1623]: pam_unix(sudo:session): session closed for user root May 10 20:33:42 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 20:33:42 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 20:33:42 smsl-n100 sudo[1621]: pam_unix(sudo:session): session closed for user root May 10 20:33:42 smsl-n100 sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 20:33:42 smsl-n100 sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:42 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 10 20:33:42 smsl-n100 systemd[1]: mpd.service: Succeeded. May 10 20:33:42 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 10 20:33:42 smsl-n100 systemd[1]: Starting Music Player Daemon... May 10 20:33:42 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:42 smsl-n100 volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 20:33:42 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:42 smsl-n100 volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 10 20:33:42 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: 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)"} May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:43 smsl-n100 sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 10 20:33:43 smsl-n100 sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:43 smsl-n100 sudo[1634]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 20:33:43 smsl-n100 sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:43 smsl-n100 volumio[964]: info: MPD Permissions set May 10 20:33:43 smsl-n100 volumio[964]: info: TidalConnect service started! May 10 20:33:43 smsl-n100 volumio[964]: info: Completed starting Core Plugins May 10 20:33:43 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:33:43 smsl-n100 volumio[964]: info: ----- MyVolumio plugins startup ---- May 10 20:33:43 smsl-n100 volumio[964]: info: ------------------------------------------- May 10 20:33:43 smsl-n100 volumio[964]: info: [MyVolumio PluginManager] Fetching plans data.... May 10 20:33:43 smsl-n100 sudo[1634]: pam_unix(sudo:session): session closed for user root May 10 20:33:43 smsl-n100 volumio[964]: info: Shairport-Sync Started May 10 20:33:43 smsl-n100 volumio[964]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 10 20:33:43 smsl-n100 sudo[1639]: pam_unix(sudo:session): session closed for user root May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: Starting Shairport Sync May 10 20:33:43 smsl-n100 sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 20:33:43 smsl-n100 sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:43 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 20:33:43 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 10 20:33:43 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:43 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:43 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 20:33:43 smsl-n100 sudo[1651]: pam_unix(sudo:session): session closed for user root May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 20:33:43 smsl-n100 volumio[964]: info: Not Reporting Auto name since its the default one May 10 20:33:43 smsl-n100 volumio[964]: info: Shairport-Sync Started May 10 20:33:43 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 10 20:33:43 smsl-n100 volumio[964]: xcb_connection_has_error() returned true May 10 20:33:43 smsl-n100 volumio[964]: info: Configured Audio card not found, not starting RAAT May 10 20:33:44 smsl-n100 volumio[964]: info: TidalConnect service stoped! May 10 20:33:44 smsl-n100 volumio[964]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:44 smsl-n100 volumio[964]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:44 smsl-n100 sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 10 20:33:44 smsl-n100 sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:44 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. May 10 20:33:44 smsl-n100 sudo[1685]: pam_unix(sudo:session): session closed for user root May 10 20:33:45 smsl-n100 volumio[964]: info: Executing endpoint tc_getconfig May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 10 20:33:45 smsl-n100 vtcs[1687]: STARTING TidalConnect services, version: 1.4.0.34 May 10 20:33:45 smsl-n100 vtcs[1687]: STARTED TidalConnect services. May 10 20:33:45 smsl-n100 volumio[964]: info: RAAT: Requesting Headphone Status May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 10 20:33:45 smsl-n100 volumio[964]: info: Executing endpoint tc_connect May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 10 20:33:45 smsl-n100 volumio[964]: info: Connecting to TidalConnect May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::servicePushState May 10 20:33:45 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:45 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:45 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:45 smsl-n100 volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::servicePushState May 10 20:33:45 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:45 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:45 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:45 smsl-n100 volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 10 20:33:45 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:45 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:45 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:45 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:45 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:45 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:45 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:45 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:46 smsl-n100 volumio[964]: info: TidalConnect service stoped! May 10 20:33:46 smsl-n100 volumio[964]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:46 smsl-n100 volumio[964]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 10 20:33:46 smsl-n100 sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 10 20:33:46 smsl-n100 sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 20:33:46 smsl-n100 sudo[1716]: pam_unix(sudo:session): session closed for user root May 10 20:33:46 smsl-n100 volumio[964]: info: RAAT: Requesting Headphone Status May 10 20:33:46 smsl-n100 volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 10 20:33:46 smsl-n100 volumio[964]: info: CoreStateMachine::pushState May 10 20:33:46 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:46 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioPushState May 10 20:33:46 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output update for this device May 10 20:33:46 smsl-n100 volumio[964]: info: MRS: Pushing multiroomSync output May 10 20:33:46 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:46 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: May 10 20:33:46 smsl-n100 volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined May 10 20:33:46 smsl-n100 volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 10 20:33:46 smsl-n100 mpd[1642]: May 10 20:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 10 20:33:46 smsl-n100 systemd[1]: Started Music Player Daemon. May 10 20:33:46 smsl-n100 sudo[1627]: pam_unix(sudo:session): session closed for user root May 10 20:33:46 smsl-n100 sudo[1584]: pam_unix(sudo:session): session closed for user root May 10 20:33:46 smsl-n100 volumio[964]: error: MPD error: The expression evaluated to a falsy value: May 10 20:33:46 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:46 smsl-n100 volumio[964]: error: The expression evaluated to a falsy value: May 10 20:33:46 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:46 smsl-n100 volumio[964]: Upnp client error: Error: This socket has been ended by the other party May 10 20:33:46 smsl-n100 volumio[964]: error: MPD error: The expression evaluated to a falsy value: May 10 20:33:46 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:46 smsl-n100 volumio[964]: error: The expression evaluated to a falsy value: May 10 20:33:46 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:47 smsl-n100 volumio[964]: error: updateQueue error: null May 10 20:33:47 smsl-n100 volumio[964]: error: MPD error: The expression evaluated to a falsy value: May 10 20:33:47 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:47 smsl-n100 volumio[964]: error: The expression evaluated to a falsy value: May 10 20:33:47 smsl-n100 volumio[964]: assert.ok(self.idling) May 10 20:33:47 smsl-n100 volumio[964]: error: updateQueue error: null May 10 20:33:47 smsl-n100 dhcpcd[617]: eth0: leased 192.168.1.74 for 86400 seconds May 10 20:33:47 smsl-n100 avahi-daemon[540]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.74. May 10 20:33:47 smsl-n100 avahi-daemon[540]: New relevant interface eth0.IPv4 for mDNS. May 10 20:33:47 smsl-n100 dhcpcd[617]: eth0: adding route to 192.168.1.0/24 May 10 20:33:47 smsl-n100 dhcpcd[617]: eth0: adding default route via 192.168.1.1 May 10 20:33:47 smsl-n100 avahi-daemon[540]: Registering new address record for 192.168.1.74 on eth0.IPv4. May 10 20:33:47 smsl-n100 ntpd[1337]: ntpd exiting on signal 15 (Terminated) May 10 20:33:47 smsl-n100 ntpd[1337]: 212.127.95.218 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 systemd[1]: Stopping Network Time Service... May 10 20:33:47 smsl-n100 ntpd[1337]: 194.146.251.114 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 162.159.200.1 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 212.127.78.21 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 185.157.13.96 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 156.17.20.254 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 89.161.47.136 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 systemd[1]: ntp.service: Succeeded. May 10 20:33:47 smsl-n100 ntpd[1337]: 89.161.47.131 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 162.159.200.123 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 94.154.96.7 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 91.212.242.21 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 ntpd[1337]: 51.68.141.5 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 systemd[1]: Stopped Network Time Service. May 10 20:33:47 smsl-n100 ntpd[1337]: 109.206.205.233 local addr 192.168.0.166 -> May 10 20:33:47 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:47 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 20:33:47 smsl-n100 systemd[1]: Starting Network Time Service... May 10 20:33:47 smsl-n100 volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 20:33:47 smsl-n100 ntpd[1755]: ntpd 4.2.8p12@1.3728-o (1): Starting May 10 20:33:47 smsl-n100 ntpd[1755]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 10 20:33:47 smsl-n100 systemd[1]: Started Network Time Service. May 10 20:33:47 smsl-n100 ntpd[1761]: proto: precision = 1.166 usec (-20) May 10 20:33:47 smsl-n100 ntpd[1761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 10 20:33:47 smsl-n100 ntpd[1761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 10 20:33:47 smsl-n100 ntpd[1761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 865 days ago May 10 20:33:47 smsl-n100 ntpd[1761]: Listen and drop on 0 v6wildcard [::]:123 May 10 20:33:47 smsl-n100 ntpd[1761]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 10 20:33:47 smsl-n100 ntpd[1761]: Listen normally on 2 lo 127.0.0.1:123 May 10 20:33:47 smsl-n100 ntpd[1761]: Listen normally on 3 eth0 192.168.1.74:123 May 10 20:33:47 smsl-n100 ntpd[1761]: Listen normally on 4 wlan0 192.168.0.166:123 May 10 20:33:47 smsl-n100 ntpd[1761]: Listening on routing socket on fd #21 for interface updates May 10 20:33:47 smsl-n100 ntpd[1761]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 10 20:33:47 smsl-n100 ntpd[1761]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 10 20:33:47 smsl-n100 volumio[964]: info: TidalConnect service started! May 10 20:33:48 smsl-n100 volumio[964]: info: Discovery: this is already registered, fb74965d-62a3-4ccc-a848-f15079eef8e5 May 10 20:33:48 smsl-n100 volumio[964]: info: Discovery: Found device SMSL N100 May 10 20:33:48 smsl-n100 volumio[964]: info: CoreCommandRouter::volumioGetState May 10 20:33:48 smsl-n100 volumio[964]: info: CorePlayQueue::getTrack 0 May 10 20:33:48 smsl-n100 volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 20:33:48 smsl-n100 volumio[964]: Error: The value NaN is not a number May 10 20:33:48 smsl-n100 volumio[964]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 10 20:33:48 smsl-n100 volumio[964]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 10 20:33:48 smsl-n100 volumio[964]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 10 20:33:48 smsl-n100 volumio[964]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 10 20:33:48 smsl-n100 volumio[964]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 10 20:33:48 smsl-n100 volumio[964]: at Browser.emit (events.js:400:28) May 10 20:33:48 smsl-n100 volumio[964]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 10 20:33:48 smsl-n100 volumio[964]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 10 20:33:48 smsl-n100 volumio[964]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 10 20:33:48 smsl-n100 volumio[964]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 10 20:33:48 smsl-n100 volumio[964]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 10 20:33:48 smsl-n100 volumio[964]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 10 20:33:48 smsl-n100 volumio[964]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 10 20:33:48 smsl-n100 volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 20:33:48 smsl-n100 ntpd[1761]: Soliciting pool server 194.146.251.102 May 10 20:33:49 smsl-n100 sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 20:32 May 10 20:33:49 smsl-n100 sudo[1772]: 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"