-- Logs begin at Fri 2025-03-07 16:07:48 CST, end at Fri 2025-03-07 16:09:32 CST. -- Mar 07 16:08:00 volumio ntpd[763]: Soliciting pool server 172.105.240.243 Mar 07 16:08:00 volumio ntpd[763]: Soliciting pool server 81.16.177.123 Mar 07 16:08:00 volumio ntpd[763]: Soliciting pool server 193.182.111.14 Mar 07 16:08:01 volumio ntpd[763]: Soliciting pool server 162.159.200.123 Mar 07 16:08:01 volumio ntpd[763]: Soliciting pool server 84.16.67.12 Mar 07 16:08:01 volumio ntpd[763]: Soliciting pool server 45.76.221.157 Mar 07 16:08:01 volumio ntpd[763]: Soliciting pool server 139.199.215.251 Mar 07 16:08:02 volumio ntpd[763]: Soliciting pool server 193.182.111.143 Mar 07 16:08:02 volumio ntpd[763]: Soliciting pool server 5.79.108.34 Mar 07 16:08:02 volumio ntpd[763]: Soliciting pool server 78.46.102.180 Mar 07 16:08:03 volumio sudo[664]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:03 volumio wireless.js[374]: SETTING APPROPRIATE REG DOMAIN: CN Mar 07 16:08:03 volumio sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 16:08:03 volumio sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:03 volumio sudo[779]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:03 volumio sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Mar 07 16:08:03 volumio sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:03 volumio sudo[781]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:03 volumio wireless.js[374]: SUCCESSFULLY SET NEW REGDOMAIN: CN Mar 07 16:08:03 volumio sudo[788]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 16:08:03 volumio sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:03 volumio sudo[788]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:03 volumio sudo[790]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 16:08:03 volumio sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:03 volumio ntpd[763]: Soliciting pool server 95.111.202.5 Mar 07 16:08:03 volumio ntpd[763]: Soliciting pool server 2a03:8600::aa Mar 07 16:08:03 volumio sudo[790]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:03 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:04 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:04 volumio avahi-daemon[371]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 16:08:04 volumio avahi-daemon[371]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 16:08:04 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 16:08:04 volumio hostapd[797]: Configuration file: /etc/hostapd/hostapd.conf Mar 07 16:08:04 volumio avahi-daemon[371]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 16:08:04 volumio avahi-daemon[371]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:04 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:04 volumio avahi-daemon[371]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 16:08:04 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:04 volumio avahi-daemon[371]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 16:08:04 volumio avahi-daemon[371]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 16:08:04 volumio hostapd[797]: Using interface wlan0 with hwaddr 90:84:0d:f4:ca:c2 and ssid "Volumio" Mar 07 16:08:04 volumio dhcpcd[442]: wlan0: carrier acquired Mar 07 16:08:04 volumio hostapd[797]: wlan0: interface state UNINITIALIZED->ENABLED Mar 07 16:08:04 volumio hostapd[797]: wlan0: AP-ENABLED Mar 07 16:08:04 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 16:08:04 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 16:08:04 volumio systemd[1]: wireless.service: Succeeded. Mar 07 16:08:04 volumio systemd[1]: Started Wireless Services. Mar 07 16:08:04 volumio systemd[1]: Started Volumio Backend Module. Mar 07 16:08:04 volumio dhcpcd[442]: wlan0: IAID 0d:f4:ca:c2 Mar 07 16:08:04 volumio systemd[1]: Started Start Volumio Kiosk. Mar 07 16:08:04 volumio dnsmasq[815]: dnsmasq: syntax check OK. Mar 07 16:08:04 volumio systemd[1]: Started First Start. Mar 07 16:08:04 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Mar 07 16:08:04 volumio systemd[1]: Reached target Multi-User System. Mar 07 16:08:04 volumio systemd[1]: Reached target Graphical Interface. Mar 07 16:08:04 volumio firststart.sh[820]: Volumio first start configuration script Mar 07 16:08:04 volumio firststart.sh[820]: Configuring unconfigured packages Mar 07 16:08:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 07 16:08:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 07 16:08:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 07 16:08:04 volumio startx[819]: xauth: file /root/.Xauthority does not exist Mar 07 16:08:04 volumio firststart.sh[820]: Creating /var/log/samba/cores folder Mar 07 16:08:04 volumio firststart.sh[820]: Removing default SSH host keys Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_ecdsa_key' Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_ed25519_key' Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_rsa_key' Mar 07 16:08:04 volumio firststart.sh[820]: removed '/etc/ssh/ssh_host_rsa_key.pub' Mar 07 16:08:04 volumio firststart.sh[820]: Generating SSH host keys Mar 07 16:08:04 volumio dnsmasq[872]: started, version 2.80 cachesize 150 Mar 07 16:08:04 volumio dnsmasq[872]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Mar 07 16:08:04 volumio dnsmasq-dhcp[872]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Mar 07 16:08:04 volumio dnsmasq[872]: reading /etc/resolv.conf Mar 07 16:08:04 volumio dnsmasq[872]: using nameserver 192.168.1.1#53 Mar 07 16:08:04 volumio dnsmasq[872]: using nameserver 192.168.0.1#53 Mar 07 16:08:04 volumio dnsmasq[872]: using nameserver 208.67.222.222#53 Mar 07 16:08:04 volumio dnsmasq[872]: using nameserver 208.67.220.220#53 Mar 07 16:08:04 volumio dnsmasq[872]: read /etc/hosts - 5 addresses Mar 07 16:08:04 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 16:08:04 volumio systemd[1]: Reached target Host and Network Name Lookups. Mar 07 16:08:04 volumio systemd[1]: Startup finished in 16.462s (kernel) + 16.800s (userspace) = 33.262s. Mar 07 16:08:04 volumio startx[819]: X.Org X Server 1.20.4 Mar 07 16:08:04 volumio startx[819]: X Protocol Version 11, Revision 0 Mar 07 16:08:04 volumio startx[819]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Mar 07 16:08:04 volumio startx[819]: Current Operating System: Linux volumio 5.10.165-volumio #1 SMP Tue Jan 31 19:07:10 CET 2023 x86_64 Mar 07 16:08:04 volumio startx[819]: Kernel command line: BOOT_IMAGE=/vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=85d1734a-effa-4bc9-b506-c65acf1b091d bootpart=UUID=8A50-F494 datapart=UUID=6bc03079-717f-4074-a5f4-d21d0595a2d8 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 Mar 07 16:08:04 volumio startx[819]: Build Date: 18 December 2021 09:05:36AM Mar 07 16:08:04 volumio startx[819]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Mar 07 16:08:04 volumio startx[819]: Current version of pixman: 0.36.0 Mar 07 16:08:04 volumio startx[819]: Before reporting problems, check http://wiki.x.org Mar 07 16:08:04 volumio startx[819]: to make sure that you have the latest version. Mar 07 16:08:04 volumio startx[819]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 07 16:08:04 volumio startx[819]: (++) from command line, (!!) notice, (II) informational, Mar 07 16:08:04 volumio startx[819]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 07 16:08:04 volumio startx[819]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Mar 7 08:08:04 2025 Mar 07 16:08:04 volumio startx[819]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 07 16:08:05 volumio volumio-remote-updater[372]: [2025-03-07 08:08:05] [info] asio async_connect error: system:111 (Connection refused) Mar 07 16:08:05 volumio volumio-remote-updater[372]: [2025-03-07 08:08:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 07 16:08:05 volumio volumio-remote-updater[372]: [2025-03-07 08:08:05] [error] handle_connect error: Underlying Transport Error Mar 07 16:08:05 volumio dhcpcd[442]: wlan0: soliciting a DHCP lease Mar 07 16:08:05 volumio dhcpcd[442]: wlan0: soliciting an IPv6 router Mar 07 16:08:05 volumio systemd[1]: soundcard-init.service: Succeeded. Mar 07 16:08:05 volumio firststart.sh[820]: debconf: unable to initialize frontend: Dialog Mar 07 16:08:05 volumio firststart.sh[820]: debconf: (TERM is not set, so the dialog frontend is not usable.) Mar 07 16:08:05 volumio firststart.sh[820]: debconf: falling back to frontend: Readline Mar 07 16:08:05 volumio firststart.sh[820]: debconf: unable to initialize frontend: Readline Mar 07 16:08:05 volumio firststart.sh[820]: debconf: (This frontend requires a controlling tty.) Mar 07 16:08:05 volumio firststart.sh[820]: debconf: falling back to frontend: Teletype Mar 07 16:08:06 volumio ntpd[763]: Listen normally on 4 wlan0 192.168.211.1:123 Mar 07 16:08:06 volumio ntpd[763]: new interface(s) found: waking up resolver Mar 07 16:08:06 volumio startx[819]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Mar 07 16:08:06 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:06 volumio volumio[817]: info: ----- Volumio3 ---- Mar 07 16:08:06 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:06 volumio volumio[817]: info: ----- System startup ---- Mar 07 16:08:06 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:06 volumio firststart.sh[820]: Creating SSH2 RSA key; this may take some time ... Mar 07 16:08:06 volumio firststart.sh[820]: 2048 SHA256:BFwPafk3KSoAWTkgphNA9T8JQ9dy7Dqgb57M2nbRvdE root@volumio (RSA) Mar 07 16:08:07 volumio firststart.sh[820]: Creating SSH2 DSA key; this may take some time ... Mar 07 16:08:07 volumio firststart.sh[820]: 1024 SHA256:BQ7d6l0lqu2hxKVJXz2tjXTP9hmzRnSlj9bm+VpBJn0 root@volumio (DSA) Mar 07 16:08:07 volumio firststart.sh[820]: Creating SSH2 ECDSA key; this may take some time ... Mar 07 16:08:07 volumio firststart.sh[820]: 256 SHA256:E8mCnUABuVWQbZUHViL0Wo9qzFom0d5AzvOOUtVfwso root@volumio (ECDSA) Mar 07 16:08:07 volumio volumio[817]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Mar 07 16:08:07 volumio volumio[817]: info: MYVOLUMIO Environment detected Mar 07 16:08:07 volumio volumio[817]: info: Plugin folders cleanup Mar 07 16:08:07 volumio volumio[817]: info: Scanning into folder /volumio/app/plugins/ Mar 07 16:08:07 volumio volumio[817]: info: Scanning category audio_interface Mar 07 16:08:07 volumio volumio[817]: info: Scanning category miscellanea Mar 07 16:08:07 volumio volumio[817]: info: Scanning category music_service Mar 07 16:08:07 volumio volumio[817]: info: Scanning category plugins.json Mar 07 16:08:07 volumio volumio[817]: info: Scanning category system_controller Mar 07 16:08:07 volumio volumio[817]: info: Scanning category user_interface Mar 07 16:08:07 volumio volumio[817]: info: Scanning into folder /data/plugins/ Mar 07 16:08:07 volumio volumio[817]: info: Plugin folders cleanup completed Mar 07 16:08:07 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:07 volumio volumio[817]: info: ----- Core plugins startup ---- Mar 07 16:08:07 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:07 volumio volumio[817]: info: Loading plugins from folder /volumio/app/plugins/ Mar 07 16:08:07 volumio volumio[817]: info: Adding plugin upnp to MyMusic Plugins Mar 07 16:08:07 volumio volumio[817]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 07 16:08:07 volumio volumio[817]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 07 16:08:07 volumio volumio[817]: info: Loading plugins from folder /data/plugins/ Mar 07 16:08:07 volumio volumio[817]: info: Loading plugin "system"... Mar 07 16:08:07 volumio volumio[817]: info: No id defined. Creating one Mar 07 16:08:07 volumio volumio[817]: info: Loading plugin "appearance"... Mar 07 16:08:08 volumio systemd[1]: Reloading. Mar 07 16:08:08 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 16:08:08 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "network"... Mar 07 16:08:09 volumio volumio[817]: info: Refreshing Cached IP Addresses Mar 07 16:08:09 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:08:09 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:09 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:09 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 07 16:08:09 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "services"... Mar 07 16:08:09 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "alsa_controller"... Mar 07 16:08:09 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:08:09 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:09 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:09 volumio firststart.sh[820]: rescue-ssh.target is a disabled or a static unit, not starting it. Mar 07 16:08:09 volumio volumio[817]: warn: The ALSA output card is not set, defaulting to card [object Object] Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 16:08:09 volumio volumio[817]: info: Setting mixer Master for card HDA NVidia Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 16:08:09 volumio firststart.sh[820]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Mar 07 16:08:09 volumio firststart.sh[820]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Mar 07 16:08:09 volumio systemd[1]: Reloading. Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "wizard"... Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "volumio_command_line_client"... Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "upnp"... Mar 07 16:08:09 volumio volumio[817]: info: [1741334889434] Starting Upmpd Daemon Mar 07 16:08:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "my_music"... Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "mpd"... Mar 07 16:08:09 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 16:08:09 volumio volumio[817]: info: Creating MPD Configuration file Mar 07 16:08:09 volumio volumio[817]: info: Loading plugin "upnp_browser"... Mar 07 16:08:09 volumio sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 16:08:09 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:09 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 16:08:09 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:09 volumio systemd[1]: Stopping Music Player Daemon... Mar 07 16:08:09 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:09 volumio firststart.sh[820]: Enabling SSH for first boot Mar 07 16:08:09 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 07 16:08:10 volumio sshd[1244]: Server listening on 0.0.0.0 port 22. Mar 07 16:08:10 volumio sshd[1244]: Server listening on :: port 22. Mar 07 16:08:10 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 07 16:08:10 volumio firststart.sh[820]: Disabling firststart service Mar 07 16:08:10 volumio firststart.sh[820]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Mar 07 16:08:10 volumio systemd[1]: Reloading. Mar 07 16:08:10 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 16:08:10 volumio volumio[817]: info: Loading plugin "networkfs"... Mar 07 16:08:10 volumio firststart.sh[820]: Finalizing Mar 07 16:08:10 volumio systemd[1]: mpd.service: Succeeded. Mar 07 16:08:10 volumio systemd[1]: Stopped Music Player Daemon. Mar 07 16:08:10 volumio systemd[1]: Starting Music Player Daemon... Mar 07 16:08:10 volumio systemd[1]: firststart.service: Succeeded. Mar 07 16:08:10 volumio dhcpcd[442]: wlan0: probing for an IPv4LL address Mar 07 16:08:10 volumio volumio[817]: info: Starting Udev Watcher for removable devices Mar 07 16:08:11 volumio volumio[817]: info: Ignoring mount for partition: boot Mar 07 16:08:11 volumio volumio[817]: info: Ignoring mount for partition: volumio Mar 07 16:08:11 volumio volumio[817]: info: Ignoring mount for partition: volumio_data Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "alarm-clock"... Mar 07 16:08:11 volumio mpd[1262]: Mar 07 08:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 07 16:08:11 volumio mpd[1262]: Mar 07 08:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 07 16:08:11 volumio mpd[1262]: Mar 07 08:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 07 16:08:11 volumio systemd[1]: Started Music Player Daemon. Mar 07 16:08:11 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "airplay_emulation"... Mar 07 16:08:11 volumio volumio[817]: info: Starting Shairport Sync Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "last_100"... Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "webradio"... Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "i2s_dacs"... Mar 07 16:08:11 volumio volumio[817]: info: I2S DAC not set, start Auto-detection Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "volumiodiscovery"... Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** For more information see Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 16:08:11 volumio volumio[817]: *** WARNING *** For more information see Mar 07 16:08:11 volumio node[817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 07 16:08:11 volumio node[817]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 16:08:11 volumio node[817]: *** WARNING *** For more information see Mar 07 16:08:11 volumio node[817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 07 16:08:11 volumio node[817]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 16:08:11 volumio node[817]: *** WARNING *** For more information see Mar 07 16:08:11 volumio volumio[817]: info: Discovery: Started advertising with name: Volumio Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "outputs"... Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "albumart"... Mar 07 16:08:11 volumio volumio[817]: info: Plugin example_plugin is not enabled Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "inputs"... Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "updater_comm"... Mar 07 16:08:11 volumio volumio[817]: info: Plugin mpdemulation is not enabled Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "rest_api"... Mar 07 16:08:11 volumio volumio[817]: info: Loading plugin "websocket"... Mar 07 16:08:11 volumio volumio[817]: info: Loading i18n strings for locale en Mar 07 16:08:11 volumio volumio[817]: Updating browse sources language Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:08:11 volumio volumio[817]: Forking 1 albumart workers Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::initPlayerControls Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:08:11 volumio volumio[817]: Express server listening on port 3000 Mar 07 16:08:11 volumio volumio[817]: [Metrics] WebUI: 5s 441.45ms Mar 07 16:08:11 volumio volumio[817]: info: Setting Device type: x86 Mar 07 16:08:11 volumio volumio[817]: info: CoreStateMachine::resetVolumioState Mar 07 16:08:11 volumio volumio[817]: info: CoreStateMachine::getcurrentVolume Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 16:08:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:11 volumio volumio[817]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 07 16:08:11 volumio volumio[817]: info: Completed loading Core Plugins Mar 07 16:08:11 volumio volumio[817]: info: Preparing to generate the ALSA configuration file Mar 07 16:08:11 volumio volumio[817]: info: Cannot read play queue from file Mar 07 16:08:11 volumio volumio[817]: info: MPD Permissions set Mar 07 16:08:11 volumio volumio[817]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 07 16:08:11 volumio volumio[817]: wlan0 Interface doesn't support scanning : Operation not supported Mar 07 16:08:11 volumio volumio[817]: info: Cannot use regular scanning, forcing with ap-force Mar 07 16:08:11 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 07 16:08:11 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:11 volumio volumio[817]: Starting albumart workers Mar 07 16:08:13 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:14 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:14 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:15 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:15 volumio dhcpcd[442]: wlan0: using IPv4LL address 169.254.148.40 Mar 07 16:08:15 volumio dhcpcd[442]: wlan0: adding route to 169.254.0.0/16 Mar 07 16:08:15 volumio avahi-daemon[371]: Registering new address record for 169.254.148.40 on wlan0.IPv4. Mar 07 16:08:15 volumio volumio-remote-updater[372]: [2025-03-07 08:08:15] [connect] Successful connection Mar 07 16:08:15 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:16 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:16 volumio ntpd[763]: Listen normally on 5 wlan0 169.254.148.40:123 Mar 07 16:08:16 volumio ntpd[763]: new interface(s) found: waking up resolver Mar 07 16:08:17 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:17 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:18 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:19 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:19 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 07 16:08:20 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:21 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:21 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:22 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 07 16:08:23 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:23 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:24 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:24 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:25 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:26 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:26 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:27 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:27 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:28 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:30 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:30 volumio volumio-remote-updater[372]: [2025-03-07 08:08:30] [connect] Successful connection Mar 07 16:08:30 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:31 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:32 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:32 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::getcurrentVolume Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 16:08:32 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:08:32 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:08:32 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio volumio[817]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Total Clients: 1 Mar 07 16:08:32 volumio volumio[817]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Total Clients: 2 Mar 07 16:08:32 volumio volumio[817]: info: MPD running with PID1262 Mar 07 16:08:32 volumio volumio[817]: ,establishing connection Mar 07 16:08:32 volumio volumio[817]: error: MPD error: The expression evaluated to a falsy value: Mar 07 16:08:32 volumio volumio[817]: assert.ok(self.idling) Mar 07 16:08:32 volumio volumio[817]: error: MPD error: The expression evaluated to a falsy value: Mar 07 16:08:32 volumio volumio[817]: assert.ok(self.idling) Mar 07 16:08:32 volumio volumio-remote-updater[372]: [2025-03-07 08:08:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741334910 101 Mar 07 16:08:32 volumio volumio[817]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: VolumeController:: Volume=41 Mute =false Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::pushState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::updateTrackBlock Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrackBlock Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 16:08:32 volumio volumio[817]: info: Asound.conf file written Mar 07 16:08:32 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 07 16:08:32 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio volumio[817]: No state is present for card NVidia Mar 07 16:08:32 volumio volumio[817]: Found hardware: "HDA-Intel" "Realtek ALC889A" "HDA:10ec0885,106b4100,00100103" "0x10de" "0xcb79" Mar 07 16:08:32 volumio volumio[817]: Hardware is initialized using a generic method Mar 07 16:08:32 volumio volumio[817]: No state is present for card NVidia Mar 07 16:08:32 volumio volumio[817]: info: Output device has changed, restarting MPD Mar 07 16:08:32 volumio sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 16:08:32 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio volumio[817]: info: Output device has changed, restarting Shairport Sync Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:32 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 16:08:32 volumio sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio systemd[1]: Stopping Music Player Daemon... Mar 07 16:08:32 volumio volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 16:08:32 volumio volumio[817]: info: ___________ START PLUGINS ___________ Mar 07 16:08:32 volumio systemd[1]: mpd.service: Succeeded. Mar 07 16:08:32 volumio systemd[1]: Stopped Music Player Daemon. Mar 07 16:08:32 volumio systemd[1]: Starting Music Player Daemon... Mar 07 16:08:32 volumio volumio[817]: info: Setting Hotspot Unique name on first start: Volumio-03511 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::Reload Ui Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 16:08:32 volumio volumio[817]: info: [1741334912485] CoreMusicLibrary::Adding element Media Servers Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:08:32 volumio volumio[817]: Cannot find translation for sourceMedia Servers Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 16:08:32 volumio volumio[817]: info: [1741334912539] CoreMusicLibrary::Adding element Last_100 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:08:32 volumio volumio[817]: Cannot find translation for sourceMedia Servers Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 16:08:32 volumio volumio[817]: info: [1741334912541] CoreMusicLibrary::Adding element Webradio Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:08:32 volumio volumio[817]: Cannot find translation for sourceMedia Servers Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 16:08:32 volumio volumio[817]: info: Volumio Calling Home Mar 07 16:08:32 volumio volumio[817]: info: Completed starting Core Plugins Mar 07 16:08:32 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:32 volumio volumio[817]: info: ----- MyVolumio plugins startup ---- Mar 07 16:08:32 volumio volumio[817]: info: ------------------------------------------- Mar 07 16:08:32 volumio volumio[817]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 07 16:08:32 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:08:32 volumio volumio[817]: error: updateQueue error: null Mar 07 16:08:32 volumio volumio[817]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 07 16:08:32 volumio volumio[817]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Total Clients: 4 Mar 07 16:08:32 volumio volumio[817]: info: VolumeController:: Volume=41 Mute =false Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::pushState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::updateTrackBlock Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrackBlock Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 16:08:32 volumio volumio[817]: info: MPD Permissions set Mar 07 16:08:32 volumio volumio[817]: info: Discovery: adding undefined Mar 07 16:08:32 volumio volumio[817]: info: Discovery: Found device Volumio Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Mar 07 16:08:32 volumio volumio[817]: info: VolumeController:: Volume=41 Mute =false Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::pushState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:32 volumio volumio[817]: info: VolumeController:: Volume=41 Mute =false Mar 07 16:08:32 volumio volumio[817]: info: CoreStateMachine::pushState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:32 volumio volumio[817]: info: Discovery: this is already registered, undefined Mar 07 16:08:32 volumio volumio[817]: info: Discovery: Found device Volumio Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 07 16:08:32 volumio sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 07 16:08:32 volumio sudo[1452]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 07 16:08:32 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:08:32 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:32 volumio volumio[817]: info: Upmpdcli Daemon Started Mar 07 16:08:32 volumio mpd[1445]: Mar 07 08:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 07 16:08:32 volumio mpd[1445]: Mar 07 08:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 07 16:08:32 volumio mpd[1445]: Mar 07 08:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 07 16:08:32 volumio systemd[1]: Started Music Player Daemon. Mar 07 16:08:32 volumio sudo[1429]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:33 volumio volumio[817]: info: Starting Shairport Sync Mar 07 16:08:33 volumio volumio[817]: info: Starting Shairport Sync Mar 07 16:08:33 volumio volumio[817]: info: Starting Shairport Sync Mar 07 16:08:33 volumio sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 16:08:33 volumio sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 16:08:33 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:33 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 16:08:33 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 16:08:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:33 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 16:08:33 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 16:08:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 07 16:08:33 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 16:08:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 16:08:33 volumio volumio[817]: info: Shairport-Sync Started Mar 07 16:08:33 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 16:08:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio volumio[817]: Error adding Membership: Error: addMembership EINVAL Mar 07 16:08:33 volumio volumio[817]: info: Shairport-Sync Started Mar 07 16:08:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 16:08:33 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:33 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:33 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:33 volumio volumio[817]: error: updateQueue error: null Mar 07 16:08:33 volumio volumio[817]: info: Shairport-Sync Started Mar 07 16:08:33 volumio volumio[817]: info: Volumio called home Mar 07 16:08:33 volumio volumio[1455]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 07 16:08:33 volumio volumio[1455]: .......++++ Mar 07 16:08:33 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 07 16:08:33 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:33 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:33 volumio volumio[817]: info: Permissions for /etc/hostapd/hostapd.conf Mar 07 16:08:33 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 07 16:08:33 volumio volumio[817]: info: Discovery: Restarting Advertising due to device name change Mar 07 16:08:33 volumio sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 07 16:08:33 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:33 volumio systemd[1]: Starting Wireless Services... Mar 07 16:08:33 volumio wireless.js[1500]: Cleaning previous... Mar 07 16:08:33 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 16:08:33 volumio dnsmasq[872]: exiting on receipt of SIGTERM Mar 07 16:08:33 volumio systemd[1]: dnsmasq.service: Succeeded. Mar 07 16:08:33 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 16:08:33 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 16:08:33 volumio dhcpcd[442]: wlan0: carrier lost Mar 07 16:08:33 volumio avahi-daemon[371]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 16:08:33 volumio avahi-daemon[371]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:34 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:34 volumio volumio[1455]: ...................................................++++ Mar 07 16:08:34 volumio volumio[1455]: e is 65537 (0x010001) Mar 07 16:08:34 volumio volumio[1455]: writing RSA key Mar 07 16:08:34 volumio dhcpcd[442]: wlan0: deleting route to 169.254.0.0/16 Mar 07 16:08:34 volumio avahi-daemon[371]: Withdrawing address record for 169.254.148.40 on wlan0. Mar 07 16:08:34 volumio avahi-daemon[371]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 16:08:34 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.148.40. Mar 07 16:08:34 volumio avahi-daemon[371]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 16:08:34 volumio avahi-daemon[371]: Registering new address record for 169.254.148.40 on wlan0.IPv4. Mar 07 16:08:34 volumio avahi-daemon[371]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 16:08:34 volumio avahi-daemon[371]: Withdrawing address record for 169.254.148.40 on wlan0. Mar 07 16:08:34 volumio avahi-daemon[371]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.148.40. Mar 07 16:08:34 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:34 volumio systemd[1]: hostapd.service: Succeeded. Mar 07 16:08:34 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 16:08:34 volumio volumio[817]: info: Discovery: A device disappeared from network Mar 07 16:08:34 volumio volumio[817]: info: Discovery: Device volumio disappeared from network Mar 07 16:08:34 volumio sudo[1556]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 16:08:34 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:34 volumio avahi-daemon[371]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 16:08:34 volumio avahi-daemon[371]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:34 volumio avahi-daemon[371]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 16:08:34 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:34 volumio sudo[1575]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 16:08:34 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:34 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:34 volumio wireless.js[1500]: Stopped aP Mar 07 16:08:34 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 16:08:34 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:34 volumio volumio[817]: info: Discovery: A device disappeared from network Mar 07 16:08:34 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:34 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:34 volumio sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 07 16:08:34 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:34 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:34 volumio sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 16:08:34 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:34 volumio sudo[1765]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:34 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 07 16:08:34 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:35 volumio ntpd[763]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=29 secs Mar 07 16:08:35 volumio ntpd[763]: Deleting interface #5 wlan0, 169.254.148.40#123, interface stats: received=0, sent=0, dropped=0, active_time=19 secs Mar 07 16:08:37 volumio volumio[817]: error: MPD error: The expression evaluated to a falsy value: Mar 07 16:08:37 volumio volumio[817]: assert.ok(self.idling) Mar 07 16:08:37 volumio volumio[817]: error: updateQueue error: null Mar 07 16:08:38 volumio volumio[817]: info: Discovery: Started advertising with name: Volumio Mar 07 16:08:39 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:39 volumio sudo[1775]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 16:08:39 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:39 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:39 volumio sudo[1777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 16:08:39 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:39 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:39 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:39 volumio volumio[817]: info: Discovery: adding undefined Mar 07 16:08:39 volumio volumio[817]: info: Discovery: Found device Volumio Mar 07 16:08:39 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:39 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:39 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:39 volumio avahi-daemon[371]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 16:08:39 volumio avahi-daemon[371]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 16:08:39 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 16:08:39 volumio hostapd[1785]: Configuration file: /etc/hostapd/hostapd.conf Mar 07 16:08:39 volumio avahi-daemon[371]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 16:08:39 volumio avahi-daemon[371]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:39 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Mar 07 16:08:40 volumio avahi-daemon[371]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 16:08:40 volumio avahi-daemon[371]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 16:08:40 volumio avahi-daemon[371]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 16:08:40 volumio avahi-daemon[371]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 16:08:40 volumio hostapd[1785]: Using interface wlan0 with hwaddr 90:84:0d:f4:ca:c2 and ssid "Volumio-03511" Mar 07 16:08:40 volumio dhcpcd[442]: wlan0: carrier acquired Mar 07 16:08:40 volumio hostapd[1785]: wlan0: interface state UNINITIALIZED->ENABLED Mar 07 16:08:40 volumio hostapd[1785]: wlan0: AP-ENABLED Mar 07 16:08:40 volumio systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Mar 07 16:08:40 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 16:08:40 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 16:08:40 volumio dnsmasq[1788]: dnsmasq: syntax check OK. Mar 07 16:08:40 volumio dhcpcd[442]: wlan0: IAID 0d:f4:ca:c2 Mar 07 16:08:40 volumio systemd[1]: wireless.service: Succeeded. Mar 07 16:08:40 volumio systemd[1]: Started Wireless Services. Mar 07 16:08:40 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:40 volumio dnsmasq[1796]: started, version 2.80 cachesize 150 Mar 07 16:08:40 volumio dnsmasq[1796]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Mar 07 16:08:40 volumio dnsmasq-dhcp[1796]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Mar 07 16:08:40 volumio dnsmasq[1796]: reading /etc/resolv.conf Mar 07 16:08:40 volumio dnsmasq[1796]: using nameserver 192.168.1.1#53 Mar 07 16:08:40 volumio dnsmasq[1796]: using nameserver 192.168.0.1#53 Mar 07 16:08:40 volumio dnsmasq[1796]: using nameserver 208.67.222.222#53 Mar 07 16:08:40 volumio dnsmasq[1796]: using nameserver 208.67.220.220#53 Mar 07 16:08:40 volumio dnsmasq[1796]: read /etc/hosts - 5 addresses Mar 07 16:08:40 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 16:08:40 volumio dhcpcd[442]: wlan0: soliciting an IPv6 router Mar 07 16:08:40 volumio dhcpcd[442]: wlan0: soliciting a DHCP lease Mar 07 16:08:41 volumio volumio[817]: info: Discovery: this is already registered, undefined Mar 07 16:08:41 volumio volumio[817]: info: Discovery: Found device Volumio Mar 07 16:08:41 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:41 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:41 volumio ntpd[763]: Listen normally on 6 wlan0 192.168.211.1:123 Mar 07 16:08:41 volumio ntpd[763]: new interface(s) found: waking up resolver Mar 07 16:08:42 volumio volumio[817]: error: updateQueue error: null Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin bluetooth to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin multiroom to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin metavolumio to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin cd_controller to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 07 16:08:43 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:43 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:08:43 volumio volumio[817]: info: Starting MyVolumio Remote Streaming Endpoints Mar 07 16:08:43 volumio volumio[817]: info: MyVolumio not started Mar 07 16:08:43 volumio volumio[817]: info: Initializing device activation check Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 07 16:08:43 volumio volumio[817]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 07 16:08:44 volumio volumio[817]: info: Starting Streaming Service Transparent Proxy Mar 07 16:08:44 volumio volumio[817]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 07 16:08:44 volumio volumio[817]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 07 16:08:44 volumio volumio[817]: info: Streaming services startup Mar 07 16:08:44 volumio volumio[817]: info: Starting Streaming Daemon Mar 07 16:08:44 volumio sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 07 16:08:44 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:44 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:44 volumio volumio[817]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 07 16:08:44 volumio volumio[817]: error: Cannot start Volumio Streaming Daemon Mar 07 16:08:44 volumio volumio[817]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 07 16:08:44 volumio volumio[817]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 07 16:08:44 volumio volumio[817]: STREAMING PROXY: Starting server on port 3245 Mar 07 16:08:44 volumio volumio[817]: Node JS runtime: 14 Mar 07 16:08:45 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 07 16:08:45 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 07 16:08:45 volumio sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:08:45 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:45 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:45 volumio dhcpcd[442]: wlan0: probing for an IPv4LL address Mar 07 16:08:50 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 07 16:08:50 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 07 16:08:50 volumio volumio[817]: info: Refreshing Cached IP Addresses Mar 07 16:08:50 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 07 16:08:50 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:50 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:50 volumio systemd[1]: upmpdcli.service: Succeeded. Mar 07 16:08:50 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:08:50 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:50 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:08:50 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:08:50 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:50 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Mar 07 16:08:51 volumio dhcpcd[442]: wlan0: using IPv4LL address 169.254.148.40 Mar 07 16:08:51 volumio dhcpcd[442]: wlan0: adding route to 169.254.0.0/16 Mar 07 16:08:51 volumio avahi-daemon[371]: Registering new address record for 169.254.148.40 on wlan0.IPv4. Mar 07 16:08:52 volumio ntpd[763]: Listen normally on 7 wlan0 169.254.148.40:123 Mar 07 16:08:52 volumio ntpd[763]: new interface(s) found: waking up resolver Mar 07 16:08:58 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 16:08:58 volumio volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 07 16:08:58 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:58 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:09:00 volumio sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:09:00 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:00 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:00 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:09:00 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:00 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:00 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 07 16:09:00 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:00 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 07 16:09:00 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:00 volumio volumio[817]: info: Upmpdcli Daemon Started Mar 07 16:09:00 volumio volumio[1854]: writing RSA key Mar 07 16:09:02 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 16:09:02 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:09:02 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 07 16:09:04 volumio volumio[817]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 07 16:09:04 volumio volumio[817]: info: Completed starting MyVolumio Plugin Mar 07 16:09:04 volumio volumio[817]: info: BOOT COMPLETED Mar 07 16:09:04 volumio volumio[817]: [Metrics] CommandRouter: 57s 428.52ms Mar 07 16:09:04 volumio volumio[817]: info: CoreCommandRouter::volumiosetStartupVolume Mar 07 16:09:04 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 16:09:04 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:09:04 volumio volumio[817]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:09:04 volumio volumio[817]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:09:05 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 07 16:09:05 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 16:09:05 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 07 16:09:05 volumio volumio[817]: info: Version has changed, forcing UI Reload Mar 07 16:09:05 volumio volumio[817]: info: CoreCommandRouter::Reload Ui Mar 07 16:09:09 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:09:09 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:09 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:09 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:09:09 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:09 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:09 volumio volumio[817]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Total Clients: 7 Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 07 16:09:09 volumio volumio[817]: info: Received Get System Info Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 16:09:09 volumio volumio[817]: info: Discovery: Getting this device information Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::volumioGetState Mar 07 16:09:09 volumio volumio[817]: info: CorePlayQueue::getTrack 0 Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 16:09:09 volumio volumio-remote-updater[372]: No test mode Mar 07 16:09:09 volumio volumio-remote-updater[372]: No alpha test mode Mar 07 16:09:09 volumio volumio[817]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Mar 07 16:09:09 volumio volumio[817]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 16:09:09 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 16:09:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 16:09:11 volumio volumio[817]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix Multiroom Buffer Sizes
    • Increase results for Kana-Kanji Conversion
    • Fix for streaming interruptions

 

 

","title":"Update v3.779","updateavailable":true} Mar 07 16:09:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 07 16:09:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 16:09:11 volumio volumio[817]: info: Loading i18n strings for locale zh Mar 07 16:09:11 volumio volumio[817]: Updating browse sources language Mar 07 16:09:11 volumio volumio[817]: Cannot find translation for sourceMedia Servers Mar 07 16:09:11 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:09:11 volumio volumio[817]: Cannot find translation for sourceMedia Servers Mar 07 16:09:12 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 16:09:12 volumio sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 16:09:12 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:12 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:12 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 07 16:09:12 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:12 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:12 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 07 16:09:12 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:12 volumio dbus-daemon[375]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=1910 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ") Mar 07 16:09:12 volumio systemd[1]: Starting Time & Date Service... Mar 07 16:09:12 volumio dbus-daemon[375]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 07 16:09:12 volumio systemd[1]: Started Time & Date Service. Mar 07 16:09:12 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:12 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 07 16:09:12 volumio volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 07 16:09:12 volumio sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 07 16:09:12 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:12 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:12 volumio volumio[817]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 07 16:09:12 volumio volumio[817]: wlan0 Interface doesn't support scanning : Operation not supported Mar 07 16:09:12 volumio volumio[817]: info: Cannot use regular scanning, forcing with ap-force Mar 07 16:09:12 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 07 16:09:12 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:09:14 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:14 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:15 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:15 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:16 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:17 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:17 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:18 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:19 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:19 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:20 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:20 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:21 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:22 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:22 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:23 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:24 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:24 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:25 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:25 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:26 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:27 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:27 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:28 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:30 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:30 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:31 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:31 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:32 volumio kernel: b43-phy0 ERROR: MAC suspend failed Mar 07 16:09:32 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Mar 07 16:09:32 volumio volumio[817]: info: Fetching Streaming Services browse cache Mar 07 16:09:32 volumio volumio[817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 16:09:32 volumio volumio[817]: TypeError: Cannot read property 'name' of null Mar 07 16:09:32 volumio volumio[817]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1608:42) Mar 07 16:09:32 volumio volumio[817]: at Socket.emit (events.js:400:28) Mar 07 16:09:32 volumio volumio[817]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 07 16:09:32 volumio volumio[817]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 07 16:09:32 volumio volumio[817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 16:09:32 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-07 16:08 Mar 07 16:09:32 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"