-- Logs begin at Tue 2025-07-08 20:23:51 MSK, end at Tue 2025-07-08 20:25:14 MSK. -- Jul 08 20:24:00 smsl-n100 ntpd[732]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 08 20:24:00 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:00] [info] asio async_connect error: system:111 (Connection refused) Jul 08 20:24:00 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 08 20:24:00 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:00] [error] handle_connect error: Underlying Transport Error Jul 08 20:24:02 smsl-n100 mpd[755]: Jul 08 20:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 20:24:02 smsl-n100 systemd[1]: Started Music Player Daemon. Jul 08 20:24:02 smsl-n100 mpd_monitor.sh[546]: MPD restarted due to no mpc output. Jul 08 20:24:04 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 08 20:24:04 smsl-n100 sh[472]: eth0: carrier acquired Jul 08 20:24:04 smsl-n100 dhcpcd[517]: eth0: carrier acquired Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: carrier acquired Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: carrier acquired Jul 08 20:24:04 smsl-n100 sh[472]: eth0: IAID aa:93:37:64 Jul 08 20:24:04 smsl-n100 dhcpcd[517]: eth0: IAID aa:93:37:64 Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: IAID aa:93:37:64 Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: IAID aa:93:37:64 Jul 08 20:24:04 smsl-n100 ifplugd(eth0)[754]: Link beat detected. Jul 08 20:24:04 smsl-n100 ifplugd(eth0)[754]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 08 20:24:04 smsl-n100 ifplugd(eth0)[754]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: rebinding lease of 192.168.1.80 Jul 08 20:24:04 smsl-n100 dhcpcd[567]: eth0: rebinding lease of 192.168.1.80 Jul 08 20:24:05 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:24:05 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:24:05 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:24:05 smsl-n100 dhcpcd[567]: eth0: soliciting an IPv6 router Jul 08 20:24:05 smsl-n100 dhcpcd[567]: eth0: soliciting an IPv6 router Jul 08 20:24:05 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:05 smsl-n100 volumio[798]: info: ----- Volumio3 ---- Jul 08 20:24:05 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:05 smsl-n100 volumio[798]: info: ----- System startup ---- Jul 08 20:24:05 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:05 smsl-n100 sh[472]: eth0: rebinding lease of 192.168.1.80 Jul 08 20:24:05 smsl-n100 dhcpcd[517]: eth0: rebinding lease of 192.168.1.80 Jul 08 20:24:05 smsl-n100 sh[472]: eth0: soliciting an IPv6 router Jul 08 20:24:05 smsl-n100 dhcpcd[517]: eth0: soliciting an IPv6 router Jul 08 20:24:07 smsl-n100 volumio[798]: info: MYVOLUMIO Environment detected Jul 08 20:24:07 smsl-n100 volumio[798]: info: Plugin folders cleanup Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning into folder /volumio/app/plugins/ Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category audio_interface Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category miscellanea Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category music_service Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category plugins.json Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category system_controller Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning category user_interface Jul 08 20:24:07 smsl-n100 volumio[798]: info: Scanning into folder /data/plugins/ Jul 08 20:24:07 smsl-n100 volumio[798]: info: Plugin folders cleanup completed Jul 08 20:24:07 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:07 smsl-n100 volumio[798]: info: ----- Core plugins startup ---- Jul 08 20:24:07 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:07 smsl-n100 volumio[798]: info: Loading plugins from folder /volumio/app/plugins/ Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin bluetooth to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin multiroom to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin upnp to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin metavolumio to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin cd_controller to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin raat to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 08 20:24:07 smsl-n100 volumio[798]: info: Loading plugins from folder /data/plugins/ Jul 08 20:24:07 smsl-n100 volumio[798]: info: Loading plugin "system"... Jul 08 20:24:07 smsl-n100 volumio[798]: info: Loading plugin "appearance"... Jul 08 20:24:07 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:07] [connect] Successful connection Jul 08 20:24:09 smsl-n100 dhcpcd[567]: eth0: probing for an IPv4LL address Jul 08 20:24:09 smsl-n100 dhcpcd[567]: eth0: probing for an IPv4LL address Jul 08 20:24:10 smsl-n100 dhcpcd[567]: eth0: DHCP lease expired Jul 08 20:24:10 smsl-n100 dhcpcd[567]: eth0: DHCP lease expired Jul 08 20:24:10 smsl-n100 dhcpcd[567]: eth0: soliciting a DHCP lease Jul 08 20:24:10 smsl-n100 dhcpcd[567]: eth0: soliciting a DHCP lease Jul 08 20:24:10 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:24:10 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:24:10 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "network"... Jul 08 20:24:10 smsl-n100 volumio[798]: info: Refreshing Cached IP Addresses Jul 08 20:24:10 smsl-n100 sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 20:24:10 smsl-n100 sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:10 smsl-n100 sudo[861]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "services"... Jul 08 20:24:10 smsl-n100 sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 20:24:10 smsl-n100 sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "alsa_controller"... Jul 08 20:24:10 smsl-n100 sudo[863]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:10 smsl-n100 volumio[798]: warn: Unable to locate the audio output device SVB-202D. Please configure a valid output device. Jul 08 20:24:10 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "wizard"... Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "networkfs"... Jul 08 20:24:10 smsl-n100 sh[472]: eth0: probing for an IPv4LL address Jul 08 20:24:10 smsl-n100 dhcpcd[517]: eth0: probing for an IPv4LL address Jul 08 20:24:10 smsl-n100 volumio[798]: info: Starting Udev Watcher for removable devices Jul 08 20:24:10 smsl-n100 sh[472]: eth0: DHCP lease expired Jul 08 20:24:10 smsl-n100 dhcpcd[517]: eth0: DHCP lease expired Jul 08 20:24:10 smsl-n100 sh[472]: eth0: soliciting a DHCP lease Jul 08 20:24:10 smsl-n100 dhcpcd[517]: eth0: soliciting a DHCP lease Jul 08 20:24:10 smsl-n100 volumio[798]: info: Ignoring mount for partition: boot Jul 08 20:24:10 smsl-n100 volumio[798]: info: Ignoring mount for partition: volumio Jul 08 20:24:10 smsl-n100 volumio[798]: info: Ignoring mount for partition: volumio_data Jul 08 20:24:10 smsl-n100 volumio[798]: info: Mounting Device ESD-USB Jul 08 20:24:10 smsl-n100 sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ESD-USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 08 20:24:10 smsl-n100 sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:10 smsl-n100 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 08 20:24:10 smsl-n100 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 08 20:24:10 smsl-n100 sudo[894]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:10 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "volumio_command_line_client"... Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "upnp"... Jul 08 20:24:10 smsl-n100 volumio[798]: info: [1751995450864] Starting Upmpd Daemon Jul 08 20:24:10 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "my_music"... Jul 08 20:24:10 smsl-n100 volumio[798]: info: Loading plugin "mpd"... Jul 08 20:24:11 smsl-n100 volumio[798]: info: Plugin upnp_browser is not enabled Jul 08 20:24:11 smsl-n100 volumio[798]: info: Loading plugin "bluetooth"... Jul 08 20:24:12 smsl-n100 volumio[798]: info: [1751995452077] Starting BluetoothController Jul 08 20:24:12 smsl-n100 volumio[798]: info: Loading plugin "alarm-clock"... Jul 08 20:24:12 smsl-n100 volumio[798]: info: Loading plugin "manifestui"... Jul 08 20:24:13 smsl-n100 volumio[798]: info: Loading plugin "metavolumio"... Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: adding default route Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:15 smsl-n100 avahi-daemon[552]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.205.206. Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:15 smsl-n100 avahi-daemon[552]: New relevant interface eth0.IPv4 for mDNS. Jul 08 20:24:15 smsl-n100 dhcpcd[567]: eth0: adding default route Jul 08 20:24:15 smsl-n100 avahi-daemon[552]: Registering new address record for 169.254.205.206 on eth0.IPv4. Jul 08 20:24:15 smsl-n100 dhcpcd[567]: forked to background, child pid 910 Jul 08 20:24:15 smsl-n100 dhcpcd[567]: forked to background, child pid 910 Jul 08 20:24:15 smsl-n100 systemd[1]: Started DHCP Client Daemon. Jul 08 20:24:15 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:24:15 smsl-n100 sh[472]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:15 smsl-n100 sh[472]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:15 smsl-n100 sh[472]: eth0: adding default route Jul 08 20:24:15 smsl-n100 dhcpcd[517]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:15 smsl-n100 dhcpcd[517]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:15 smsl-n100 dhcpcd[517]: eth0: adding default route Jul 08 20:24:15 smsl-n100 sh[472]: forked to background, child pid 924 Jul 08 20:24:15 smsl-n100 dhcpcd[517]: forked to background, child pid 924 Jul 08 20:24:15 smsl-n100 ifplugd(eth0)[754]: client: ifup: interface eth0 already configured Jul 08 20:24:15 smsl-n100 sh[472]: eth0=eth0 Jul 08 20:24:15 smsl-n100 ifplugd(eth0)[754]: Program executed successfully. Jul 08 20:24:15 smsl-n100 volumio[798]: info: Loading plugin "airplay_emulation"... Jul 08 20:24:15 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:15 smsl-n100 volumio[798]: info: Plugin cd_controller is not enabled Jul 08 20:24:15 smsl-n100 volumio[798]: info: Loading plugin "last_100"... Jul 08 20:24:15 smsl-n100 volumio[798]: info: Plugin raat is not enabled Jul 08 20:24:15 smsl-n100 volumio[798]: info: Loading plugin "streaming_services"... Jul 08 20:24:16 smsl-n100 ntpd[732]: Listen normally on 3 eth0 169.254.205.206:123 Jul 08 20:24:16 smsl-n100 ntpd[732]: new interface(s) found: waking up resolver Jul 08 20:24:17 smsl-n100 nmbd[731]: [2025/07/08 20:24:17.511301, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 08 20:24:17 smsl-n100 nmbd[731]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 08 20:24:17 smsl-n100 systemd[1]: Started Samba NMB Daemon. Jul 08 20:24:17 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... Jul 08 20:24:18 smsl-n100 winbindd[950]: [2025/07/08 20:24:18.053190, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 08 20:24:18 smsl-n100 winbindd[950]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 08 20:24:18 smsl-n100 winbindd[950]: [2025/07/08 20:24:18.079759, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 08 20:24:18 smsl-n100 winbindd[950]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 08 20:24:18 smsl-n100 systemd[1]: Started Samba Winbind Daemon. Jul 08 20:24:18 smsl-n100 systemd[1]: Starting Samba SMB Daemon... Jul 08 20:24:18 smsl-n100 volumio[798]: info: Starting Streaming Service Transparent Proxy Jul 08 20:24:18 smsl-n100 volumio[798]: info: Plugin tidalconnect is not enabled Jul 08 20:24:18 smsl-n100 volumio[798]: info: Loading plugin "webradio"... Jul 08 20:24:18 smsl-n100 volumio[798]: info: Loading plugin "i2s_dacs"... Jul 08 20:24:18 smsl-n100 volumio[798]: info: I2S DAC not set, start Auto-detection Jul 08 20:24:18 smsl-n100 volumio[798]: info: Loading plugin "volumiodiscovery"... Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** For more information see Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 08 20:24:18 smsl-n100 volumio[798]: *** WARNING *** For more information see Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** For more information see Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 08 20:24:18 smsl-n100 node[798]: *** WARNING *** For more information see Jul 08 20:24:19 smsl-n100 volumio[798]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 08 20:24:19 smsl-n100 volumio[798]: info: Discovery: Started advertising with name: SMSL N100 Jul 08 20:24:19 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 08 20:24:19 smsl-n100 volumio[798]: info: Loading plugin "multiroom"... Jul 08 20:24:19 smsl-n100 winbindd[950]: [2025/07/08 20:24:19.105121, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jul 08 20:24:19 smsl-n100 winbindd[950]: res_names->count = 3, expected 4 Jul 08 20:24:19 smsl-n100 smbd[955]: [2025/07/08 20:24:19.161476, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 08 20:24:19 smsl-n100 systemd[1]: Started Samba SMB Daemon. Jul 08 20:24:19 smsl-n100 smbd[955]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 08 20:24:20 smsl-n100 volumio[798]: STREAMING PROXY: Starting server on port 3245 Jul 08 20:24:20 smsl-n100 volumio[798]: Node JS runtime: 14 Jul 08 20:24:22 smsl-n100 volumio[798]: info: Applying required configuration parameters for plugin multiroom Jul 08 20:24:22 smsl-n100 sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 08 20:24:22 smsl-n100 sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:22 smsl-n100 sudo[987]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:22 smsl-n100 volumio[798]: info: MRS: MultiRoom plugin initialized Jul 08 20:24:22 smsl-n100 volumio[798]: info: MRS: STOPPING SNAPCLIENT Jul 08 20:24:22 smsl-n100 volumio[798]: info: MRS: Snap server stop Jul 08 20:24:22 smsl-n100 volumio[798]: info: MRS: STOPPING volumioStreaming Jul 08 20:24:22 smsl-n100 sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 08 20:24:22 smsl-n100 sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:22 smsl-n100 sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 08 20:24:22 smsl-n100 sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:22 smsl-n100 volumio[798]: info: Loading plugin "outputs"... Jul 08 20:24:22 smsl-n100 sudo[1006]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:22 smsl-n100 sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 08 20:24:22 smsl-n100 sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:22 smsl-n100 volumio[798]: info: Loading plugin "albumart"... Jul 08 20:24:22 smsl-n100 sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 08 20:24:22 smsl-n100 sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:22 smsl-n100 sudo[1014]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:22 smsl-n100 sudo[1008]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:22 smsl-n100 volumio[798]: info: Plugin example_plugin is not enabled Jul 08 20:24:22 smsl-n100 volumio[798]: info: Loading plugin "hi_res_audio"... Jul 08 20:24:22 smsl-n100 sudo[1011]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:22 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:22] [connect] Successful connection Jul 08 20:24:23 smsl-n100 volumio[798]: Forking 3 albumart workers Jul 08 20:24:23 smsl-n100 systemd[1]: systemd-fsckd.service: Succeeded. Jul 08 20:24:25 smsl-n100 volumio[798]: Starting albumart workers Jul 08 20:24:25 smsl-n100 volumio[798]: Starting albumart workers Jul 08 20:24:25 smsl-n100 volumio[798]: Starting albumart workers Jul 08 20:24:26 smsl-n100 volumio[798]: info: Applying required configuration parameters for plugin hi_res_audio Jul 08 20:24:26 smsl-n100 volumio[798]: info: Loading plugin "inputs"... Jul 08 20:24:28 smsl-n100 volumio[798]: info: Loading plugin "qobuz"... Jul 08 20:24:30 smsl-n100 volumio[798]: info: Loading plugin "smart_inputs"... Jul 08 20:24:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jul 08 20:24:31 smsl-n100 dhcpcd[910]: eth0: carrier lost Jul 08 20:24:31 smsl-n100 dhcpcd[924]: eth0: carrier lost Jul 08 20:24:31 smsl-n100 dhcpcd[924]: eth0: deleting route to 169.254.0.0/16 Jul 08 20:24:31 smsl-n100 dhcpcd[924]: eth0: deleting default route Jul 08 20:24:31 smsl-n100 avahi-daemon[552]: Withdrawing address record for 169.254.205.206 on eth0. Jul 08 20:24:31 smsl-n100 avahi-daemon[552]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.205.206. Jul 08 20:24:31 smsl-n100 avahi-daemon[552]: Interface eth0.IPv4 no longer relevant for mDNS. Jul 08 20:24:31 smsl-n100 dhcpcd[910]: eth0: deleting route to 169.254.0.0/16 Jul 08 20:24:31 smsl-n100 dhcpcd[910]: eth0: deleting default route Jul 08 20:24:31 smsl-n100 volumio[798]: info: Loading plugin "tidal"... Jul 08 20:24:31 smsl-n100 ifplugd(eth0)[754]: Link beat lost. Jul 08 20:24:32 smsl-n100 ntpd[732]: Deleting interface #3 eth0, 169.254.205.206#123, interface stats: received=0, sent=0, dropped=0, active_time=16 secs Jul 08 20:24:33 smsl-n100 volumio[798]: info: Loading plugin "oem_helper"... Jul 08 20:24:34 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 08 20:24:34 smsl-n100 dhcpcd[924]: eth0: carrier acquired Jul 08 20:24:34 smsl-n100 dhcpcd[910]: eth0: carrier acquired Jul 08 20:24:34 smsl-n100 dhcpcd[924]: eth0: IAID aa:93:37:64 Jul 08 20:24:34 smsl-n100 dhcpcd[910]: eth0: IAID aa:93:37:64 Jul 08 20:24:34 smsl-n100 dhcpcd[924]: eth0: soliciting a DHCP lease Jul 08 20:24:34 smsl-n100 ifplugd(eth0)[754]: Link beat detected. Jul 08 20:24:34 smsl-n100 dhcpcd[910]: eth0: soliciting an IPv6 router Jul 08 20:24:35 smsl-n100 dhcpcd[910]: eth0: soliciting a DHCP lease Jul 08 20:24:35 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:24:35 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:24:35 smsl-n100 dhcpcd[924]: eth0: soliciting an IPv6 router Jul 08 20:24:35 smsl-n100 volumio[798]: info: Applying required configuration parameters for plugin oem_helper Jul 08 20:24:35 smsl-n100 volumio[798]: info: Loading plugin "smslcontrol"... Jul 08 20:24:36 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 Jul 08 20:24:36 smsl-n100 volumio[798]: info: Adding this device properties Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 08 20:24:36 smsl-n100 volumio[798]: info: Setting Additional Device Volumio Properties: [object Object] Jul 08 20:24:36 smsl-n100 volumio[798]: info: Loading plugin "updater_comm"... Jul 08 20:24:36 smsl-n100 volumio[798]: info: Plugin mpdemulation is not enabled Jul 08 20:24:36 smsl-n100 volumio[798]: info: Loading plugin "rest_api"... Jul 08 20:24:36 smsl-n100 volumio[798]: info: Loading plugin "websocket"... Jul 08 20:24:36 smsl-n100 volumio[798]: info: Starting Socket.io Server version 2.3.0 Jul 08 20:24:36 smsl-n100 volumio[798]: info: Loading i18n strings for locale ru Jul 08 20:24:36 smsl-n100 volumio[798]: Updating browse sources language Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::initPlayerControls Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:36 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 20:24:37 smsl-n100 volumio[798]: Express server listening on port 3000 Jul 08 20:24:37 smsl-n100 volumio[798]: [Metrics] WebUI: 33s 156.35ms Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::resetVolumioState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::getcurrentVolume Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioRetrievevolume Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:37 smsl-n100 sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 20:24:37 smsl-n100 sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:37 smsl-n100 volumio[798]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 08 20:24:37 smsl-n100 volumio[798]: info: Completed loading Core Plugins Jul 08 20:24:37 smsl-n100 sudo[1158]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:37 smsl-n100 volumio[798]: info: Preparing to generate the ALSA configuration file Jul 08 20:24:37 smsl-n100 sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 20:24:37 smsl-n100 sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:37 smsl-n100 sudo[1160]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:37 smsl-n100 volumio[798]: info: Volumio Network Manager: Network status updated: 0 Jul 08 20:24:37 smsl-n100 volumio[798]: info: MRS: Removed streaming files Jul 08 20:24:37 smsl-n100 volumio[798]: info: MRS: volumioStreaming STOPPED Jul 08 20:24:37 smsl-n100 volumio[798]: info: MRS: SNAPSERVER STOPPED Jul 08 20:24:37 smsl-n100 volumio[798]: info: MRS: SNAPCLIENT STOPPED Jul 08 20:24:37 smsl-n100 volumio[798]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 08 20:24:37 smsl-n100 volumio[798]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 08 20:24:37 smsl-n100 volumio[798]: info: Reading ALSA contributions from plugins. Jul 08 20:24:37 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:37] [connect] Successful connection Jul 08 20:24:37 smsl-n100 volumio[798]: info: Reloading queue from file Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::setRepeat null single undefined Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::setRandom null Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:37 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:37 smsl-n100 volumio[798]: info: Setting Device type: NanoPi NEO 3 Jul 08 20:24:38 smsl-n100 volumio-remote-updater[543]: [2025-07-08 20:24:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751995477 101 Jul 08 20:24:38 smsl-n100 volumio[798]: 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 Jul 08 20:24:38 smsl-n100 volumio[798]: info: Discovery: A device disappeared from network Jul 08 20:24:38 smsl-n100 volumio[798]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 08 20:24:38 smsl-n100 volumio[798]: info: Asound.conf file unchanged, so no further update is needed Jul 08 20:24:38 smsl-n100 volumio[798]: info: Output device has changed, restarting MPD Jul 08 20:24:38 smsl-n100 sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 08 20:24:38 smsl-n100 sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 08 20:24:38 smsl-n100 sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 20:24:38 smsl-n100 sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 volumio[798]: info: Output device has changed, restarting Shairport Sync Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:38 smsl-n100 sudo[1172]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 sudo[1169]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 20:24:38 smsl-n100 sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 volumio[798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 20:24:38 smsl-n100 volumio[798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 20:24:38 smsl-n100 systemd[1]: Stopping Music Player Daemon... Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:38 smsl-n100 volumio[798]: info: ___________ START PLUGINS ___________ Jul 08 20:24:38 smsl-n100 volumio[798]: info: ControllerMpd::onStart: Initializing MPD Jul 08 20:24:38 smsl-n100 volumio[798]: info: Creating MPD Configuration file Jul 08 20:24:38 smsl-n100 sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 08 20:24:38 smsl-n100 sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 sudo[1184]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 20:24:38 smsl-n100 sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 volumio[798]: info: Adding METAVOLUMIO REST API Endpoints Jul 08 20:24:38 smsl-n100 volumio[798]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 08 20:24:38 smsl-n100 volumio[798]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 08 20:24:38 smsl-n100 volumio[798]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 08 20:24:38 smsl-n100 volumio[798]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:38 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 08 20:24:38 smsl-n100 sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100 Jul 08 20:24:38 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 08 20:24:38 smsl-n100 sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 sudo[1189]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 08 20:24:38 smsl-n100 volumio[798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:38 smsl-n100 volumio[798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 08 20:24:38 smsl-n100 volumio[798]: info: [1751995478782] CoreMusicLibrary::Adding element Last_100 Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 20:24:38 smsl-n100 volumio[798]: info: Streaming services startup Jul 08 20:24:38 smsl-n100 volumio[798]: info: Starting Streaming Daemon Jul 08 20:24:38 smsl-n100 sudo[1193]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 20:24:38 smsl-n100 sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 sudo[1193]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 08 20:24:38 smsl-n100 sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:38 smsl-n100 sudo[1202]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 08 20:24:38 smsl-n100 volumio[798]: info: [1751995478936] CoreMusicLibrary::Adding element Webradio Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 20:24:38 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:24:38 smsl-n100 volumio[798]: info: Initializing BBC Radios Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 08 20:24:39 smsl-n100 volumio[798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:39 smsl-n100 volumio[798]: error: Hi Res Audio Failed Login: Missing Login Data Jul 08 20:24:39 smsl-n100 volumio[798]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 08 20:24:39 smsl-n100 volumio[798]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 08 20:24:39 smsl-n100 volumio[798]: info: Initializing Serial Communication on port /dev/ttyS1 Jul 08 20:24:39 smsl-n100 volumio[798]: info: Touch Event Listener Process Starting Jul 08 20:24:39 smsl-n100 volumio[798]: info: Refreshing QOBUZ token Jul 08 20:24:39 smsl-n100 sudo[1222]: pam_unix(sudo:auth): conversation failed Jul 08 20:24:39 smsl-n100 sudo[1222]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jul 08 20:24:39 smsl-n100 volumio[798]: info: Adding inputs REST Endpoints Jul 08 20:24:39 smsl-n100 volumio[798]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 08 20:24:39 smsl-n100 volumio[798]: info: Scanning Audio Inputs Jul 08 20:24:39 smsl-n100 volumio[798]: info: Checking against Known Cards name Jul 08 20:24:39 smsl-n100 volumio[798]: info: Adding Server instance for streaming Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:24:39 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:39 smsl-n100 volumio[798]: info: Initializing GPIOs Jul 08 20:24:39 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 Jul 08 20:24:39 smsl-n100 dhcpcd[924]: eth0: probing for an IPv4LL address Jul 08 20:24:39 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 Jul 08 20:24:39 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 Jul 08 20:24:39 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 Jul 08 20:24:39 smsl-n100 volumio[798]: info: Starting Button Listener Jul 08 20:24:39 smsl-n100 volumio[798]: info: Turning off Green LED Jul 08 20:24:39 smsl-n100 volumio[798]: info: Tuning on Blue LED Jul 08 20:24:39 smsl-n100 volumio[798]: info: Signaling System Ready Jul 08 20:24:39 smsl-n100 volumio[798]: info: Volumio Calling Home Jul 08 20:24:39 smsl-n100 volumio[798]: info: Stopping AccessToken refresher cron Jul 08 20:24:40 smsl-n100 dhcpcd[910]: eth0: probing for an IPv4LL address Jul 08 20:24:40 smsl-n100 volumio[798]: info: AccessToken refresher cron started Jul 08 20:24:40 smsl-n100 volumio[798]: info: Adding TIDAL REST API Endpoints Jul 08 20:24:40 smsl-n100 volumio[798]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 08 20:24:40 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:24:40 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:24:40 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:24:40 smsl-n100 volumio[798]: info: Serial port opened successfully Jul 08 20:24:40 smsl-n100 volumio[798]: info: Sending serial start messages Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: Reporting MCU Network Status: 0 Jul 08 20:24:40 smsl-n100 volumio[798]: warn: QOBUZ login failed: No access token received Jul 08 20:24:40 smsl-n100 volumio[798]: info: Stopping AccessToken refresher cron for QOBUZ Jul 08 20:24:40 smsl-n100 volumio[798]: info: AccessToken refresher cron started for QOBUZ Jul 08 20:24:40 smsl-n100 volumio[798]: info: Adding QOBUZ REST API Endpoints Jul 08 20:24:40 smsl-n100 volumio[798]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 08 20:24:40 smsl-n100 volumio[798]: info: Touch Event Listener Process Closed Jul 08 20:24:40 smsl-n100 volumio[798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 08 20:24:40 smsl-n100 volumio[798]: error: Cannot start Volumio Streaming Daemon Jul 08 20:24:40 smsl-n100 volumio[798]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 08 20:24:40 smsl-n100 volumio[798]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 08 20:24:40 smsl-n100 volumio[798]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Jul 08 20:24:40 smsl-n100 volumio[798]: Can't get device info: No such device Jul 08 20:24:40 smsl-n100 volumio[798]: info: MPD Permissions set Jul 08 20:24:40 smsl-n100 volumio[798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 08 20:24:40 smsl-n100 volumio[798]: info: MPD Permissions set Jul 08 20:24:40 smsl-n100 volumio[798]: info: Upmpdcli Daemon Started Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 08 20:24:40 smsl-n100 volumio[798]: info: Setting Device Volume Override Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 08 20:24:40 smsl-n100 volumio[798]: info: Updating Volume Controller Parameters: Device: 5 Name: SVB-202D Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 08 20:24:40 smsl-n100 volumio[798]: info: Disabling external Volume Control Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:40 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:40 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:40 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:40 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:40 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 0, message: undefined Jul 08 20:24:40 smsl-n100 volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:40 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:41 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:41 smsl-n100 volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 08 20:24:41 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:41 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:41 smsl-n100 sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 20:24:41 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:41 smsl-n100 sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:41 smsl-n100 sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:41 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:41 smsl-n100 sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:41 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:41 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:41 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:41 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 08 20:24:41 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:41 smsl-n100 sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 20:24:41 smsl-n100 sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:41 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:41 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioGetState Jul 08 20:24:41 smsl-n100 sudo[1287]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:41 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:41 smsl-n100 sudo[1285]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:41 smsl-n100 sudo[1290]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:41 smsl-n100 volumio[798]: info: Preparing to generate the ALSA configuration file Jul 08 20:24:41 smsl-n100 sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 08 20:24:41 smsl-n100 sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:41 smsl-n100 systemd[1]: Started Volumio Bluetooth Module. Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioGetState Jul 08 20:24:42 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:42 smsl-n100 sudo[1295]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:42 smsl-n100 sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 08 20:24:42 smsl-n100 sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:42 smsl-n100 volumiobt[1299]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 08 20:24:42 smsl-n100 sudo[1300]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:42 smsl-n100 volumio[798]: info: Shairport-Sync Started Jul 08 20:24:42 smsl-n100 volumio[798]: Error adding Membership: Error: addMembership EINVAL Jul 08 20:24:42 smsl-n100 volumio[798]: info: Shairport-Sync Started Jul 08 20:24:42 smsl-n100 volumio[798]: info: Shairport-Sync Started Jul 08 20:24:42 smsl-n100 volumio[798]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 08 20:24:42 smsl-n100 volumio[798]: info: Reading ALSA contributions from plugins. Jul 08 20:24:42 smsl-n100 volumio[798]: info: Volumio BT Module successfully started Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:24:42 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:24:42 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:42 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:24:42 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:24:42 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:24:42 smsl-n100 volumio[798]: info: Asound.conf file unchanged, so no further update is needed Jul 08 20:24:42 smsl-n100 volumio[798]: info: Output device has changed, restarting MPD Jul 08 20:24:42 smsl-n100 volumio[798]: info: Output device has changed, restarting Shairport Sync Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 20:24:42 smsl-n100 sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:42 smsl-n100 sudo[1306]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:42 smsl-n100 sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 20:24:42 smsl-n100 sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:42 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 08 20:24:42 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 08 20:24:42 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 08 20:24:42 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 08 20:24:42 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:24:42 smsl-n100 volumio[798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 20:24:42 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:24:42 smsl-n100 volumio[798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: Preparing to generate the ALSA configuration file Jul 08 20:24:42 smsl-n100 volumio[798]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 08 20:24:42 smsl-n100 volumio[798]: info: Reading ALSA contributions from plugins. Jul 08 20:24:42 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:42 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:42 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:42 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:42 smsl-n100 volumio[798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 08 20:24:42 smsl-n100 volumio[798]: info: MPD Permissions set Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 sudo[1314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 20:24:42 smsl-n100 sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:42 smsl-n100 sudo[1314]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 20:24:42 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:42 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:43 smsl-n100 volumio[798]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jul 08 20:24:43 smsl-n100 volumio[798]: info: Asound.conf file unchanged, so no further update is needed Jul 08 20:24:43 smsl-n100 volumio[798]: info: Output device has changed, restarting MPD Jul 08 20:24:43 smsl-n100 sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 20:24:43 smsl-n100 sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:43 smsl-n100 sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 08 20:24:43 smsl-n100 sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:43 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 20:24:43 smsl-n100 volumio[798]: info: Output device has changed, restarting Shairport Sync Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:43 smsl-n100 sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 20:24:43 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 08 20:24:43 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:43 smsl-n100 volumiobt[1299]: pulseaudio: no process found Jul 08 20:24:43 smsl-n100 sudo[1329]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:43 smsl-n100 sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:43 smsl-n100 sudo[1330]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:43 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:43 smsl-n100 sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 20:24:43 smsl-n100 sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:43 smsl-n100 sudo[1326]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:43 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 08 20:24:43 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 08 20:24:43 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 08 20:24:43 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 08 20:24:43 smsl-n100 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.14' (uid=1000 pid=1344 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 08 20:24:43 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:24:43 smsl-n100 volumio[798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 20:24:43 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:24:43 smsl-n100 volumio[798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 08 20:24:43 smsl-n100 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Successfully called chroot. Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Successfully dropped privileges. Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Successfully limited resources. Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Canary thread running. Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Running. Jul 08 20:24:43 smsl-n100 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.15' (uid=0 pid=1347 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 08 20:24:43 smsl-n100 rtkit-daemon[1347]: Watchdog thread running. Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:43 smsl-n100 systemd[1]: Starting Authorization Manager... Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:43 smsl-n100 sudo[1342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 20:24:43 smsl-n100 sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:43 smsl-n100 sudo[1342]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:43 smsl-n100 volumio[798]: info: MPD Permissions set Jul 08 20:24:43 smsl-n100 volumio[798]: info: Shairport-Sync Started Jul 08 20:24:43 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:43 smsl-n100 volumio[798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 08 20:24:43 smsl-n100 polkitd[1351]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 08 20:24:43 smsl-n100 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 systemd[1]: Started Authorization Manager. Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 20:24:43 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:24:43 smsl-n100 volumio[798]: info: Starting Shairport Sync Jul 08 20:24:44 smsl-n100 sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 20:24:44 smsl-n100 sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:44 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 20:24:44 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 08 20:24:44 smsl-n100 volumio[798]: info: MRS: Getting audio outputs on start Jul 08 20:24:44 smsl-n100 volumio[798]: info: MRS: Requesting all other devices output Jul 08 20:24:44 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:44 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 20:24:44 smsl-n100 sudo[1364]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:44 smsl-n100 volumio[798]: info: Shairport-Sync Started Jul 08 20:24:44 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 08 20:24:44 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Jul 08 20:24:44 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Jul 08 20:24:44 smsl-n100 sudo[1308]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 sudo[1176]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 volumio[798]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 08 20:24:44 smsl-n100 volumio[798]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 08 20:24:44 smsl-n100 volumio[798]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 08 20:24:44 smsl-n100 sudo[1333]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 sudo[1186]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 volumio[798]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 08 20:24:44 smsl-n100 volumio[798]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 08 20:24:44 smsl-n100 volumio[798]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 08 20:24:44 smsl-n100 volumio[798]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 08 20:24:44 smsl-n100 volumio[798]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 08 20:24:44 smsl-n100 volumio[798]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 08 20:24:44 smsl-n100 volumio[798]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 08 20:24:44 smsl-n100 volumio[798]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 08 20:24:44 smsl-n100 volumio[798]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 08 20:24:44 smsl-n100 volumio[798]: info: Completed starting Core Plugins Jul 08 20:24:44 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:44 smsl-n100 volumio[798]: info: ----- MyVolumio plugins startup ---- Jul 08 20:24:44 smsl-n100 volumio[798]: info: ------------------------------------------- Jul 08 20:24:44 smsl-n100 volumio[798]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 08 20:24:44 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:24:44 smsl-n100 dbus-daemon[568]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.36' (uid=1000 pid=1344 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:44 smsl-n100 systemd[1]: Starting Bluetooth service... Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:44 smsl-n100 pulseaudio[1344]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 08 20:24:44 smsl-n100 pulseaudio[1344]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Applying permissions Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Setting BT discoverable and pairable Jul 08 20:24:44 smsl-n100 dhcpcd[924]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:44 smsl-n100 dhcpcd[924]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:44 smsl-n100 dhcpcd[924]: eth0: adding default route Jul 08 20:24:44 smsl-n100 avahi-daemon[552]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.205.206. Jul 08 20:24:44 smsl-n100 avahi-daemon[552]: New relevant interface eth0.IPv4 for mDNS. Jul 08 20:24:44 smsl-n100 avahi-daemon[552]: Registering new address record for 169.254.205.206 on eth0.IPv4. Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: Bluetooth daemon 5.50 Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 08 20:24:44 smsl-n100 dbus-daemon[568]: [system] Successfully activated service 'org.bluez' Jul 08 20:24:44 smsl-n100 systemd[1]: Started Bluetooth service. Jul 08 20:24:44 smsl-n100 sudo[1372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 20:24:44 smsl-n100 sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: Starting SDP server Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: Excluding (cli) sap Jul 08 20:24:44 smsl-n100 sudo[1372]: pam_unix(sudo:session): session closed for user root Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: kernel lacks bnep-protocol support Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: System does not support network plugin Jul 08 20:24:44 smsl-n100 bluetoothd[1373]: Bluetooth management interface 1.14 initialized Jul 08 20:24:44 smsl-n100 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.37' (uid=0 pid=1373 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# -e power on Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Invalid command in menu main: -e Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Use "help" for a list of available commands in a menu. Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Use "menu " if you want to enter any submenu. Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Use "back" if you want to return to menu main. Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# agent on Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Failed to register agent object Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# discoverable on Jul 08 20:24:44 smsl-n100 volumiobt[1299]: No default controller available Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# pairable on Jul 08 20:24:44 smsl-n100 volumiobt[1299]: No default controller available Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# agent NoInputNoOutput Jul 08 20:24:44 smsl-n100 volumiobt[1299]: Failed to register agent object Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# default-agent Jul 08 20:24:44 smsl-n100 volumiobt[1299]: No agent is registered Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [bluetooth]# quit Jul 08 20:24:44 smsl-n100 pulseaudio[1344]: 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 Jul 08 20:24:44 smsl-n100 volumiobt[1299]: [59B blob data] Jul 08 20:24:44 smsl-n100 systemd[1]: Starting Hostname Service... Jul 08 20:24:44 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:24:45 smsl-n100 dhcpcd[910]: eth0: using IPv4LL address 169.254.205.206 Jul 08 20:24:45 smsl-n100 dhcpcd[910]: eth0: adding route to 169.254.0.0/16 Jul 08 20:24:45 smsl-n100 dhcpcd[910]: eth0: adding default route Jul 08 20:24:45 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:24:45 smsl-n100 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 08 20:24:45 smsl-n100 systemd[1]: Started Hostname Service. Jul 08 20:24:46 smsl-n100 ntpd[732]: Listen normally on 4 eth0 169.254.205.206:123 Jul 08 20:24:46 smsl-n100 ntpd[732]: new interface(s) found: waking up resolver Jul 08 20:24:48 smsl-n100 mpd[1383]: Jul 08 20:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 20:24:48 smsl-n100 systemd[1]: Started Music Player Daemon. Jul 08 20:24:48 smsl-n100 mpd_monitor.sh[546]: MPD restarted due to no mpc output. Jul 08 20:24:48 smsl-n100 volumio[798]: Upnp client error: Error: This socket has been ended by the other party Jul 08 20:24:48 smsl-n100 volumio[798]: error: MPD error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: Upnp client error: Error: This socket has been ended by the other party Jul 08 20:24:48 smsl-n100 volumio[798]: error: MPD error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: error: MPD error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: error: The expression evaluated to a falsy value: Jul 08 20:24:48 smsl-n100 volumio[798]: assert.ok(self.idling) Jul 08 20:24:48 smsl-n100 volumio[798]: error: updateQueue error: null Jul 08 20:24:48 smsl-n100 volumio[1174]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 08 20:24:49 smsl-n100 volumio[1174]: .................++++ Jul 08 20:24:50 smsl-n100 volumio[798]: info: Reporting MCU Network Status: 1 Jul 08 20:24:50 smsl-n100 volumio[798]: info: Volumio Network Manager: Network status updated: 1 Jul 08 20:24:50 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 1, message: undefined Jul 08 20:24:50 smsl-n100 volumio[798]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=314fb6dd9ac81686d54781b92cbbb659" http://updates.volumio.org/downloader-v1/track-device Jul 08 20:24:50 smsl-n100 volumio[798]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 08 20:24:50 smsl-n100 volumio[798]: Dload Upload Total Spent Left Speed Jul 08 20:24:50 smsl-n100 volumio[798]: [132B blob data] Jul 08 20:24:50 smsl-n100 volumio[798]: retrying in 5 seconds, trial 0 Jul 08 20:24:50 smsl-n100 volumio[798]: info: Volumio Calling Home Jul 08 20:24:53 smsl-n100 dhcpcd[924]: eth0: carrier lost Jul 08 20:24:53 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jul 08 20:24:53 smsl-n100 dhcpcd[910]: eth0: carrier lost Jul 08 20:24:53 smsl-n100 ifplugd(eth0)[754]: Link beat lost. Jul 08 20:24:53 smsl-n100 dhcpcd[910]: eth0: deleting route to 169.254.0.0/16 Jul 08 20:24:53 smsl-n100 dhcpcd[910]: eth0: deleting default route Jul 08 20:24:53 smsl-n100 avahi-daemon[552]: Withdrawing address record for 169.254.205.206 on eth0. Jul 08 20:24:53 smsl-n100 avahi-daemon[552]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.205.206. Jul 08 20:24:53 smsl-n100 avahi-daemon[552]: Interface eth0.IPv4 no longer relevant for mDNS. Jul 08 20:24:53 smsl-n100 volumio[798]: info: Discovery: A device disappeared from network Jul 08 20:24:53 smsl-n100 volumio[798]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 08 20:24:53 smsl-n100 dhcpcd[924]: eth0: deleting route to 169.254.0.0/16 Jul 08 20:24:53 smsl-n100 dhcpcd[924]: eth0: deleting default route Jul 08 20:24:55 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:24:55 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:24:55 smsl-n100 ntpd[732]: Deleting interface #4 eth0, 169.254.205.206#123, interface stats: received=0, sent=0, dropped=0, active_time=9 secs Jul 08 20:24:57 smsl-n100 volumio[1174]: .......................................................................................................++++ Jul 08 20:24:57 smsl-n100 volumio[1174]: e is 65537 (0x010001) Jul 08 20:24:57 smsl-n100 volumio[1174]: writing RSA key Jul 08 20:24:57 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 08 20:24:57 smsl-n100 dhcpcd[910]: eth0: carrier acquired Jul 08 20:24:57 smsl-n100 dhcpcd[924]: eth0: carrier acquired Jul 08 20:24:57 smsl-n100 dhcpcd[910]: eth0: IAID aa:93:37:64 Jul 08 20:24:57 smsl-n100 dhcpcd[924]: eth0: IAID aa:93:37:64 Jul 08 20:24:57 smsl-n100 ifplugd(eth0)[754]: Link beat detected. Jul 08 20:24:58 smsl-n100 dhcpcd[924]: eth0: soliciting an IPv6 router Jul 08 20:24:58 smsl-n100 dhcpcd[910]: eth0: soliciting an IPv6 router Jul 08 20:24:58 smsl-n100 dhcpcd[924]: eth0: soliciting a DHCP lease Jul 08 20:24:58 smsl-n100 dhcpcd[924]: eth0: offered 192.168.1.80 from 192.168.1.254 Jul 08 20:24:58 smsl-n100 dhcpcd[924]: eth0: probing address 192.168.1.80/24 Jul 08 20:24:58 smsl-n100 dhcpcd[910]: eth0: soliciting a DHCP lease Jul 08 20:24:58 smsl-n100 dhcpcd[910]: eth0: offered 192.168.1.80 from 192.168.1.254 Jul 08 20:24:58 smsl-n100 dhcpcd[910]: eth0: probing address 192.168.1.80/24 Jul 08 20:25:00 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:25:00 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Date not found in response Jul 08 20:25:00 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 20:25:00 smsl-n100 volumio[798]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jul 08 20:25:00 smsl-n100 volumio[798]: info: Reporting MCU Network Status: 0 Jul 08 20:25:00 smsl-n100 volumio[798]: info: Volumio Network Manager: Network status updated: 0 Jul 08 20:25:00 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:25:00 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:25:00 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:25:00 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:25:00 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:25:00 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:25:00 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:25:00 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 0, message: undefined Jul 08 20:25:03 smsl-n100 dhcpcd[910]: eth0: leased 192.168.1.80 for 120 seconds Jul 08 20:25:03 smsl-n100 dhcpcd[910]: eth0: adding route to 192.168.1.0/24 Jul 08 20:25:03 smsl-n100 avahi-daemon[552]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.80. Jul 08 20:25:03 smsl-n100 dhcpcd[910]: eth0: adding default route via 192.168.1.254 Jul 08 20:25:03 smsl-n100 avahi-daemon[552]: New relevant interface eth0.IPv4 for mDNS. Jul 08 20:25:03 smsl-n100 avahi-daemon[552]: Registering new address record for 192.168.1.80 on eth0.IPv4. Jul 08 20:25:03 smsl-n100 dhcpcd[924]: eth0: leased 192.168.1.80 for 120 seconds Jul 08 20:25:03 smsl-n100 dhcpcd[924]: eth0: adding route to 192.168.1.0/24 Jul 08 20:25:03 smsl-n100 dhcpcd[924]: eth0: adding default route via 192.168.1.254 Jul 08 20:25:04 smsl-n100 ntpd[732]: ntpd exiting on signal 15 (Terminated) Jul 08 20:25:04 smsl-n100 systemd[1]: Stopping Network Time Service... Jul 08 20:25:04 smsl-n100 systemd[1]: ntp.service: Succeeded. Jul 08 20:25:04 smsl-n100 systemd[1]: Stopped Network Time Service. Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:25:04 smsl-n100 systemd[1]: Starting Network Time Service... Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:25:04 smsl-n100 ntpd[1619]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 08 20:25:04 smsl-n100 ntpd[1619]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 08 20:25:04 smsl-n100 systemd[1]: Started Network Time Service. Jul 08 20:25:04 smsl-n100 ntpd[1627]: proto: precision = 1.166 usec (-20) Jul 08 20:25:04 smsl-n100 ntpd[1627]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 08 20:25:04 smsl-n100 ntpd[1627]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 08 20:25:04 smsl-n100 ntpd[1627]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 924 days ago Jul 08 20:25:04 smsl-n100 ntpd[1627]: Listen and drop on 0 v6wildcard [::]:123 Jul 08 20:25:04 smsl-n100 ntpd[1627]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 08 20:25:04 smsl-n100 ntpd[1627]: Listen normally on 2 lo 127.0.0.1:123 Jul 08 20:25:04 smsl-n100 ntpd[1627]: Listen normally on 3 eth0 192.168.1.80:123 Jul 08 20:25:04 smsl-n100 ntpd[1627]: Listening on routing socket on fd #20 for interface updates Jul 08 20:25:04 smsl-n100 ntpd[1627]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 08 20:25:04 smsl-n100 ntpd[1627]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 08 20:25:04 smsl-n100 ntpd[1627]: ntpd exiting on signal 15 (Terminated) Jul 08 20:25:04 smsl-n100 systemd[1]: Stopping Network Time Service... Jul 08 20:25:04 smsl-n100 systemd[1]: ntp.service: Succeeded. Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:25:04 smsl-n100 systemd[1]: Stopped Network Time Service. Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 08 20:25:04 smsl-n100 systemd[1]: Starting Network Time Service... Jul 08 20:25:04 smsl-n100 volumio[798]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 20:25:04 smsl-n100 ntpd[1634]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 08 20:25:04 smsl-n100 ntpd[1634]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 08 20:25:04 smsl-n100 systemd[1]: Started Network Time Service. Jul 08 20:25:04 smsl-n100 ntpd[1640]: proto: precision = 1.166 usec (-20) Jul 08 20:25:04 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 08 20:25:04 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 08 20:25:04 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 924 days ago Jul 08 20:25:04 smsl-n100 ntpd[1640]: Listen and drop on 0 v6wildcard [::]:123 Jul 08 20:25:04 smsl-n100 ntpd[1640]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 08 20:25:04 smsl-n100 ntpd[1640]: Listen normally on 2 lo 127.0.0.1:123 Jul 08 20:25:04 smsl-n100 ntpd[1640]: Listen normally on 3 eth0 192.168.1.80:123 Jul 08 20:25:04 smsl-n100 ntpd[1640]: Listening on routing socket on fd #20 for interface updates Jul 08 20:25:04 smsl-n100 ntpd[1640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 08 20:25:04 smsl-n100 ntpd[1640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 08 20:25:04 smsl-n100 volumio[798]: error: MyVolumio Plugin failed to start in a timely fashion Jul 08 20:25:04 smsl-n100 volumio[798]: [Metrics] CommandRouter: 59s 241.38ms Jul 08 20:25:04 smsl-n100 volumio[798]: info: CoreCommandRouter::volumiosetStartupVolume Jul 08 20:25:04 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 20:25:04 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:25:04 smsl-n100 volumio[798]: info: CoreCommandRouter::Close All Modals sent Jul 08 20:25:04 smsl-n100 volumio[798]: info: CoreCommandRouter::Close All Modals sent Jul 08 20:25:04 smsl-n100 volumio[798]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 08 20:25:04 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:25:04 smsl-n100 volumio[798]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jul 08 20:25:04 smsl-n100 volumio[798]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Jul 08 20:25:04 smsl-n100 volumio[798]: aplay: main:828: audio open error: No such device Jul 08 20:25:05 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Fetching time from Volumio... Jul 08 20:25:05 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 08 20:25:05 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 20:25:05 smsl-n100 volumio[798]: xcb_connection_has_error() returned true Jul 08 20:25:05 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 08 20:25:05 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 08 20:25:05 smsl-n100 volumio[798]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=314fb6dd9ac81686d54781b92cbbb659" http://updates.volumio.org/downloader-v1/track-device Jul 08 20:25:05 smsl-n100 volumio[798]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 08 20:25:05 smsl-n100 volumio[798]: Dload Upload Total Spent Left Speed Jul 08 20:25:05 smsl-n100 volumio[798]: [527B blob data] Jul 08 20:25:05 smsl-n100 volumio[798]: retrying in 5 seconds, trial 1 Jul 08 20:25:05 smsl-n100 volumio[798]: info: Volumio Calling Home Jul 08 20:25:09 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:25:09 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 20:25:09 smsl-n100 volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 20:25:10 smsl-n100 volumio[798]: info: Reporting MCU Network Status: 1 Jul 08 20:25:10 smsl-n100 volumio[798]: info: Volumio Network Manager: Network status updated: 1 Jul 08 20:25:10 smsl-n100 volumio[798]: info: CoreStateMachine::pushState Jul 08 20:25:10 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:25:10 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioPushState Jul 08 20:25:10 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:25:10 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 08 20:25:10 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 100, message: undefined Jul 08 20:25:10 smsl-n100 volumio[798]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 20:25:10 smsl-n100 volumio[798]: error: Serial API: Failed to decode command: 1, message: undefined Jul 08 20:25:11 smsl-n100 volumio[798]: info: BOOT COMPLETED Jul 08 20:25:13 smsl-n100 volumio[798]: info: Discovery: adding af44a9ff-0482-411e-a669-10597b4fa5a3 Jul 08 20:25:13 smsl-n100 volumio[798]: info: Discovery: Found device SMSL N100 Jul 08 20:25:13 smsl-n100 volumio[798]: info: CoreCommandRouter::volumioGetState Jul 08 20:25:13 smsl-n100 volumio[798]: info: CorePlayQueue::getTrack 0 Jul 08 20:25:13 smsl-n100 volumio[798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:25:13 smsl-n100 volumio[798]: Error: The value NaN is not a number Jul 08 20:25:13 smsl-n100 volumio[798]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 08 20:25:13 smsl-n100 volumio[798]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 08 20:25:13 smsl-n100 volumio[798]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 08 20:25:13 smsl-n100 volumio[798]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 08 20:25:13 smsl-n100 volumio[798]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 08 20:25:13 smsl-n100 volumio[798]: at Browser.emit (events.js:400:28) Jul 08 20:25:13 smsl-n100 volumio[798]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 08 20:25:13 smsl-n100 volumio[798]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 08 20:25:13 smsl-n100 volumio[798]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 08 20:25:13 smsl-n100 volumio[798]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 08 20:25:13 smsl-n100 volumio[798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:25:14 smsl-n100 ntpd[1640]: Soliciting pool server 94.228.114.152 Jul 08 20:25:14 smsl-n100 ntpd[1640]: Soliciting pool server 217.170.87.229 Jul 08 20:25:14 smsl-n100 ntpd[1640]: Soliciting pool server 195.90.182.235 Jul 08 20:25:14 smsl-n100 ntpd[1640]: Soliciting pool server 151.0.2.54 Jul 08 20:25:14 smsl-n100 volumio-time-update[553]: volumio-time-update-util: Setting system time to: 2025-07-09 17:54:52 Jul 08 20:25:14 smsl-n100 sudo[1681]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-09 17:54:52 Jul 08 20:25:14 smsl-n100 sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:25:14 smsl-n100 sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 20:24 Jul 08 20:25:14 smsl-n100 sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 20:25:14 smsl-n100 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=1684 comm="timedatectl set-time 2025-07-09 17:54:52 ") Jul 08 20:25:14 smsl-n100 systemd[1]: Starting Time & Date Service... 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"