-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Mon 2026-01-26 20:02:23 EST. -- Jan 26 20:01:41 smsl-n100 systemd-timedated[861]: Changed local time to Mon Jan 26 20:01:41 2026 Jan 26 20:01:41 smsl-n100 sudo[855]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:41 smsl-n100 volumio-time-update[563]: volumio-time-update-util: System time updated successfully. Jan 26 20:01:41 smsl-n100 systemd[1]: Started Volumio Time Update Utility. Jan 26 20:01:41 smsl-n100 systemd[1]: Starting Daily apt download activities... Jan 26 20:01:41 smsl-n100 ifplugd(eth0)[747]: client: ifup: interface eth0 already configured Jan 26 20:01:41 smsl-n100 sh[466]: eth0=eth0 Jan 26 20:01:41 smsl-n100 ntpd[882]: ntpd exiting on signal 15 (Terminated) Jan 26 20:01:41 smsl-n100 systemd[1]: Stopping Network Time Service... Jan 26 20:01:41 smsl-n100 systemd[1]: ntp.service: Succeeded. Jan 26 20:01:41 smsl-n100 ifplugd(eth0)[747]: Program executed successfully. Jan 26 20:01:41 smsl-n100 systemd[1]: Stopped Network Time Service. Jan 26 20:01:41 smsl-n100 systemd[1]: Starting Network Time Service... Jan 26 20:01:41 smsl-n100 ntpd[929]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 26 20:01:41 smsl-n100 ntpd[929]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 26 20:01:41 smsl-n100 systemd[1]: Started Network Time Service. Jan 26 20:01:41 smsl-n100 ntpd[936]: proto: precision = 1.166 usec (-20) Jan 26 20:01:41 smsl-n100 ntpd[936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 26 20:01:41 smsl-n100 ntpd[936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 26 20:01:41 smsl-n100 ntpd[936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1127 days ago Jan 26 20:01:41 smsl-n100 dhcpcd[561]: forked to background, child pid 938 Jan 26 20:01:41 smsl-n100 dhcpcd[561]: forked to background, child pid 938 Jan 26 20:01:41 smsl-n100 ntpd[936]: Listen and drop on 0 v6wildcard [::]:123 Jan 26 20:01:41 smsl-n100 ntpd[936]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 26 20:01:41 smsl-n100 ntpd[936]: Listen normally on 2 lo 127.0.0.1:123 Jan 26 20:01:41 smsl-n100 ntpd[936]: Listen normally on 3 eth0 10.113.10.55:123 Jan 26 20:01:41 smsl-n100 ntpd[936]: Listening on routing socket on fd #20 for interface updates Jan 26 20:01:41 smsl-n100 systemd[1]: Started DHCP Client Daemon. Jan 26 20:01:41 smsl-n100 ntpd[936]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jan 26 20:01:41 smsl-n100 ntpd[936]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jan 26 20:01:42 smsl-n100 ntpd[936]: Soliciting pool server 23.186.168.127 Jan 26 20:01:42 smsl-n100 nmbd[716]: [2026/01/26 20:01:42.674103, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:01:42 smsl-n100 systemd[1]: Started Samba NMB Daemon. Jan 26 20:01:42 smsl-n100 nmbd[716]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 26 20:01:42 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... Jan 26 20:01:42 smsl-n100 nmbd[716]: [2026/01/26 20:01:42.740804, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 26 20:01:42 smsl-n100 nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 10.113.10.55 for name WORKGROUP<1d>. Jan 26 20:01:42 smsl-n100 nmbd[716]: This response was from IP 10.113.10.22, reporting an IP address of 10.113.10.22. Jan 26 20:01:43 smsl-n100 volumio-remote-updater[537]: [2026-01-26 20:01:43] [connect] Successful connection Jan 26 20:01:43 smsl-n100 ntpd[936]: Soliciting pool server 23.150.41.123 Jan 26 20:01:43 smsl-n100 winbindd[957]: [2026/01/26 20:01:43.380202, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 26 20:01:43 smsl-n100 winbindd[957]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 26 20:01:43 smsl-n100 winbindd[957]: [2026/01/26 20:01:43.411507, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:01:43 smsl-n100 systemd[1]: Started Samba Winbind Daemon. Jan 26 20:01:43 smsl-n100 winbindd[957]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 26 20:01:43 smsl-n100 systemd[1]: Starting Samba SMB Daemon... Jan 26 20:01:43 smsl-n100 ntpd[936]: Soliciting pool server 149.248.12.167 Jan 26 20:01:43 smsl-n100 volumio[779]: info: Loading plugin "network"... Jan 26 20:01:43 smsl-n100 volumio[779]: info: Refreshing Cached IP Addresses Jan 26 20:01:43 smsl-n100 sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:01:43 smsl-n100 volumio[779]: info: Loading plugin "services"... Jan 26 20:01:43 smsl-n100 sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:43 smsl-n100 volumio[779]: info: Loading plugin "alsa_controller"... Jan 26 20:01:43 smsl-n100 sudo[964]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:43 smsl-n100 sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:01:43 smsl-n100 sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:44 smsl-n100 sudo[966]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:44 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:01:44 smsl-n100 volumio[779]: info: Loading plugin "wizard"... Jan 26 20:01:44 smsl-n100 volumio[779]: info: Loading plugin "networkfs"... Jan 26 20:01:44 smsl-n100 ntpd[936]: Soliciting pool server 158.51.99.19 Jan 26 20:01:44 smsl-n100 ntpd[936]: Soliciting pool server 162.244.81.139 Jan 26 20:01:44 smsl-n100 sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ch_admin,password=jhsno94G!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.113.10.20/Music\CD Rip /mnt/NAS/CD Jan 26 20:01:44 smsl-n100 sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:44 smsl-n100 ntpd[936]: Soliciting pool server 23.157.160.168 Jan 26 20:01:44 smsl-n100 sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ch_admin,password=jhsno94G!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.113.10.20/Music\HD_Files /mnt/NAS/HD Jan 26 20:01:44 smsl-n100 sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:44 smsl-n100 sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ch_admin,password=jhsno94G!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.113.10.20/Music\DSD Rip /mnt/NAS/DSD Jan 26 20:01:44 smsl-n100 volumio[779]: info: Starting Udev Watcher for removable devices Jan 26 20:01:44 smsl-n100 sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:44 smsl-n100 volumio[779]: info: Ignoring mount for partition: boot Jan 26 20:01:44 smsl-n100 volumio[779]: info: Ignoring mount for partition: volumio Jan 26 20:01:44 smsl-n100 volumio[779]: info: Ignoring mount for partition: volumio_data Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: Loaded Jan 26 20:01:44 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 20:01:44 smsl-n100 volumio[779]: info: Loading plugin "volumio_command_line_client"... Jan 26 20:01:44 smsl-n100 volumio[779]: info: Plugin upnp is not enabled Jan 26 20:01:44 smsl-n100 sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ch_admin,password=jhsno94G!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.113.10.20/Music\CD Collections /mnt/NAS/Collections Jan 26 20:01:44 smsl-n100 volumio[779]: info: Loading plugin "my_music"... Jan 26 20:01:44 smsl-n100 sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:01:44 smsl-n100 volumio[779]: info: Loading plugin "mpd"... Jan 26 20:01:44 smsl-n100 systemd[1]: Started Samba SMB Daemon. Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 26 20:01:44 smsl-n100 kernel: Key type cifs.spnego registered Jan 26 20:01:44 smsl-n100 kernel: Key type cifs.idmap registered Jan 26 20:01:44 smsl-n100 systemd[1]: Reached target Multi-User System. Jan 26 20:01:44 smsl-n100 systemd[1]: Reached target Graphical Interface. Jan 26 20:01:44 smsl-n100 kernel: CIFS: Attempting to mount //10.113.10.20/Music\DSD Rip Jan 26 20:01:44 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. Jan 26 20:01:44 smsl-n100 smbd[962]: [2026/01/26 20:01:44.798850, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:01:44 smsl-n100 smbd[962]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 26 20:01:44 smsl-n100 kernel: CIFS: Attempting to mount //10.113.10.20/Music\HD_Files Jan 26 20:01:44 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. Jan 26 20:01:44 smsl-n100 kernel: CIFS: Attempting to mount //10.113.10.20/Music\CD Rip Jan 26 20:01:44 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. Jan 26 20:01:44 smsl-n100 kernel: CIFS: Attempting to mount //10.113.10.20/Music\CD Collections Jan 26 20:01:44 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. Jan 26 20:01:44 smsl-n100 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: Duplicate cookie detected Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie c=00000000be34e19b [p=0000000027bee29f fl=222 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie d=00000000cf10f5cc n=00000000d3a54a6b Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie c=0000000064edf308 [p=0000000027bee29f fl=2 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie d=00000000cf10f5cc n=0000000089bd33a7 Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: Duplicate cookie detected Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie c=00000000be34e19b [p=0000000027bee29f fl=222 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie d=00000000cf10f5cc n=00000000d3a54a6b Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie c=00000000f414cc0a [p=0000000027bee29f fl=2 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie d=00000000cf10f5cc n=00000000fad6b57c Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: Duplicate cookie detected Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie c=00000000be34e19b [p=0000000027bee29f fl=222 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-cookie d=00000000cf10f5cc n=00000000d3a54a6b Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: O-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie c=00000000e23d40ca [p=0000000027bee29f fl=2 nc=0 na=1] Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-cookie d=00000000cf10f5cc n=000000001015d220 Jan 26 20:01:44 smsl-n100 kernel: FS-Cache: N-key=[8] '020001bd0a710a14' Jan 26 20:01:44 smsl-n100 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 20:01:44 smsl-n100 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 26 20:01:45 smsl-n100 sudo[988]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:45 smsl-n100 sudo[993]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:45 smsl-n100 sudo[995]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:45 smsl-n100 sudo[1004]: pam_unix(sudo:session): session closed for user root Jan 26 20:01:45 smsl-n100 ntpd[936]: Soliciting pool server 44.190.5.123 Jan 26 20:01:45 smsl-n100 ntpd[936]: Soliciting pool server 138.68.201.49 Jan 26 20:01:45 smsl-n100 ntpd[936]: Soliciting pool server 171.66.97.126 Jan 26 20:01:45 smsl-n100 ntpd[936]: Soliciting pool server 23.150.41.122 Jan 26 20:01:45 smsl-n100 systemd[1]: apt-daily.service: Succeeded. Jan 26 20:01:45 smsl-n100 systemd[1]: Started Daily apt download activities. Jan 26 20:01:45 smsl-n100 systemd[1]: Starting Daily apt upgrade and clean activities... Jan 26 20:01:45 smsl-n100 volumio[779]: info: Plugin upnp_browser is not enabled Jan 26 20:01:45 smsl-n100 volumio[779]: info: Plugin bluetooth is not enabled Jan 26 20:01:45 smsl-n100 volumio[779]: info: Loading plugin "alarm-clock"... Jan 26 20:01:46 smsl-n100 volumio[779]: info: Loading plugin "manifestui"... Jan 26 20:01:46 smsl-n100 ntpd[936]: Soliciting pool server 23.186.168.129 Jan 26 20:01:46 smsl-n100 ntpd[936]: Soliciting pool server 65.182.224.60 Jan 26 20:01:46 smsl-n100 ntpd[936]: Soliciting pool server 185.234.20.134 Jan 26 20:01:46 smsl-n100 volumio[779]: info: Loading plugin "metavolumio"... Jan 26 20:01:47 smsl-n100 ntpd[936]: Soliciting pool server 72.14.186.59 Jan 26 20:01:47 smsl-n100 ntpd[936]: Soliciting pool server 172.104.28.175 Jan 26 20:01:48 smsl-n100 ntpd[936]: Soliciting pool server 144.202.62.209 Jan 26 20:01:48 smsl-n100 ntpd[936]: Soliciting pool server 2600:3c01::f03c:91ff:fe55:215c Jan 26 20:01:48 smsl-n100 systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 26 20:01:48 smsl-n100 systemd[1]: Started Daily apt upgrade and clean activities. Jan 26 20:01:48 smsl-n100 systemd[1]: Startup finished in 6.053s (kernel) + 23.426s (userspace) = 29.480s. Jan 26 20:01:49 smsl-n100 ntpd[936]: Soliciting pool server 23.186.168.123 Jan 26 20:01:49 smsl-n100 volumio[779]: info: Loading plugin "airplay_emulation"... Jan 26 20:01:49 smsl-n100 volumio[779]: info: Starting Shairport Sync Jan 26 20:01:49 smsl-n100 volumio[779]: info: Plugin cd_controller is not enabled Jan 26 20:01:49 smsl-n100 volumio[779]: info: Loading plugin "last_100"... Jan 26 20:01:49 smsl-n100 volumio[779]: info: Loading plugin "raat"... Jan 26 20:01:50 smsl-n100 volumio[779]: info: RAAT Plugin loaded Jan 26 20:01:50 smsl-n100 volumio[779]: info: Adding restartRAATSocket REST API Endpoint Jan 26 20:01:50 smsl-n100 volumio[779]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jan 26 20:01:50 smsl-n100 volumio[779]: info: Loading plugin "streaming_services"... Jan 26 20:01:51 smsl-n100 ntpd[936]: receive: Unexpected origin timestamp 0xed228cfe.59e021e7 does not match aorg 0000000000.00000000 from server@44.190.5.123 xmt 0xed228cff.753b4a0a Jan 26 20:01:51 smsl-n100 ntpd[936]: receive: Unexpected origin timestamp 0xed228cfe.59d864f1 does not match aorg 0000000000.00000000 from server@65.182.224.60 xmt 0xed228cff.76e844f0 Jan 26 20:01:51 smsl-n100 ntpd[936]: receive: Unexpected origin timestamp 0xed228cfe.59d34b28 does not match aorg 0000000000.00000000 from server@23.186.168.129 xmt 0xed228cff.760c2ca0 Jan 26 20:01:53 smsl-n100 volumio[779]: info: Starting Streaming Service Transparent Proxy Jan 26 20:01:53 smsl-n100 volumio[779]: info: Loading plugin "tidalconnect"... Jan 26 20:01:53 smsl-n100 volumio[779]: info: Loading plugin "webradio"... Jan 26 20:01:54 smsl-n100 volumio[779]: info: Loading plugin "i2s_dacs"... Jan 26 20:01:54 smsl-n100 volumio[779]: info: I2S DAC not set, start Auto-detection Jan 26 20:01:54 smsl-n100 volumio[779]: info: Loading plugin "volumiodiscovery"... Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** For more information see Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:01:54 smsl-n100 volumio[779]: *** WARNING *** For more information see Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** For more information see Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:01:54 smsl-n100 node[779]: *** WARNING *** For more information see Jan 26 20:01:54 smsl-n100 volumio[779]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 20:01:54 smsl-n100 volumio[779]: info: Discovery: Started advertising with name: SMSL N100 Jan 26 20:01:54 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 20:01:54 smsl-n100 volumio[779]: info: Loading plugin "calmradio"... Jan 26 20:01:55 smsl-n100 volumio[779]: STREAMING PROXY: Starting server on port 3245 Jan 26 20:01:55 smsl-n100 volumio[779]: Node JS runtime: 14 Jan 26 20:01:56 smsl-n100 volumio[779]: info: Plugin multiroom is not enabled Jan 26 20:01:56 smsl-n100 volumio[779]: info: Loading plugin "outputs"... Jan 26 20:01:56 smsl-n100 volumio[779]: info: Loading plugin "albumart"... Jan 26 20:01:56 smsl-n100 volumio[779]: info: Plugin example_plugin is not enabled Jan 26 20:01:56 smsl-n100 volumio[779]: info: Loading plugin "hi_res_audio"... Jan 26 20:01:57 smsl-n100 volumio[779]: Forking 3 albumart workers Jan 26 20:01:59 smsl-n100 volumio-remote-updater[537]: [2026-01-26 20:01:59] [connect] Successful connection Jan 26 20:01:59 smsl-n100 volumio[779]: Starting albumart workers Jan 26 20:01:59 smsl-n100 systemd[1]: systemd-fsckd.service: Succeeded. Jan 26 20:01:59 smsl-n100 volumio[779]: Starting albumart workers Jan 26 20:01:59 smsl-n100 volumio[779]: Starting albumart workers Jan 26 20:02:00 smsl-n100 volumio[779]: info: Applying required configuration parameters for plugin hi_res_audio Jan 26 20:02:00 smsl-n100 volumio[779]: info: Loading plugin "inputs"... Jan 26 20:02:02 smsl-n100 volumio[779]: info: Loading plugin "qobuz"... Jan 26 20:02:04 smsl-n100 volumio[779]: info: Plugin smart_inputs is not enabled Jan 26 20:02:04 smsl-n100 volumio[779]: info: Loading plugin "tidal"... Jan 26 20:02:07 smsl-n100 volumio[779]: info: Loading plugin "oem_helper"... Jan 26 20:02:09 smsl-n100 volumio[779]: info: Applying required configuration parameters for plugin oem_helper Jan 26 20:02:09 smsl-n100 volumio[779]: info: Loading plugin "smslcontrol"... Jan 26 20:02:09 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 Jan 26 20:02:09 smsl-n100 volumio[779]: info: Adding this device properties Jan 26 20:02:09 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jan 26 20:02:09 smsl-n100 volumio[779]: info: Setting Additional Device Volumio Properties: [object Object] Jan 26 20:02:09 smsl-n100 volumio[779]: info: Loading plugin "updater_comm"... Jan 26 20:02:09 smsl-n100 volumio[779]: info: Plugin mpdemulation is not enabled Jan 26 20:02:09 smsl-n100 volumio[779]: info: Loading plugin "rest_api"... Jan 26 20:02:09 smsl-n100 volumio[779]: info: Loading plugin "websocket"... Jan 26 20:02:09 smsl-n100 volumio[779]: info: Starting Socket.io Server version 2.3.0 Jan 26 20:02:09 smsl-n100 volumio[779]: info: Loading plugin "RoonBridge"... Jan 26 20:02:10 smsl-n100 volumio[779]: info: Applying required configuration parameters for plugin RoonBridge Jan 26 20:02:10 smsl-n100 volumio[779]: info: Loading i18n strings for locale en Jan 26 20:02:10 smsl-n100 volumio[779]: Updating browse sources language Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::initPlayerControls Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: Express server listening on port 3000 Jan 26 20:02:10 smsl-n100 volumio[779]: [Metrics] WebUI: 33s 255.14ms Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreStateMachine::resetVolumioState Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreStateMachine::getcurrentVolume Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:10 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:10 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:10 smsl-n100 volumio[779]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 26 20:02:10 smsl-n100 volumio[779]: info: Completed loading Core Plugins Jan 26 20:02:10 smsl-n100 volumio[779]: info: Preparing to generate the ALSA configuration file Jan 26 20:02:11 smsl-n100 volumio[779]: info: Volumio Network Manager: Network status updated: 1 Jan 26 20:02:11 smsl-n100 volumio[779]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 26 20:02:11 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreStateMachine::setRepeat false single undefined Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:11 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreStateMachine::setRandom false Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:11 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:11 smsl-n100 volumio[779]: info: Setting Device type: NanoPi NEO 3 Jan 26 20:02:11 smsl-n100 volumio[779]: info: Asound.conf file unchanged, so no further update is needed Jan 26 20:02:11 smsl-n100 volumio[779]: info: Output device has changed, restarting MPD Jan 26 20:02:11 smsl-n100 sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 20:02:11 smsl-n100 sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:11 smsl-n100 volumio[779]: info: Output device has changed, restarting Shairport Sync Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:11 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:11 smsl-n100 sudo[1252]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:11 smsl-n100 sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 20:02:11 smsl-n100 sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:11 smsl-n100 systemd[1]: Listening on mpd.socket. Jan 26 20:02:11 smsl-n100 systemd[1]: Starting Music Player Daemon... Jan 26 20:02:11 smsl-n100 volumio[779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:02:11 smsl-n100 volumio[779]: info: ___________ START PLUGINS ___________ Jan 26 20:02:11 smsl-n100 volumio[779]: info: ControllerMpd::onStart: Initializing MPD Jan 26 20:02:11 smsl-n100 volumio[779]: info: Creating MPD Configuration file Jan 26 20:02:12 smsl-n100 sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 20:02:12 smsl-n100 sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:12 smsl-n100 sudo[1262]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding METAVOLUMIO REST API Endpoints Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:12 smsl-n100 sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 20:02:12 smsl-n100 sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:12 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. Jan 26 20:02:12 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 26 20:02:12 smsl-n100 systemd[1]: mpd.service: Succeeded. Jan 26 20:02:12 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jan 26 20:02:12 smsl-n100 systemd[1]: Starting Music Player Daemon... Jan 26 20:02:12 smsl-n100 volumio[779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:02:12 smsl-n100 volumio[779]: info: [1769475732289] CoreMusicLibrary::Adding element Last_100 Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:12 smsl-n100 volumio[779]: info: Starting RAAT Plugin Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 26 20:02:12 smsl-n100 volumio[779]: info: Additional UI Settings Added for plugin music_service/raat Jan 26 20:02:12 smsl-n100 volumio[779]: info: Registering DSP Elements listener and retrieving current ones Jan 26 20:02:12 smsl-n100 volumio[779]: info: Additional DSP elements updated Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: Updating RAAT Signal Path Jan 26 20:02:12 smsl-n100 volumio[779]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 26 20:02:12 smsl-n100 volumio[779]: info: Streaming services startup Jan 26 20:02:12 smsl-n100 sudo[1272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 20:02:12 smsl-n100 volumio[779]: info: Starting Streaming Daemon Jan 26 20:02:12 smsl-n100 sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:12 smsl-n100 sudo[1272]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 20:02:12 smsl-n100 sudo[1272]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:12 smsl-n100 sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 20:02:12 smsl-n100 sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:12 smsl-n100 sudo[1281]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:02:12 smsl-n100 volumio[779]: info: [1769475732543] CoreMusicLibrary::Adding element Webradio Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:02:12 smsl-n100 volumio[779]: info: Initializing BBC Radios Jan 26 20:02:12 smsl-n100 sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 20:02:12 smsl-n100 sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:12 smsl-n100 sudo[1288]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding Calm Radio to Browse Sources Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:02:12 smsl-n100 volumio[779]: info: [1769475732753] CoreMusicLibrary::Adding element Calm Radio Jan 26 20:02:12 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:12 smsl-n100 volumio[779]: Cannot find translation for source Calm Radio Jan 26 20:02:12 smsl-n100 volumio[779]: error: Hi Res Audio Failed Login: Missing Login Data Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 20:02:12 smsl-n100 volumio[779]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 20:02:12 smsl-n100 volumio[779]: info: Initializing Serial Communication on port /dev/ttyS1 Jan 26 20:02:12 smsl-n100 volumio[779]: info: Touch Event Listener Process Starting Jan 26 20:02:12 smsl-n100 volumio[779]: info: Refreshing TIDAL token Jan 26 20:02:12 smsl-n100 sudo[1302]: pam_unix(sudo:auth): conversation failed Jan 26 20:02:12 smsl-n100 sudo[1302]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jan 26 20:02:13 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:02:13 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:13 smsl-n100 volumio[779]: info: Initializing GPIOs Jan 26 20:02:13 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 Jan 26 20:02:13 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 Jan 26 20:02:13 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 Jan 26 20:02:13 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 Jan 26 20:02:13 smsl-n100 volumio[779]: info: Starting Button Listener Jan 26 20:02:13 smsl-n100 volumio[779]: info: Turning off Green LED Jan 26 20:02:13 smsl-n100 volumio[779]: info: Tuning on Blue LED Jan 26 20:02:13 smsl-n100 volumio[779]: info: Signaling System Ready Jan 26 20:02:13 smsl-n100 volumio[779]: info: Volumio Calling Home Jan 26 20:02:13 smsl-n100 sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 26 20:02:13 smsl-n100 sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:13 smsl-n100 systemd[1]: Started RoonBridge. Jan 26 20:02:13 smsl-n100 sudo[1328]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:13 smsl-n100 volumio[779]: (node:779) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 26 20:02:13 smsl-n100 volumio[779]: (Use `node --trace-deprecation ...` to show where the warning was created) Jan 26 20:02:13 smsl-n100 volumio[779]: info: Stopping AccessToken refresher cron for QOBUZ Jan 26 20:02:13 smsl-n100 volumio[779]: info: AccessToken refresher cron started for QOBUZ Jan 26 20:02:13 smsl-n100 volumio[779]: info: Adding QOBUZ REST API Endpoints Jan 26 20:02:13 smsl-n100 volumio[779]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 26 20:02:13 smsl-n100 volumio[779]: info: Serial port opened successfully Jan 26 20:02:13 smsl-n100 volumio[779]: info: Sending serial start messages Jan 26 20:02:13 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:13 smsl-n100 volumio[779]: info: Reporting MCU Network Status: 1 Jan 26 20:02:14 smsl-n100 volumio[779]: info: Touch Event Listener Process Closed Jan 26 20:02:14 smsl-n100 volumio[779]: error: Cannot start Volumio Streaming Daemon Jan 26 20:02:14 smsl-n100 volumio[779]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 20:02:14 smsl-n100 volumio[779]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 20:02:14 smsl-n100 volumio[779]: info: RAAT Albumart path created successfully Jan 26 20:02:14 smsl-n100 volumio[779]: info: MPD Permissions set Jan 26 20:02:14 smsl-n100 volumio[779]: info: MPD Permissions set Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 26 20:02:14 smsl-n100 volumio[779]: info: Setting Device Volume Override Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 26 20:02:14 smsl-n100 volumio[779]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 26 20:02:14 smsl-n100 volumio[779]: info: Disabling external Volume Control Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:14 smsl-n100 volumio-remote-updater[537]: [2026-01-26 20:02:14] [connect] Successful connection Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: Volumio called home Jan 26 20:02:14 smsl-n100 volumio[779]: info: Discovery: adding bab79c95-6f3a-4939-bc86-50bb8e0c87ac Jan 26 20:02:14 smsl-n100 volumio[779]: info: Discovery: Found device SMSL N100 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 1, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 26 20:02:14 smsl-n100 volumio-remote-updater[537]: [2026-01-26 20:02:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1769475734 101 Jan 26 20:02:14 smsl-n100 volumio[779]: 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: 10 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:14 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:14 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:14 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:14 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:14 smsl-n100 volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 26 20:02:15 smsl-n100 volumio[779]: info: Access Token successfully retrieved Jan 26 20:02:15 smsl-n100 volumio[779]: info: Starting Shairport Sync Jan 26 20:02:15 smsl-n100 volumio[779]: info: Starting Shairport Sync Jan 26 20:02:15 smsl-n100 volumio[779]: info: Starting Shairport Sync Jan 26 20:02:15 smsl-n100 sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:02:15 smsl-n100 sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:15 smsl-n100 sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:15 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:15 smsl-n100 sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:15 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:15 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:15 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:15 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:15 smsl-n100 sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:02:15 smsl-n100 sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:15 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 20:02:15 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jan 26 20:02:15 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:15 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:15 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 20:02:15 smsl-n100 sudo[1366]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:15 smsl-n100 sudo[1374]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:15 smsl-n100 sudo[1378]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:15 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:15 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:15 smsl-n100 volumio[779]: info: Shairport-Sync Started Jan 26 20:02:16 smsl-n100 volumio[779]: Error adding Membership: Error: addMembership EINVAL Jan 26 20:02:16 smsl-n100 volumio[779]: info: Shairport-Sync Started Jan 26 20:02:16 smsl-n100 volumio[779]: info: Shairport-Sync Started Jan 26 20:02:16 smsl-n100 volumio[779]: info: TidalConnect service stoped! Jan 26 20:02:16 smsl-n100 volumio[779]: verbose: New Socket.io Connection to 10.113.10.55 from 10.113.10.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetQueue Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreStateMachine::getQueue Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getQueue Jan 26 20:02:16 smsl-n100 volumio[779]: info: Listing playlists Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 20:02:16 smsl-n100 volumio[779]: info: Received Get System Info Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:02:16 smsl-n100 volumio[779]: info: Discovery: Getting this device information Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioGetState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 RoonBridge[1332]: 00:00:00.038 Warn: get lock file path: /tmp/.rnbgem0- Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:16 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:16 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: MAXVOL, message: Jan 26 20:02:16 smsl-n100 volumio[779]: error: Serial API: Failed to decode command: 100, message: undefined Jan 26 20:02:16 smsl-n100 volumio[779]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 26 20:02:16 smsl-n100 volumio[779]: info: Successfully retrieved User Session From TIDAL Jan 26 20:02:16 smsl-n100 volumio[779]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 20:02:16 smsl-n100 volumio[779]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 20:02:16 smsl-n100 sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 20:02:16 smsl-n100 sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:02:16 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Jan 26 20:02:16 smsl-n100 sudo[1393]: pam_unix(sudo:session): session closed for user root Jan 26 20:02:16 smsl-n100 volumio[779]: info: VolumeController::SetAlsaVolume0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:16 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:16 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:16 smsl-n100 volumio[779]: error: Help! Some callbacks for volumioPushState are crashing! Jan 26 20:02:16 smsl-n100 volumio[779]: error: Cannot read property 'sendVolumeMute' of undefined Jan 26 20:02:17 smsl-n100 volumio[779]: info: Successfully retrieved User Subscription From TIDAL Jan 26 20:02:17 smsl-n100 volumio[779]: info: Adding TIDAL to Browse Sources Jan 26 20:02:17 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:02:17 smsl-n100 volumio[779]: info: [1769475737011] CoreMusicLibrary::Adding element TIDAL Jan 26 20:02:17 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:02:17 smsl-n100 volumio[779]: Cannot find translation for source Calm Radio Jan 26 20:02:17 smsl-n100 volumio[779]: Cannot find translation for source TIDAL Jan 26 20:02:17 smsl-n100 volumio[779]: info: Adding TIDAL REST API Endpoints Jan 26 20:02:17 smsl-n100 volumio[779]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.427 Trace: [childprocess] using unix child process Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.639 Debug: PathForResource, filename: ../.update Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.732 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.756 Debug: PathForResource, filename: RAATServer Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.757 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.760 Debug: PathForResource, filename: RoonBridgeHelper Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: 00:00:01.761 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 26 20:02:17 smsl-n100 RoonBridge[1332]: Initializing Jan 26 20:02:17 smsl-n100 volumio[779]: info: Executing endpoint tc_getconfig Jan 26 20:02:17 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 26 20:02:17 smsl-n100 vtcs[1395]: STARTING TidalConnect services, version: 1.5.1-beta1 Jan 26 20:02:17 smsl-n100 vtcs[1395]: STARTED TidalConnect services. Jan 26 20:02:18 smsl-n100 volumio[779]: info: Executing endpoint tc_connect Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 26 20:02:18 smsl-n100 volumio[779]: info: Connecting to TidalConnect Jan 26 20:02:18 smsl-n100 RoonBridge[1332]: 00:00:01.912 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::servicePushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 26 20:02:18 smsl-n100 volumio[779]: verbose: CURRENT POSITION 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::syncState stateService stop Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::syncState currentStatus stop Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: No code Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::servicePushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 26 20:02:18 smsl-n100 volumio[779]: verbose: CURRENT POSITION 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::syncState stateService stop Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::syncState currentStatus stop Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: No code Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreStateMachine::pushState Jan 26 20:02:18 smsl-n100 volumio[779]: info: CorePlayQueue::getTrack 0 Jan 26 20:02:18 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushState Jan 26 20:02:18 smsl-n100 RoonBridge[1332]: 00:00:02.077 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 26 20:02:18 smsl-n100 RoonBridge[1332]: Not Running (.o) Jan 26 20:02:19 smsl-n100 RoonBridge[1332]: Running Jan 26 20:02:19 smsl-n100 volumio[779]: info: TidalConnect service started! Jan 26 20:02:20 smsl-n100 RoonBridge[1332]: 00:00:00.093 Warn: get lock file path: /tmp/.rnbhgem0- Jan 26 20:02:21 smsl-n100 volumio[779]: info: Preload queue cleared Jan 26 20:02:21 smsl-n100 mpd[1280]: Jan 26 20:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreStateMachine::ClearQueue Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreStateMachine::stop Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 20:02:21 smsl-n100 volumio[779]: info: CorePlayQueue::clearPlayQueue Jan 26 20:02:21 smsl-n100 volumio[779]: info: CorePlayQueue::saveQueue Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreCommandRouter::volumioPushQueue Jan 26 20:02:21 smsl-n100 volumio[779]: info: CoreStateMachine::addQueueItems Jan 26 20:02:21 smsl-n100 volumio[779]: info: CorePlayQueue::addQueueItems Jan 26 20:02:21 smsl-n100 volumio[779]: info: Preload queue cleared Jan 26 20:02:21 smsl-n100 volumio[779]: info: Adding Item to queue: music-library/NAS/CD/Andy Williams - The Real... Andy Williams/CD 1/01. Moon River.flac Jan 26 20:02:21 smsl-n100 volumio[779]: info: Exploding uri music-library/NAS/CD/Andy Williams - The Real... Andy Williams/CD 1/01. Moon River.flac in service mpd Jan 26 20:02:21 smsl-n100 volumio[779]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:02:21 smsl-n100 volumio[779]: TypeError: Cannot read property 'then' of undefined Jan 26 20:02:21 smsl-n100 volumio[779]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2546:19) Jan 26 20:02:21 smsl-n100 volumio[779]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2344:21) Jan 26 20:02:21 smsl-n100 volumio[779]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Jan 26 20:02:21 smsl-n100 volumio[779]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 26 20:02:21 smsl-n100 volumio[779]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 26 20:02:21 smsl-n100 volumio[779]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Jan 26 20:02:21 smsl-n100 volumio[779]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Jan 26 20:02:21 smsl-n100 volumio[779]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Jan 26 20:02:21 smsl-n100 volumio[779]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Jan 26 20:02:21 smsl-n100 volumio[779]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Jan 26 20:02:21 smsl-n100 volumio[779]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:02:23 smsl-n100 sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 20:01 Jan 26 20:02:23 smsl-n100 sudo[1450]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"