-- Logs begin at Tue 2019-09-03 17:53:55 UTC, end at Wed 2025-04-30 13:54:27 UTC. -- Apr 30 13:54:06 volumio systemd[1]: Time has been changed Apr 30 13:54:07 volumio nmbd[4967]: [2025/04/30 13:54:07.585995, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 30 13:54:07 volumio nmbd[4967]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.222 for name WORKGROUP<1d>. Apr 30 13:54:07 volumio nmbd[4967]: This response was from IP 192.168.1.1, reporting an IP address of 192.168.1.1. Apr 30 13:54:07 volumio winbindd[4984]: [2025/04/30 13:54:07.622983, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 30 13:54:07 volumio winbindd[4984]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 30 13:54:07 volumio winbindd[5010]: [2025/04/30 13:54:07.626436, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio winbindd[5010]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 30 13:54:07 volumio dnsmasq[5012]: started, version 2.72 cachesize 150 Apr 30 13:54:07 volumio dnsmasq[5012]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Apr 30 13:54:07 volumio systemd[1]: Started dnsmasq.service. Apr 30 13:54:07 volumio dnsmasq[5012]: reading /etc/resolv.conf Apr 30 13:54:07 volumio dnsmasq[5012]: using nameserver 192.168.1.1#53 Apr 30 13:54:07 volumio dnsmasq[5012]: using nameserver 208.67.222.222#53 Apr 30 13:54:07 volumio dnsmasq[5012]: using nameserver 208.67.220.220#53 Apr 30 13:54:07 volumio dnsmasq[5012]: read /etc/hosts - 4 addresses Apr 30 13:54:07 volumio systemd[1]: Starting Host and Network Name Lookups. Apr 30 13:54:07 volumio systemd[1]: Reached target Host and Network Name Lookups. Apr 30 13:54:07 volumio smbd[4969]: Starting SMB/CIFS daemon: smbd[2025/04/30 13:54:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[4969]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07, 0] ../lib/util/util.c:220(directory_create_or_exist) Apr 30 13:54:07 volumio smbd[4969]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Apr 30 13:54:07 volumio smbd[4969]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Apr 30 13:54:07 volumio smbd[4969]: Unable to setup corepath for smbd: No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[4969]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07, 0] ../source3/smbd/server.c:1241(main) Apr 30 13:54:07 volumio smbd[4969]: smbd version 4.2.14-Debian started. Apr 30 13:54:07 volumio smbd[4969]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 30 13:54:07 volumio smbd[5000]: [2025/04/30 13:54:07.720031, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[5000]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[5000]: [2025/04/30 13:54:07.721313, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[5000]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[5000]: [2025/04/30 13:54:07.721501, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[5000]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07.720031, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[4969]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07.721313, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[4969]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: [2025/04/30 13:54:07.721501, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:07 volumio smbd[4969]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 30 13:54:07 volumio smbd[4969]: . Apr 30 13:54:07 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Apr 30 13:54:07 volumio systemd[1]: Starting Multi-User System. Apr 30 13:54:07 volumio systemd[1]: Reached target Multi-User System. Apr 30 13:54:07 volumio systemd[1]: Starting Graphical Interface. Apr 30 13:54:07 volumio systemd[1]: Reached target Graphical Interface. Apr 30 13:54:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 30 13:54:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 30 13:54:08 volumio winbindd[5031]: [2025/04/30 13:54:08.721944, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 30 13:54:08 volumio winbindd[5031]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Apr 30 13:54:08 volumio firststart.sh[4991]: Creating /var/log/samba folder Apr 30 13:54:08 volumio firststart.sh[4991]: Removing default SSH host keys Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_dsa_key' Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_dsa_key.pub' Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_ecdsa_key' Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_rsa_key' Apr 30 13:54:08 volumio firststart.sh[4991]: removed '/etc/ssh/ssh_host_rsa_key.pub' Apr 30 13:54:08 volumio firststart.sh[4991]: Generating SSH host keys Apr 30 13:54:08 volumio hotspot[4986]: basename: missing operand Apr 30 13:54:08 volumio hotspot[4986]: Try 'basename --help' for more information. Apr 30 13:54:08 volumio hotspot[4986]: Launching Ordinary Hostapd Apr 30 13:54:08 volumio dhcpd[4987]: Internet Systems Consortium DHCP Server 4.3.1 Apr 30 13:54:08 volumio dhcpd[4987]: Copyright 2004-2014 Internet Systems Consortium. Apr 30 13:54:08 volumio dhcpd[4987]: All rights reserved. Apr 30 13:54:08 volumio dhcpd[4987]: For info, please visit https://www.isc.org/software/dhcp/ Apr 30 13:54:08 volumio dhcpd[4987]: Config file: /etc/dhcp/dhcpd.conf Apr 30 13:54:08 volumio dhcpd[4987]: Database file: /var/lib/dhcp/dhcpd.leases Apr 30 13:54:08 volumio dhcpd[4987]: PID file: /var/run/dhcpd.pid Apr 30 13:54:08 volumio hotspot[4987]: Internet Systems Consortium DHCP Server 4.3.1 Apr 30 13:54:08 volumio hotspot[4987]: Copyright 2004-2014 Internet Systems Consortium. Apr 30 13:54:08 volumio hotspot[4987]: All rights reserved. Apr 30 13:54:08 volumio hotspot[4987]: For info, please visit https://www.isc.org/software/dhcp/ Apr 30 13:54:08 volumio hotspot[4987]: Config file: /etc/dhcp/dhcpd.conf Apr 30 13:54:08 volumio hotspot[4987]: Database file: /var/lib/dhcp/dhcpd.leases Apr 30 13:54:08 volumio hotspot[4987]: PID file: /var/run/dhcpd.pid Apr 30 13:54:08 volumio dhcpd[4987]: Internet Systems Consortium DHCP Server 4.3.1 Apr 30 13:54:08 volumio dhcpd[4987]: Copyright 2004-2014 Internet Systems Consortium. Apr 30 13:54:08 volumio dhcpd[4987]: All rights reserved. Apr 30 13:54:08 volumio dhcpd[4987]: For info, please visit https://www.isc.org/software/dhcp/ Apr 30 13:54:08 volumio dhcpd[4987]: Wrote 0 leases to leases file. Apr 30 13:54:08 volumio hotspot[4987]: Wrote 0 leases to leases file. Apr 30 13:54:09 volumio dhcpd[4987]: Listening on LPF/wlan0/38:a2:8c:21:51:be/192.168.211.0/24 Apr 30 13:54:09 volumio dhcpd[4987]: Sending on LPF/wlan0/38:a2:8c:21:51:be/192.168.211.0/24 Apr 30 13:54:09 volumio dhcpd[4987]: Apr 30 13:54:09 volumio dhcpd[4987]: No subnet declaration for eth0 (192.168.1.222). Apr 30 13:54:09 volumio dhcpd[4987]: ** Ignoring requests on eth0. If this is not what Apr 30 13:54:09 volumio dhcpd[4987]: you want, please write a subnet declaration Apr 30 13:54:09 volumio dhcpd[4987]: in your dhcpd.conf file for the network segment Apr 30 13:54:09 volumio dhcpd[4987]: to which interface eth0 is attached. ** Apr 30 13:54:09 volumio dhcpd[4987]: Apr 30 13:54:09 volumio dhcpd[4987]: Sending on Socket/fallback/fallback-net Apr 30 13:54:09 volumio dhcpd[5037]: Server starting service. Apr 30 13:54:09 volumio systemd[1]: Started dhcpd.service. Apr 30 13:54:09 volumio systemd[1]: Startup finished in 20.572s (kernel) + 1min 14.633s (userspace) = 1min 35.205s. Apr 30 13:54:09 volumio hotspot[4987]: Listening on LPF/wlan0/38:a2:8c:21:51:be/192.168.211.0/24 Apr 30 13:54:09 volumio hotspot[4987]: Sending on LPF/wlan0/38:a2:8c:21:51:be/192.168.211.0/24 Apr 30 13:54:09 volumio hotspot[4987]: No subnet declaration for eth0 (192.168.1.222). Apr 30 13:54:09 volumio hotspot[4987]: ** Ignoring requests on eth0. If this is not what Apr 30 13:54:09 volumio hotspot[4987]: you want, please write a subnet declaration Apr 30 13:54:09 volumio hotspot[4987]: in your dhcpd.conf file for the network segment Apr 30 13:54:09 volumio hotspot[4987]: to which interface eth0 is attached. ** Apr 30 13:54:09 volumio hotspot[4987]: Sending on Socket/fallback/fallback-net Apr 30 13:54:09 volumio smbd[5014]: [2025/04/30 13:54:09.823900, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 30 13:54:09 volumio smbd[5014]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 30 13:54:09 volumio kernel: RTL871X: assoc success Apr 30 13:54:09 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Apr 30 13:54:09 volumio kernel: RTL871X: set group key camid:1, addr:00:00:00:00:00:00, kid:1, type:AES Apr 30 13:54:09 volumio volumio-streaming-daemon[4997]: ############################ Apr 30 13:54:09 volumio volumio-streaming-daemon[4997]: # Volumio Streaming Daemon # Apr 30 13:54:09 volumio volumio-streaming-daemon[4997]: # Running on port 7777 # Apr 30 13:54:09 volumio volumio-streaming-daemon[4997]: ############################ Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: unable to initialize frontend: Dialog Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: (TERM is not set, so the dialog frontend is not usable.) Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: falling back to frontend: Readline Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: unable to initialize frontend: Readline Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: (This frontend requires a controlling tty.) Apr 30 13:54:10 volumio firststart.sh[4991]: debconf: falling back to frontend: Teletype Apr 30 13:54:11 volumio firststart.sh[4991]: debconf: warning: possible database corruption. Will attempt to repair by adding back missing question openssh-server/permit-root-login. Apr 30 13:54:11 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:11 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026d13b48; stop 0, status 4 Apr 30 13:54:11 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:11 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:11 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2001, virqc:bfff Apr 30 13:54:11 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdbb18; stop 0, status 4 Apr 30 13:54:11 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:11 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:11 volumio kernel: RTL871X: nolinked power save enter Apr 30 13:54:13 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:13 volumio volumio[4990]: info: ----- Volumio2 ---- Apr 30 13:54:13 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:13 volumio volumio[4990]: info: ----- System startup ---- Apr 30 13:54:13 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:14 volumio volumio[4990]: info: MYVOLUMIO Environment detected Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/airplay_emulation. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/inputs. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/mpd. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/webradio. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/example_plugin. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/last_100. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin music_service/upnp_browser. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin audio_interface/outputs. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin audio_interface/upnp. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin audio_interface/alsa_controller. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin miscellanea/alarm-clock. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin miscellanea/albumart. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin miscellanea/appearance. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin miscellanea/wizard. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin miscellanea/my_music. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin user_interface/websocket. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin user_interface/mpdemulation. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin user_interface/rest_api. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/updater_comm. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/network. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/networkfs. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/services. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/system. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/i2s_dacs. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/volumiodiscovery. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it Apr 30 13:54:14 volumio volumio[4990]: info: Plugin folders cleanup Apr 30 13:54:14 volumio volumio[4990]: info: Scanning into folder /volumio/app/plugins/ Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category audio_interface Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category miscellanea Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category music_service Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category plugins.json Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category system_controller Apr 30 13:54:14 volumio volumio[4990]: info: Scanning category user_interface Apr 30 13:54:14 volumio volumio[4990]: info: Scanning into folder /data/plugins/ Apr 30 13:54:14 volumio volumio[4990]: info: Plugin folders cleanup completed Apr 30 13:54:14 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:14 volumio volumio[4990]: info: ----- Core plugins startup ---- Apr 30 13:54:14 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:14 volumio volumio[4990]: info: Loading plugins from folder /volumio/app/plugins/ Apr 30 13:54:14 volumio volumio[4990]: info: Adding plugin upnp to MyMusic Plugins Apr 30 13:54:14 volumio volumio[4990]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 30 13:54:14 volumio volumio[4990]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 30 13:54:14 volumio volumio[4990]: info: Loading plugins from folder /data/plugins/ Apr 30 13:54:14 volumio volumio[4990]: info: Loading plugin "system"... Apr 30 13:54:14 volumio volumio[4990]: No id defined. Creating one Apr 30 13:54:14 volumio volumio[4990]: info: Loading plugin "appearance"... Apr 30 13:54:14 volumio firststart.sh[4991]: Creating SSH2 RSA key; this may take some time ... Apr 30 13:54:14 volumio firststart.sh[4991]: 2048 2c:ff:e5:ab:44:27:b9:47:53:f7:87:1c:2c:5c:e7:6c /etc/ssh/ssh_host_rsa_key.pub (RSA) Apr 30 13:54:14 volumio volumio-remote-updater[4604]: [2025-04-30 13:54:14] [connect] Successful connection Apr 30 13:54:15 volumio firststart.sh[4991]: Creating SSH2 DSA key; this may take some time ... Apr 30 13:54:15 volumio firststart.sh[4991]: 1024 24:c0:58:a6:4a:3b:57:bf:32:12:cf:50:9f:b1:fa:18 /etc/ssh/ssh_host_dsa_key.pub (DSA) Apr 30 13:54:15 volumio firststart.sh[4991]: Creating SSH2 ECDSA key; this may take some time ... Apr 30 13:54:15 volumio firststart.sh[4991]: 256 55:4f:de:12:e3:33:32:74:af:26:7e:02:7d:7b:2e:7d /etc/ssh/ssh_host_ecdsa_key.pub (ECDSA) Apr 30 13:54:15 volumio volumio[4990]: info: Loading plugin "network"... Apr 30 13:54:15 volumio kernel: [aml_sd_emmc_irq] sdio: desc_timeout,vstat:0xa1ff3000,virqc:bfff Apr 30 13:54:15 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:15 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:15 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:15 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:15 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:15 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:15 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:15 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:15 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:15 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1ff2100, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb978; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "services"... Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "alsa_controller"... Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] sdio: warning... data crc, vstat:0xa1fd2003, virqc:bfff Apr 30 13:54:16 volumio kernel: [aml_sd_emmc_irq] @ cmd 53 with ffffffc026cdb998; stop 0, status 4 Apr 30 13:54:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 30 13:54:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:53 Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 30 13:54:16 volumio volumio[4990]: info: Setting mixer Audio i2s mute for card Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "wizard"... Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "volumio_command_line_client"... Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "upnp"... Apr 30 13:54:16 volumio volumio[4990]: info: [1746021256540] Starting Upmpd Daemon Apr 30 13:54:16 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "my_music"... Apr 30 13:54:16 volumio volumio[4990]: info: Loading plugin "mpd"... Apr 30 13:54:16 volumio kernel: ==> rtl8188e_iol_efuse_patch Apr 30 13:54:16 volumio kernel: RTL871X: nolinked power save leave Apr 30 13:54:16 volumio kernel: RTL871X: set group key camid:1, addr:00:00:00:00:00:00, kid:1, type:AES Apr 30 13:54:17 volumio volumio[4990]: info: Loading plugin "upnp_browser"... Apr 30 13:54:17 volumio firststart.sh[4991]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Apr 30 13:54:17 volumio firststart.sh[4991]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Apr 30 13:54:17 volumio systemd[1]: Reloading. Apr 30 13:54:17 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Apr 30 13:54:17 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Apr 30 13:54:17 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Apr 30 13:54:17 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Apr 30 13:54:17 volumio firststart.sh[4991]: Disabling firststart service Apr 30 13:54:17 volumio firststart.sh[4991]: Removed symlink /etc/systemd/system/multi-user.target.wants/firststart.service. Apr 30 13:54:17 volumio systemd[1]: Reloading. Apr 30 13:54:18 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Apr 30 13:54:18 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Apr 30 13:54:18 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Apr 30 13:54:18 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Apr 30 13:54:18 volumio firststart.sh[4991]: Finalizing Apr 30 13:54:23 volumio volumio[4990]: info: Loading plugin "networkfs"... Apr 30 13:54:23 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 13:54:23 volumio volumio[4990]: info: Loading plugin "alarm-clock"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "airplay_emulation"... Apr 30 13:54:24 volumio volumio[4990]: info: Starting Shairport Sync Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "last_100"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "webradio"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "i2s_dacs"... Apr 30 13:54:24 volumio volumio[4990]: info: I2S DAC not set, start Auto-detection Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "volumiodiscovery"... Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 30 13:54:24 volumio node[4990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 30 13:54:24 volumio node[4990]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 13:54:24 volumio node[4990]: *** WARNING *** For more information see Apr 30 13:54:24 volumio node[4990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 30 13:54:24 volumio node[4990]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 13:54:24 volumio node[4990]: *** WARNING *** For more information see Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** For more information see Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 13:54:24 volumio volumio[4990]: *** WARNING *** For more information see Apr 30 13:54:24 volumio volumio[4990]: Discovery: StartAdv! undefined Apr 30 13:54:24 volumio volumio[4990]: Discovery: Started advertising... undefined - undefined Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "outputs"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "albumart"... Apr 30 13:54:24 volumio volumio[4990]: info: Plugin example_plugin is not enabled Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "inputs"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "updater_comm"... Apr 30 13:54:24 volumio volumio[4990]: info: Plugin mpdemulation is not enabled Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "rest_api"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "websocket"... Apr 30 13:54:24 volumio volumio[4990]: info: ___________ START PLUGINS ___________ Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 13:54:24 volumio volumio[4990]: info: [1746021264804] CoreMusicLibrary::Adding element Media Servers Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 13:54:24 volumio volumio[4990]: Forking 3 albumart workers Apr 30 13:54:24 volumio volumio[4990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:54:24 volumio volumio[4990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 13:54:24 volumio volumio[4990]: info: [1746021264895] CoreMusicLibrary::Adding element Last_100 Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 13:54:24 volumio volumio[4990]: info: [1746021264899] CoreMusicLibrary::Adding element Webradio Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 13:54:24 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 13:54:24 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:24 volumio volumio[4990]: info: ----- MyVolumio plugins startup ---- Apr 30 13:54:24 volumio volumio[4990]: info: ------------------------------------------- Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugins from folder /myvolumio/plugins Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugins from folder /data/myvolumio/plugins Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "cd_controller"... Apr 30 13:54:24 volumio volumio[4990]: info: Loading plugin "streaming_services"... Apr 30 13:54:25 volumio volumio[4990]: info: Loading plugin "my_volumio"... Apr 30 13:54:25 volumio volumio[4990]: Starting albumart workers Apr 30 13:54:25 volumio volumio[4990]: Starting albumart workers Apr 30 13:54:25 volumio volumio[4990]: Starting albumart workers Apr 30 13:54:26 volumio volumio[4990]: info: Adding CD REST API Endpoints Apr 30 13:54:26 volumio volumio[4990]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Apr 30 13:54:26 volumio volumio[4990]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Apr 30 13:54:26 volumio volumio[4990]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Apr 30 13:54:26 volumio volumio[4990]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 30 13:54:26 volumio volumio[4990]: info: Detecting CD presence Apr 30 13:54:26 volumio volumio[4990]: info: Streaming services startup Apr 30 13:54:26 volumio volumio[4990]: info: Starting Streaming Daemon Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: Starting MyVolumio Remote Streaming Endpoints Apr 30 13:54:26 volumio sudo[5237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 30 13:54:26 volumio sudo[5237]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:54:26 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 30 13:54:26 volumio volumio[4990]: info: Loading i18n strings for locale undefined Apr 30 13:54:26 volumio volumio[4990]: error: Failed to load i18n strings for locale undefined: Error: /volumio/app/i18n/strings_undefined.json: ENOENT: no such file or directory, open '/volumio/app/i18n/strings_undefined.json' Apr 30 13:54:26 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 30 13:54:26 volumio volumio[4990]: Updating browse sources language Apr 30 13:54:26 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 30 13:54:26 volumio volumio[4990]: Cannot find translation for sourceMedia Servers Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 13:54:26 volumio sudo[5237]: pam_unix(sudo:session): session closed for user root Apr 30 13:54:26 volumio volumio-streaming-daemon[5240]: ############################ Apr 30 13:54:26 volumio volumio-streaming-daemon[5240]: # Volumio Streaming Daemon # Apr 30 13:54:26 volumio volumio-streaming-daemon[5240]: # Running on port 7777 # Apr 30 13:54:26 volumio volumio-streaming-daemon[5240]: ############################ Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::initPlayerControls Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: BOOT COMPLETED Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::Close All Modals sent Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::Close All Modals sent Apr 30 13:54:26 volumio volumio[4990]: Express server listening on port 3000 Apr 30 13:54:26 volumio volumio[4990]: Volumio Calling Home Apr 30 13:54:26 volumio volumio[4990]: info: MyVolumio not started Apr 30 13:54:26 volumio volumio[4990]: info: Initializing device activation check Apr 30 13:54:26 volumio volumio[4990]: info: CoreStateMachine::resetVolumioState Apr 30 13:54:26 volumio volumio[4990]: info: CoreStateMachine::getcurrentVolume Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::volumioRetrievevolume Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 30 13:54:26 volumio volumio[4990]: info: CoreStateMachine::getcurrentVolume Apr 30 13:54:26 volumio volumio[4990]: info: CoreCommandRouter::volumioRetrievevolume Apr 30 13:54:26 volumio volumio[4990]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 30 13:54:26 volumio volumio[4990]: info: Cannot read play queue from file Apr 30 13:54:26 volumio volumio[4990]: info: Setting Device type: Amlogic Apr 30 13:54:26 volumio volumio[4990]: info: MPD running with PID4713 ,establishing connection Apr 30 13:54:26 volumio sudo[5264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 30 13:54:26 volumio sudo[5264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:54:26 volumio volumio[4990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:54:26 volumio sudo[5264]: pam_unix(sudo:session): session closed for user root Apr 30 13:54:26 volumio volumio[4990]: TypeError: Cannot read property 'charAt' of undefined Apr 30 13:54:26 volumio volumio[4990]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Apr 30 13:54:26 volumio volumio[4990]: at ontimeout (timers.js:482:11) Apr 30 13:54:26 volumio volumio[4990]: at tryOnTimeout (timers.js:317:5) Apr 30 13:54:26 volumio volumio[4990]: at Timer.listOnTimeout (timers.js:277:5) Apr 30 13:54:26 volumio volumio[4990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:54:27 volumio sudo[5274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 13:53 Apr 30 13:54:27 volumio sudo[5274]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7434a810e3a57a07e8ee53a8f2063b5b15d2425c" VOLUMIO_FE_VERSION="8d42aa2ed20c7c37b42519af63b83b0cbcd3af68" VOLUMIO_BE_VERSION="fd4f91711c718295fa83ff62d1b2772d1cb50601" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 3 19:53:55 CEST 2019" VOLUMIO_VERSION="2.603" VOLUMIO_HARDWARE="vim1" VOLUMIO_HASH="b2272113b215eebd4e2ea68b15bca646"