Dec 16 10:33:00 volumio ntpd[1070]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:00 volumio ntpd[1070]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 16 10:33:00 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:00 volumio ntpd[1070]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 16 10:33:01 volumio ntpd[1070]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:01 volumio ntpd[1070]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 16 10:33:01 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:01 volumio ntpd[1070]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 16 10:33:01 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 16 10:33:01 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:01 volumio wireless.js[881]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: NZ Dec 16 10:33:01 volumio sudo[1223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:33:01 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:01 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:01 volumio sudo[1225]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NZ Dec 16 10:33:01 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:01 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:01 volumio wireless.js[881]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: NZ Dec 16 10:33:01 volumio sudo[1232]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 10:33:01 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:01 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:01 volumio sudo[1234]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 10:33:01 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:01 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:01 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:33:01 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:33:01 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:33:01 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:33:01 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:01 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:33:01 volumio welcome[1239]: Resolved ip:[1] 192.168.211.1 Dec 16 10:33:01 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:33:01 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 16 10:33:01 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 16 10:33:01 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:33:01 volumio wireless.js[881]: WIRELESS.JS: Notified systemd about wireless ready Dec 16 10:33:01 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:01 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 16 10:33:01 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 16 10:33:01 volumio systemd[1]: Started firststart.service - First Start. Dec 16 10:33:01 volumio firststart.sh[1253]: Volumio first start configuration script Dec 16 10:33:01 volumio firststart.sh[1253]: Configuring unconfigured packages Dec 16 10:33:01 volumio systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Dec 16 10:33:01 volumio systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Dec 16 10:33:01 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Dec 16 10:33:01 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 16 10:33:01 volumio systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 16 10:33:01 volumio volumio-cpu-tweak[1268]: Setting RT Priority for mpd Dec 16 10:33:01 volumio volumio-cpu-tweak[1293]: pid 35's current scheduling policy: SCHED_OTHER Dec 16 10:33:01 volumio volumio-cpu-tweak[1293]: pid 35's current scheduling priority: 0 Dec 16 10:33:01 volumio volumio-cpu-tweak[1268]: Setting MPD Affinity Dec 16 10:33:01 volumio volumio-cpu-tweak[1300]: pid 3's current affinity mask: f Dec 16 10:33:01 volumio volumio-cpu-tweak[1268]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 16 10:33:01 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 16 10:33:01 volumio startx[1327]: xauth: file /root/.Xauthority does not exist Dec 16 10:33:01 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:33:01 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:01 volumio firststart.sh[1253]: Creating /var/log/samba/cores folder Dec 16 10:33:01 volumio soundcard-init.sh[1337]: Card 0 Chip Conexant Generic Name HDA Intel PCH Dec 16 10:33:01 volumio firststart.sh[1253]: Removing default SSH host keys Dec 16 10:33:01 volumio soundcard-init.sh[1351]: Simple mixer control 'Master',0 Dec 16 10:33:01 volumio soundcard-init.sh[1351]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Dec 16 10:33:01 volumio soundcard-init.sh[1351]: Playback channels: Mono Dec 16 10:33:01 volumio soundcard-init.sh[1351]: Limits: Playback 0 - 74 Dec 16 10:33:01 volumio soundcard-init.sh[1351]: Mono: Playback 56 [76%] [-18.00dB] [on] Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ecdsa_key' Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ed25519_key' Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_rsa_key' Dec 16 10:33:01 volumio firststart.sh[1352]: removed '/etc/ssh/ssh_host_rsa_key.pub' Dec 16 10:33:01 volumio firststart.sh[1253]: Generating SSH host keys Dec 16 10:33:02 volumio soundcard-init.sh[1337]: Card 5 Chip USB Mixer Name Schiit Unison Modi Multi 2 Dec 16 10:33:02 volumio systemd[1]: soundcard-init.service: Deactivated successfully. Dec 16 10:33:02 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:33:02 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:02 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:33:02 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:33:02 volumio volumio-remote-updater[878]: [2025-12-15 21:33:02] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 16 10:33:02 volumio volumio-remote-updater[878]: [2025-12-15 21:33:02] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 16 10:33:02 volumio volumio-remote-updater[878]: [2025-12-15 21:33:02] [error] handle_connect error: Connection refused Dec 16 10:33:02 volumio winbindd[1278]: [2025/12/15 21:33:02.154574, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 16 10:33:02 volumio winbindd[1278]: winbindd version 4.17.12-Debian started. Dec 16 10:33:02 volumio winbindd[1278]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 16 10:33:02 volumio winbindd[1278]: [2025/12/15 21:33:02.162927, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Dec 16 10:33:02 volumio winbindd[1278]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 16 10:33:02 volumio dhcpcd[903]: wlan0: carrier acquired Dec 16 10:33:02 volumio dhcpcd[903]: wlan0: IAID 86:61:ae:27 Dec 16 10:33:02 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 16 10:33:02 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 16 10:33:02 volumio startx[1369]: X.Org X Server 1.21.1.7 Dec 16 10:33:02 volumio startx[1369]: X Protocol Version 11, Revision 0 Dec 16 10:33:02 volumio startx[1369]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Dec 16 10:33:02 volumio startx[1369]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=54cb7fb7-a04e-4bb2-a846-0d89280ad03d bootpart=UUID=D4E3-0907 datapart=UUID=01af290f-117c-456f-8c86-62e59a4a37c4 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Dec 16 10:33:02 volumio startx[1369]: xorg-server 2:21.1.7-3+deb12u10 (https://www.debian.org/support) Dec 16 10:33:02 volumio startx[1369]: Current version of pixman: 0.42.2 Dec 16 10:33:02 volumio startx[1369]: Before reporting problems, check http://wiki.x.org Dec 16 10:33:02 volumio startx[1369]: to make sure that you have the latest version. Dec 16 10:33:02 volumio startx[1369]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 16 10:33:02 volumio startx[1369]: (++) from command line, (!!) notice, (II) informational, Dec 16 10:33:02 volumio startx[1369]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 16 10:33:02 volumio startx[1369]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Dec 15 21:33:02 2025 Dec 16 10:33:02 volumio startx[1369]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 16 10:33:02 volumio systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Dec 16 10:33:02 volumio hostapd[1276]: wlan0: interface state UNINITIALIZED->ENABLED Dec 16 10:33:02 volumio hostapd[1276]: wlan0: AP-ENABLED Dec 16 10:33:02 volumio systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 16 10:33:02 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 16 10:33:02 volumio dnsmasq[1406]: started, version 2.90 cachesize 150 Dec 16 10:33:02 volumio dnsmasq[1406]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Dec 16 10:33:02 volumio dnsmasq-dhcp[1406]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 16 10:33:02 volumio dnsmasq[1406]: reading /etc/resolv.conf Dec 16 10:33:02 volumio dnsmasq[1406]: using nameserver 208.67.222.222#53 Dec 16 10:33:02 volumio dnsmasq[1406]: using nameserver 208.67.220.220#53 Dec 16 10:33:02 volumio dnsmasq[1406]: read /etc/hosts - 16 names Dec 16 10:33:02 volumio smbd[1394]: [2025/12/15 21:33:02.339412, 0] ../../source3/smbd/server.c:1741(main) Dec 16 10:33:02 volumio smbd[1394]: smbd version 4.17.12-Debian started. Dec 16 10:33:02 volumio smbd[1394]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 16 10:33:02 volumio systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 16 10:33:02 volumio systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: unable to initialize frontend: Dialog Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: (TERM is not set, so the dialog frontend is not usable.) Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: falling back to frontend: Readline Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: unable to initialize frontend: Readline Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: (This frontend requires a controlling tty.) Dec 16 10:33:02 volumio firststart.sh[1356]: debconf: falling back to frontend: Teletype Dec 16 10:33:02 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 16 10:33:02 volumio dhcpcd[903]: wlan0: soliciting a DHCP lease Dec 16 10:33:02 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:02 volumio volumio[1252]: info: ----- Volumio3 ---- Dec 16 10:33:02 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:02 volumio volumio[1252]: info: ----- System startup ---- Dec 16 10:33:02 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:02 volumio bash[1051]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 16 10:33:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 16 10:33:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 16 10:33:02 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 16 10:33:02 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 16 10:33:02 volumio firststart.sh[1449]: Creating SSH2 RSA key; this may take some time ... Dec 16 10:33:02 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 16 10:33:02 volumio firststart.sh[1532]: 3072 SHA256:yXUxypov8XGwhs/nmdgIAnclAndGpf1LNd8t6A18OSk root@volumio (RSA) Dec 16 10:33:02 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 16 10:33:02 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 16 10:33:02 volumio systemd[1]: Startup finished in 10.419s (firmware) + 626ms (loader) + 14.979s (kernel) + 7.780s (userspace) = 33.805s. Dec 16 10:33:03 volumio firststart.sh[1449]: Creating SSH2 DSA key; this may take some time ... Dec 16 10:33:03 volumio firststart.sh[1536]: 1024 SHA256:9DqTIDcGASNPAGbs8jv/liBCyPvWBegYI+/gtTpxKtw root@volumio (DSA) Dec 16 10:33:03 volumio firststart.sh[1449]: Creating SSH2 ECDSA key; this may take some time ... Dec 16 10:33:03 volumio firststart.sh[1540]: 256 SHA256:wfkzvG+LO90B4yYEsUGMT4v/9qLNgmmrP2r5pC8Ke/0 root@volumio (ECDSA) Dec 16 10:33:03 volumio dhcpcd[903]: wlan0: soliciting an IPv6 router Dec 16 10:33:03 volumio firststart.sh[1557]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 16 10:33:03 volumio firststart.sh[1557]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Dec 16 10:33:03 volumio firststart.sh[1557]: insserv: Script `ssh' has overlapping Default-Start and Default-Stop runlevels (2 3 4 5) and (2 3 4 5). This should be fixed. Dec 16 10:33:03 volumio firststart.sh[1557]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 16 10:33:03 volumio systemd[1]: Reloading. Dec 16 10:33:03 volumio systemd-gpt-auto-generator[1567]: EFI loader partition unknown, exiting. Dec 16 10:33:03 volumio systemd-gpt-auto-generator[1567]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 16 10:33:03 volumio volumio[1252]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Dec 16 10:33:03 volumio volumio[1252]: info: MYVOLUMIO Environment detected Dec 16 10:33:03 volumio volumio[1252]: info: Plugin folders cleanup Dec 16 10:33:03 volumio volumio[1252]: info: Scanning into folder /volumio/app/plugins/ Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category audio_interface Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category miscellanea Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category music_service Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category plugins.json Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category system_controller Dec 16 10:33:03 volumio volumio[1252]: info: Scanning category user_interface Dec 16 10:33:03 volumio volumio[1252]: info: Scanning into folder /data/plugins/ Dec 16 10:33:03 volumio volumio[1252]: info: Plugin folders cleanup completed Dec 16 10:33:03 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:03 volumio volumio[1252]: info: ----- Core plugins startup ---- Dec 16 10:33:03 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 10:33:03 volumio volumio[1252]: info: Adding plugin upnp to MyMusic Plugins Dec 16 10:33:03 volumio volumio[1252]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 10:33:03 volumio volumio[1252]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugins from folder /data/plugins/ Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "system"... Dec 16 10:33:03 volumio volumio[1252]: info: No id defined. Creating one Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "appearance"... Dec 16 10:33:03 volumio ntpd[1070]: IO: Listen normally on 3 wlan0 192.168.211.1:123 Dec 16 10:33:03 volumio ntpd[1070]: IO: new interface(s) found: waking up resolver Dec 16 10:33:03 volumio ntpd[1070]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:03 volumio ntpd[1070]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 16 10:33:03 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:03 volumio ntpd[1070]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 16 10:33:03 volumio systemd[1]: Reloading. Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "network"... Dec 16 10:33:03 volumio volumio[1252]: info: Refreshing Cached IP Addresses Dec 16 10:33:03 volumio sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:03 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:03 volumio systemd-gpt-auto-generator[1600]: EFI loader partition unknown, exiting. Dec 16 10:33:03 volumio systemd-gpt-auto-generator[1600]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 16 10:33:03 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "services"... Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "alsa_controller"... Dec 16 10:33:03 volumio sudo[1606]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:03 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:03 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:03 volumio sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 10:33:03 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:03 volumio volumio[1252]: warn: The ALSA output card is not set, defaulting to card [object Object] Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:03 volumio volumio[1252]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 16 10:33:03 volumio volumio[1252]: info: Setting mixer Master for card Analog Out Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "wizard"... Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "networkfs"... Dec 16 10:33:03 volumio volumio[1252]: info: Starting Udev Watcher for removable devices Dec 16 10:33:03 volumio volumio[1252]: info: Ignoring mount for partition: boot Dec 16 10:33:03 volumio volumio[1252]: info: Ignoring mount for partition: volumio Dec 16 10:33:03 volumio volumio[1252]: info: Ignoring mount for partition: volumio_data Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "volumio_command_line_client"... Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "upnp"... Dec 16 10:33:03 volumio volumio[1252]: info: [1765834383705] Starting Upmpd Daemon Dec 16 10:33:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "my_music"... Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "mpd"... Dec 16 10:33:03 volumio volumio[1252]: info: Loading plugin "upnp_browser"... Dec 16 10:33:03 volumio firststart.sh[1655]: rescue-ssh.target is a disabled or a static unit not running, not starting it. Dec 16 10:33:03 volumio firststart.sh[1655]: ssh.service is a disabled or a static unit not running, not starting it. Dec 16 10:33:03 volumio systemd[1]: Reloading. Dec 16 10:33:04 volumio systemd-gpt-auto-generator[1675]: EFI loader partition unknown, exiting. Dec 16 10:33:04 volumio systemd-gpt-auto-generator[1675]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "alarm-clock"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "airplay_emulation"... Dec 16 10:33:04 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "last_100"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "webradio"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "i2s_dacs"... Dec 16 10:33:04 volumio volumio[1252]: info: I2S DAC not set, start Auto-detection Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "volumiodiscovery"... Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** For more information see Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 10:33:04 volumio volumio[1252]: *** WARNING *** For more information see Dec 16 10:33:04 volumio node[1252]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 10:33:04 volumio node[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 10:33:04 volumio node[1252]: *** WARNING *** For more information see Dec 16 10:33:04 volumio node[1252]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 10:33:04 volumio node[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 10:33:04 volumio node[1252]: *** WARNING *** For more information see Dec 16 10:33:04 volumio volumio[1252]: info: Discovery: Started advertising with name: Volumio Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "outputs"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "albumart"... Dec 16 10:33:04 volumio volumio[1252]: info: Plugin example_plugin is not enabled Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "inputs"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "updater_comm"... Dec 16 10:33:04 volumio firststart.sh[1683]: ssh.socket is a disabled or a static unit not running, not starting it. Dec 16 10:33:04 volumio volumio[1686]: Forking 3 albumart workers Dec 16 10:33:04 volumio volumio[1252]: info: Plugin mpdemulation is not enabled Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "rest_api"... Dec 16 10:33:04 volumio volumio[1252]: info: Loading plugin "websocket"... Dec 16 10:33:04 volumio volumio[1252]: info: Starting Socket.io Server version 1.7.4 Dec 16 10:33:04 volumio volumio[1252]: info: Loading i18n strings for locale en Dec 16 10:33:04 volumio volumio[1252]: Updating browse sources language Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:04 volumio firststart.sh[1253]: Enabling SSH for first boot Dec 16 10:33:04 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::initPlayerControls Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: Express server listening on port 3000 Dec 16 10:33:04 volumio volumio[1252]: [Metrics] WebUI: 1s 725.72ms Dec 16 10:33:04 volumio volumio[1252]: info: Setting Device type: x86 Dec 16 10:33:04 volumio volumio[1252]: info: CoreStateMachine::resetVolumioState Dec 16 10:33:04 volumio volumio[1252]: info: CoreStateMachine::getcurrentVolume Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:04 volumio volumio[1701]: Starting albumart workers Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 16 10:33:04 volumio volumio[1252]: info: Completed loading Core Plugins Dec 16 10:33:04 volumio volumio[1252]: info: Preparing to generate the ALSA configuration file Dec 16 10:33:04 volumio volumio[1252]: info: Cannot read play queue from file Dec 16 10:33:04 volumio sshd[1734]: Server listening on 0.0.0.0 port 22. Dec 16 10:33:04 volumio sshd[1734]: Server listening on :: port 22. Dec 16 10:33:04 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Dec 16 10:33:04 volumio volumio[1252]: info: Volumio Network Manager: Network status updated: 0 Dec 16 10:33:04 volumio volumio[1252]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 16 10:33:04 volumio firststart.sh[1253]: Disabling firststart service Dec 16 10:33:04 volumio volumio[1703]: Starting albumart workers Dec 16 10:33:04 volumio systemctl[1735]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". Dec 16 10:33:04 volumio systemd[1]: Reloading. Dec 16 10:33:04 volumio ntpd[1070]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:04 volumio ntpd[1070]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 16 10:33:04 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:04 volumio ntpd[1070]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 16 10:33:04 volumio volumio[1252]: info: VolumeController:: Volume=47 Mute =false Dec 16 10:33:04 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:04 volumio volumio[1252]: info: CoreStateMachine::updateTrackBlock Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrackBlock Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:04 volumio volumio[1252]: info: Asound.conf file written Dec 16 10:33:04 volumio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 16 10:33:04 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio volumio[1702]: Starting albumart workers Dec 16 10:33:04 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio volumio[1252]: No state is present for card PCH Dec 16 10:33:04 volumio volumio[1252]: Found hardware: "HDA-Intel" "Conexant Generic" "HDA:14f15098,103c829a,00100100 HDA:8086280b,80860101,00100000" "0x103c" "0x829a" Dec 16 10:33:04 volumio volumio[1252]: Hardware is initialized using a generic method Dec 16 10:33:04 volumio volumio[1252]: No state is present for card PCH Dec 16 10:33:04 volumio volumio[1252]: No state is present for card S2 Dec 16 10:33:04 volumio volumio[1252]: Found hardware: "USB-Audio" "USB Mixer" "USB30be:100e" "" "" Dec 16 10:33:04 volumio volumio[1252]: Hardware is initialized using a generic method Dec 16 10:33:04 volumio volumio[1252]: No state is present for card S2 Dec 16 10:33:04 volumio volumio[1252]: info: Output device has changed, restarting MPD Dec 16 10:33:04 volumio volumio[1252]: info: Output device has changed, restarting Shairport Sync Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio sudo[1760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 10:33:04 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 10:33:04 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 10:33:04 volumio volumio[1252]: info: ___________ START PLUGINS ___________ Dec 16 10:33:04 volumio volumio[1252]: info: Setting Hotspot Unique name on first start: Volumio-C0256 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::Reload Ui Dec 16 10:33:04 volumio volumio[1252]: info: ControllerMpd::onStart: Initializing MPD Dec 16 10:33:04 volumio volumio[1252]: info: Creating MPD Configuration file Dec 16 10:33:04 volumio systemd-gpt-auto-generator[1778]: EFI loader partition unknown, exiting. Dec 16 10:33:04 volumio systemd-gpt-auto-generator[1778]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 16 10:33:04 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 10:33:04 volumio volumio[1252]: info: [1765834384513] CoreMusicLibrary::Adding element Media Servers Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:04 volumio sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 10:33:04 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio sudo[1790]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio sudo[1793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 10:33:04 volumio sudo[1793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 10:33:04 volumio volumio[1252]: info: [1765834384556] CoreMusicLibrary::Adding element Last_100 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 10:33:04 volumio volumio[1252]: info: [1765834384557] CoreMusicLibrary::Adding element Webradio Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 10:33:04 volumio volumio[1252]: info: Initializing BBC Radios Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: Volumio Calling Home Dec 16 10:33:04 volumio volumio[1252]: info: MPD Permissions set Dec 16 10:33:04 volumio volumio[1252]: info: MPD Permissions set Dec 16 10:33:04 volumio volumio[1252]: info: VolumeController:: Volume=47 Mute =false Dec 16 10:33:04 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:04 volumio firststart.sh[1253]: Finalizing Dec 16 10:33:04 volumio volumio[1252]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 16 10:33:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 16 10:33:04 volumio systemd[1]: firststart.service: Deactivated successfully. Dec 16 10:33:04 volumio systemd[1]: firststart.service: Consumed 1.435s CPU time. Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:04 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:04 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:04 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:04 volumio sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 10:33:04 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 10:33:04 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio sudo[1873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 10:33:04 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:04 volumio sudo[1864]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 10:33:04 volumio sudo[1864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 10:33:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:04 volumio systemd[1]: shairport-sync.service: Consumed 1.609s CPU time. Dec 16 10:33:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:04 volumio sudo[1877]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 16 10:33:04 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio volumio[1252]: info: Shairport-Sync Started Dec 16 10:33:04 volumio volumio[1252]: Error adding Membership: Error: addMembership EINVAL Dec 16 10:33:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 10:33:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 10:33:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:04 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:04 volumio volumio[1252]: info: Shairport-Sync Started Dec 16 10:33:04 volumio volumio[1252]: info: Shairport-Sync Started Dec 16 10:33:04 volumio volumio[1252]: info: Discovery: adding 074ea44c-3de3-44cb-86f3-5bb66967aafe Dec 16 10:33:04 volumio volumio[1252]: info: Discovery: Found device Volumio Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:04 volumio volumio[1252]: info: Discovery: this is already registered, 074ea44c-3de3-44cb-86f3-5bb66967aafe Dec 16 10:33:04 volumio volumio[1252]: info: Discovery: Found device Volumio Dec 16 10:33:04 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:04 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:05 volumio ntpd[1070]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:05 volumio ntpd[1070]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 16 10:33:05 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:05 volumio ntpd[1070]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 16 10:33:05 volumio mpd[1879]: 2025-12-15T21:33:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 10:33:05 volumio mpd[1879]: 2025-12-15T21:33:05 exception: Database corrupted Dec 16 10:33:05 volumio sudo[1939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 16 10:33:05 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 16 10:33:05 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:05 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:05 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:05 volumio volumio[1252]: info: Completed starting Core Plugins Dec 16 10:33:05 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:05 volumio volumio[1252]: info: ----- MyVolumio plugins startup ---- Dec 16 10:33:05 volumio volumio[1252]: info: ------------------------------------------- Dec 16 10:33:05 volumio volumio[1252]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 10:33:05 volumio volumio[1252]: info: Permissions for /etc/hostapd/hostapd.conf Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 16 10:33:05 volumio volumio[1252]: info: Discovery: Restarting Advertising due to device name change Dec 16 10:33:05 volumio sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 16 10:33:05 volumio sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:05 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 16 10:33:05 volumio volumio[1252]: error: MPD error: The expression evaluated to a falsy value: Dec 16 10:33:05 volumio volumio[1252]: assert.ok(self.idling) Dec 16 10:33:05 volumio volumio[1252]: error: The expression evaluated to a falsy value: Dec 16 10:33:05 volumio volumio[1252]: assert.ok(self.idling) Dec 16 10:33:05 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Dec 16 10:33:05 volumio volumio[1252]: info: Dec 16 10:33:05 volumio volumio[1252]: ---------------------------- MPD announces state update: update Dec 16 10:33:05 volumio volumio[1252]: info: ControllerMpd::getState Dec 16 10:33:05 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Dec 16 10:33:05 volumio volumio[1252]: info: MPD running with PID1879 Dec 16 10:33:05 volumio volumio[1252]: ,establishing connection Dec 16 10:33:05 volumio volumio[1252]: error: updateQueue error: null Dec 16 10:33:05 volumio volumio[1252]: info: sendMpdCommand status took 5 milliseconds Dec 16 10:33:05 volumio volumio[1252]: verbose: ControllerMpd::parseState Dec 16 10:33:05 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 16 10:33:05 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 16 10:33:05 volumio volumio[1252]: info: Command Router : Notfying DB Updatefalse Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Dec 16 10:33:05 volumio volumio[1252]: info: sendMpdCommand status took 19 milliseconds Dec 16 10:33:05 volumio volumio[1252]: verbose: ControllerMpd::parseState Dec 16 10:33:05 volumio volumio[1252]: info: ControllerMpd::pushState Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::servicePushState Dec 16 10:33:05 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:05 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:05 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:05 volumio volumio[1252]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 16 10:33:05 volumio volumio[1252]: verbose: CURRENT POSITION 0 Dec 16 10:33:05 volumio volumio[1252]: info: CoreStateMachine::syncState stateService stop Dec 16 10:33:05 volumio volumio[1252]: info: CoreStateMachine::syncState currentStatus stop Dec 16 10:33:05 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:05 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:05 volumio volumio[1252]: info: No code Dec 16 10:33:05 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:05 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:05 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:05 volumio volumio[1252]: info: ------------------------------ 23ms Dec 16 10:33:05 volumio volumio[1252]: error: updateQueue error: null Dec 16 10:33:05 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 16 10:33:05 volumio wireless.js[1953]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 16 10:33:05 volumio wireless.js[1953]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 16 10:33:05 volumio wireless.js[1953]: WIRELESS.JS: Cleaning previous... Dec 16 10:33:05 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 16 10:33:05 volumio dnsmasq[1406]: exiting on receipt of SIGTERM Dec 16 10:33:05 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Dec 16 10:33:05 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 16 10:33:05 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 16 10:33:05 volumio dhcpcd[903]: wlan0: carrier lost Dec 16 10:33:05 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:33:05 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:05 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:05 volumio volumio[1252]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 10:33:05 volumio volumio[1252]: wlan0 Failed to read scan data : Network is down Dec 16 10:33:05 volumio volumio[1252]: info: Cannot use regular scanning, forcing with ap-force Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813321, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813375, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20799 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813393, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813406, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20800 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813419, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813431, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20801 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813444, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813455, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20802 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813468, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813480, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20803 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813493, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:05 volumio nmbd[1147]: Packet send failed to 192.168.211.255(137) ERRNO=Network is unreachable Dec 16 10:33:05 volumio nmbd[1147]: [2025/12/15 21:33:05.813504, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 16 10:33:05 volumio nmbd[1147]: retransmit_or_expire_response_records: Failed to resend packet id 20810 to IP 192.168.211.255 on subnet 192.168.211.1 Dec 16 10:33:05 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:33:05 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:05 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:33:05 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:33:05 volumio systemd[1]: hostapd.service: Deactivated successfully. Dec 16 10:33:05 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 16 10:33:05 volumio sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 10:33:05 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:05 volumio sudo[1991]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 10:33:05 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:05 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:33:05 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:05 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:33:05 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:05 volumio sudo[1994]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 10:33:05 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:05 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:33:05 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:33:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:33:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:33:06 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:33:06 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:06 volumio volumio[1252]: info: Discovery: A device disappeared from network Dec 16 10:33:06 volumio volumio[1252]: info: Discovery: Device volumio disappeared from network Dec 16 10:33:06 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:06 volumio welcome[1996]: Resolved ip:[0] Dec 16 10:33:06 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:33:06 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:06 volumio wireless.js[1953]: WIRELESS.JS: Stopped aP Dec 16 10:33:06 volumio sudo[2007]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:33:06 volumio sudo[2007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:06 volumio sudo[2007]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:06 volumio sudo[2009]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 16 10:33:06 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:06 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:06 volumio sudo[2017]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:33:06 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:06 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:06 volumio sudo[2019]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 16 10:33:06 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:06 volumio ntpd[1070]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:06 volumio ntpd[1070]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 16 10:33:06 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:06 volumio ntpd[1070]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 16 10:33:06 volumio volumio[1252]: info: Discovery: A device disappeared from network Dec 16 10:33:06 volumio nmbd[1147]: [2025/12/15 21:33:06.814836, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 16 10:33:06 volumio nmbd[1147]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 16 10:33:07 volumio ntpd[1070]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 16 10:33:07 volumio volumio[1252]: info: CoreStateMachine::getcurrentVolume Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:07 volumio volumio[1252]: info: VolumeController:: Volume=43 Mute =false Dec 16 10:33:07 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:07 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:07 volumio volumio[1252]: info: CoreStateMachine::updateTrackBlock Dec 16 10:33:07 volumio volumio[1252]: info: CorePlayQueue::getTrackBlock Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:07 volumio volumio[1252]: info: VolumeController:: Volume=43 Mute =false Dec 16 10:33:07 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:07 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:07 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:08 volumio sudo[2031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 10:33:08 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:08 volumio sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 10:33:08 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:08 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 10:33:08 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:08 volumio mpd_monitor.sh[2035]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 10:33:08 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 16 10:33:08 volumio volumio[1252]: info: Successfully started MPD Monitor Dec 16 10:33:08 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 16 10:33:08 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 16 10:33:08 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 10:33:08 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:08 volumio mpd_monitor.sh[2038]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 10:33:08 volumio volumio[1252]: info: Successfully started MPD Monitor Dec 16 10:33:09 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:09 volumio wireless.js[1953]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: NZ Dec 16 10:33:09 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:33:09 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:09 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:09 volumio sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NZ Dec 16 10:33:09 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:09 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:09 volumio wireless.js[1953]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: NZ Dec 16 10:33:09 volumio sudo[2054]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 10:33:09 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:09 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:09 volumio sudo[2056]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 10:33:09 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:09 volumio volumio-remote-updater[878]: [2025-12-15 21:33:09] [connect] Successful connection Dec 16 10:33:09 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:09 volumio volumio-remote-updater[878]: [2025-12-15 21:33:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765834389 101 Dec 16 10:33:09 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Dec 16 10:33:09 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:09 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:33:09 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:33:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:33:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:33:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:33:09 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:09 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:33:09 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:33:09 volumio welcome[2061]: Resolved ip:[1] 192.168.211.1 Dec 16 10:33:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:33:09 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:09 volumio systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 16 10:33:09 volumio wireless.js[1953]: WIRELESS.JS: Notified systemd about wireless ready Dec 16 10:33:09 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:33:09 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:09 volumio iwconfig[2072]: Error for wireless request "Set Power Management" (8B2C) : Dec 16 10:33:09 volumio iwconfig[2072]: SET failed on device wlan0 ; Invalid argument. Dec 16 10:33:09 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:33:09 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 16 10:33:09 volumio sudo[1948]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:10 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:33:10 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:33:10 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:33:10 volumio dhcpcd[903]: wlan0: carrier acquired Dec 16 10:33:10 volumio hostapd[2070]: wlan0: interface state UNINITIALIZED->ENABLED Dec 16 10:33:10 volumio hostapd[2070]: wlan0: AP-ENABLED Dec 16 10:33:10 volumio systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 16 10:33:10 volumio dhcpcd[903]: wlan0: IAID 86:61:ae:27 Dec 16 10:33:10 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 16 10:33:10 volumio dnsmasq[2079]: started, version 2.90 cachesize 150 Dec 16 10:33:10 volumio dnsmasq[2079]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Dec 16 10:33:10 volumio dnsmasq-dhcp[2079]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 16 10:33:10 volumio dnsmasq[2079]: reading /etc/resolv.conf Dec 16 10:33:10 volumio dnsmasq[2079]: using nameserver 208.67.222.222#53 Dec 16 10:33:10 volumio dnsmasq[2079]: using nameserver 208.67.220.220#53 Dec 16 10:33:10 volumio dnsmasq[2079]: read /etc/hosts - 16 names Dec 16 10:33:10 volumio systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 16 10:33:10 volumio dhcpcd[903]: wlan0: soliciting a DHCP lease Dec 16 10:33:10 volumio volumio[1252]: info: Discovery: Started advertising with name: Volumio Dec 16 10:33:10 volumio volumio[1252]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 10:33:11 volumio dhcpcd[903]: wlan0: soliciting an IPv6 router Dec 16 10:33:11 volumio volumio[1252]: info: Discovery: adding 074ea44c-3de3-44cb-86f3-5bb66967aafe Dec 16 10:33:11 volumio volumio[1252]: info: Discovery: Found device Volumio Dec 16 10:33:11 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:11 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:11 volumio ntpd[1070]: IO: Listen normally on 4 wlan0 192.168.211.1:123 Dec 16 10:33:11 volumio ntpd[1070]: IO: new interface(s) found: waking up resolver Dec 16 10:33:11 volumio ntpd[1070]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:11 volumio ntpd[1070]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 16 10:33:11 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:11 volumio ntpd[1070]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 16 10:33:11 volumio volumio[1252]: info: Discovery: this is already registered, 074ea44c-3de3-44cb-86f3-5bb66967aafe Dec 16 10:33:11 volumio volumio[1252]: info: Discovery: Found device Volumio Dec 16 10:33:11 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:11 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:12 volumio ntpd[1070]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:12 volumio ntpd[1070]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 16 10:33:12 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:12 volumio ntpd[1070]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 16 10:33:13 volumio ntpd[1070]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:13 volumio ntpd[1070]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 16 10:33:13 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:13 volumio ntpd[1070]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 16 10:33:13 volumio sudo[2107]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:13 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:13 volumio sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:13 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:13 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:13 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:13 volumio sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 10:33:13 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:13 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 10:33:13 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:13 volumio volumio[1252]: info: Upmpdcli Daemon Started Dec 16 10:33:14 volumio ntpd[1070]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:14 volumio ntpd[1070]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 16 10:33:14 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:14 volumio ntpd[1070]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 16 10:33:14 volumio volumio[1252]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.071&uuid=c025622654e3d661d93e18c959af5310" http://updates.volumio.org/downloader-v1/track-device Dec 16 10:33:14 volumio volumio[1252]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 10:33:14 volumio volumio[1252]: Dload Upload Total Spent Left Speed Dec 16 10:33:14 volumio volumio[1252]: [132B blob data] Dec 16 10:33:14 volumio volumio[1252]: retrying in 5 seconds, trial 0 Dec 16 10:33:14 volumio volumio[1252]: info: Volumio Calling Home Dec 16 10:33:14 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 16 10:33:14 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 16 10:33:14 volumio sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:14 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:14 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:15 volumio dhcpcd[903]: wlan0: probing for an IPv4LL address Dec 16 10:33:18 volumio upmpdcli[2145]: writing RSA key Dec 16 10:33:19 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 16 10:33:19 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 16 10:33:19 volumio volumio[1252]: info: Refreshing Cached IP Addresses Dec 16 10:33:19 volumio sudo[2154]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 16 10:33:19 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:19 volumio sudo[2156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:19 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:20 volumio sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:20 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:20 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:20 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:20 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:20 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Dec 16 10:33:20 volumio systemd[1]: upmpdcli.service: Consumed 4.676s CPU time. Dec 16 10:33:20 volumio dhcpcd[903]: wlan0: using IPv4LL address 169.254.55.208 Dec 16 10:33:20 volumio dhcpcd[903]: wlan0: adding route to 169.254.0.0/16 Dec 16 10:33:20 volumio avahi-daemon[868]: Registering new address record for 169.254.55.208 on wlan0.IPv4. Dec 16 10:33:20 volumio dhcpcd[903]: wlan0: adding default route Dec 16 10:33:20 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:20 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:33:20 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:33:20 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:33:20 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:33:20 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:33:20 volumio welcome[2202]: Resolved ip:[2] 192.168.211.1 169.254.55.208 Dec 16 10:33:20 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:33:20 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:33:22 volumio ntpd[1070]: IO: Listen normally on 5 wlan0 169.254.55.208:123 Dec 16 10:33:22 volumio ntpd[1070]: IO: new interface(s) found: waking up resolver Dec 16 10:33:22 volumio ntpd[1070]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:23 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 16 10:33:23 volumio volumio[1252]: info: Received Get System Info Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 10:33:23 volumio volumio[1252]: info: Discovery: Getting this device information Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:23 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 10:33:23 volumio volumio-remote-updater[878]: Test mode disabled Dec 16 10:33:23 volumio volumio-remote-updater[878]: Alpha mode disabled Dec 16 10:33:23 volumio volumio-remote-updater[878]: Alpha legacy test mode disabled Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 10:33:23 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 16 10:33:24 volumio volumio[1252]: info: Loading i18n strings for locale en Dec 16 10:33:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 16 10:33:24 volumio volumio[1252]: info: Setting timezone to UTC Dec 16 10:33:24 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 16 10:33:24 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:24 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:24 volumio sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Dec 16 10:33:24 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:24 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:24 volumio sudo[2379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 16 10:33:24 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:24 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:24 volumio sudo[2383]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Dec 16 10:33:24 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:24 volumio dbus-daemon[872]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=2384 comm="/usr/bin/timedatectl set-timezone UTC") Dec 16 10:33:24 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 16 10:33:24 volumio dbus-daemon[872]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 16 10:33:24 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 16 10:33:24 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:24 volumio volumio[1252]: Updating browse sources language Dec 16 10:33:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:24 volumio volumio[1252]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.071&uuid=c025622654e3d661d93e18c959af5310" http://updates.volumio.org/downloader-v1/track-device Dec 16 10:33:24 volumio volumio[1252]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 10:33:24 volumio volumio[1252]: Dload Upload Total Spent Left Speed Dec 16 10:33:24 volumio volumio[1252]: [132B blob data] Dec 16 10:33:24 volumio volumio[1252]: retrying in 5 seconds, trial 1 Dec 16 10:33:24 volumio volumio[1252]: info: Volumio Calling Home Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 10:33:25 volumio volumio[1252]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 10:33:25 volumio volumio[1252]: [Metrics] CommandRouter: 22s 605.32ms Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:25 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:26 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 10:33:26 volumio volumio[1252]: info: Version has changed, forcing UI Reload Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::Reload Ui Dec 16 10:33:26 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 16 10:33:26 volumio volumio[1252]: info: Received Get System Info Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 10:33:26 volumio volumio[1252]: info: Discovery: Getting this device information Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:26 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 10:33:26 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:27 volumio dhcpcd[852]: timed out Dec 16 10:33:27 volumio sh[834]: timed out Dec 16 10:33:27 volumio sh[774]: ifup: failed to bring up eth0 Dec 16 10:33:27 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 16 10:33:27 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 16 10:33:27 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 16 10:33:27 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 16 10:33:27 volumio volumio[1252]: info: Loading i18n strings for locale en Dec 16 10:33:27 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 16 10:33:27 volumio volumio[1252]: info: Setting timezone to UTC Dec 16 10:33:27 volumio sudo[2402]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 16 10:33:27 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:27 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:27 volumio sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Dec 16 10:33:27 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:27 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:27 volumio sudo[2410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 16 10:33:27 volumio sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:27 volumio sudo[2410]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:27 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Dec 16 10:33:27 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:27 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:27 volumio volumio[1252]: Updating browse sources language Dec 16 10:33:27 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:28 volumio hostapd[2074]: wlan0: STA 78:46:5c:3b:bb:5b IEEE 802.11: authenticated Dec 16 10:33:28 volumio hostapd[2074]: wlan0: STA 78:46:5c:3b:bb:5b IEEE 802.11: associated (aid 1) Dec 16 10:33:28 volumio hostapd[2074]: wlan0: STA 78:46:5c:3b:bb:5b RADIUS: starting accounting session F5355F2FED8C3889 Dec 16 10:33:28 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 10:33:29 volumio sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:29 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:29 volumio sudo[2424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:29 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:29 volumio sudo[2424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:29 volumio sudo[2424]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:29 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 16 10:33:29 volumio ntpd[1070]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 16 10:33:29 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:29 volumio ntpd[1070]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 16 10:33:29 volumio volumio-remote-updater[878]: curl_easy_perform() failed: Couldn't resolve host name Dec 16 10:33:29 volumio volumio-remote-updater[878]: terminate called after throwing an instance of 'std::invalid_argument' Dec 16 10:33:29 volumio volumio-remote-updater[878]: what(): parse error - unexpected end of input Dec 16 10:33:29 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Dec 16 10:33:29 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 16 10:33:29 volumio volumio[1252]: info: Received Get System Info Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 10:33:29 volumio volumio[1252]: info: Discovery: Getting this device information Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:29 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 10:33:29 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:29 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Dec 16 10:33:29 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 16 10:33:29 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 16 10:33:29 volumio volumio-remote-updater[2428]: Volumio OTA Client: 1.7.0 Dec 16 10:33:29 volumio volumio-remote-updater[2428]: Error: No active session Dec 16 10:33:29 volumio volumio-remote-updater[2428]: [2025-12-15 21:33:29] [connect] Successful connection Dec 16 10:33:29 volumio volumio-remote-updater[2428]: [2025-12-15 21:33:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765834409 101 Dec 16 10:33:29 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 16 10:33:30 volumio sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:30 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:33:30 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio sudo[2438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 10:33:30 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 10:33:30 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio volumio[1252]: info: Upmpdcli Daemon Started Dec 16 10:33:30 volumio upmpdcli[2456]: writing RSA key Dec 16 10:33:30 volumio ntpd[1070]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:30 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 16 10:33:30 volumio volumio[1252]: info: Loading i18n strings for locale en Dec 16 10:33:30 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 16 10:33:30 volumio volumio[1252]: info: Setting timezone to Pacific/Auckland Dec 16 10:33:30 volumio sudo[2464]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 16 10:33:30 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio sudo[2468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Pacific/Auckland /etc/localtime Dec 16 10:33:30 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 16 10:33:30 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio sudo[2476]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Pacific/Auckland Dec 16 10:33:30 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:30 volumio systemd-timedated[2385]: Changed time zone to 'Pacific/Auckland' (NZDT). Dec 16 10:33:30 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:30 volumio volumio[1252]: Updating browse sources language Dec 16 10:33:30 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 10:33:32 volumio volumio[1252]: info: BOOT COMPLETED Dec 16 10:33:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 16 10:33:33 volumio volumio[1252]: info: Loading i18n strings for locale en Dec 16 10:33:34 volumio volumio[1252]: Updating browse sources language Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 16 10:33:34 volumio volumio[1252]: info: Setting timezone to Pacific/Auckland Dec 16 10:33:34 volumio sudo[2499]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 16 10:33:34 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:34 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:34 volumio sudo[2503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Pacific/Auckland /etc/localtime Dec 16 10:33:34 volumio sudo[2503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:34 volumio sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:34 volumio sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 16 10:33:34 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:34 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:34 volumio sudo[2511]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Pacific/Auckland Dec 16 10:33:34 volumio sudo[2511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:34 volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 16 10:33:34 volumio sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 10:33:34 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:34 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:33:35 volumio ntpd[1070]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 16 10:33:35 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:35 volumio ntpd[1070]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 16 10:33:36 volumio ntpd[1070]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 16 10:33:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 16 10:33:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 16 10:33:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:40 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio ntpd[1070]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 16 10:33:42 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:42 volumio ntpd[1070]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 16 10:33:42 volumio volumio[1252]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.071&uuid=c025622654e3d661d93e18c959af5310" http://updates.volumio.org/downloader-v1/track-device Dec 16 10:33:42 volumio volumio[1252]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 10:33:42 volumio volumio[1252]: Dload Upload Total Spent Left Speed Dec 16 10:33:42 volumio volumio[1252]: [764B blob data] Dec 16 10:33:42 volumio volumio[1252]: retrying in 5 seconds, trial 2 Dec 16 10:33:42 volumio volumio[1252]: info: Volumio Calling Home Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 16 10:33:42 volumio volumio[1252]: info: Preparing to save Alsa Options, stopping services first Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:33:42 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::volumioPause Dec 16 10:33:42 volumio volumio[1252]: info: CoreStateMachine::pause Dec 16 10:33:42 volumio volumio[1252]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Schiit Unison Modi Multi 2"},"disallowPush":true} Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:42 volumio volumio[1252]: info: Setting mixer PCM for card Schiit Unison Modi Multi 2 Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 10:33:42 volumio volumio[1252]: info: Updating Volume Controller Parameters: Device: 5 Name: Schiit Unison Modi Multi 2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 16 10:33:42 volumio volumio[1252]: info: Disabling external Volume Control Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:33:42 volumio volumio[1252]: info: Preparing to generate the ALSA configuration file Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 16 10:33:42 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 10:33:42 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio volumio[1252]: Unable to parse: Dec 16 10:33:42 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:42 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:42 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:42 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:42 volumio volumio[1252]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 16 10:33:42 volumio sudo[2555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 10:33:42 volumio sudo[2555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 10:33:42 volumio volumio[1252]: Unable to parse: Dec 16 10:33:42 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:42 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:42 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:42 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:42 volumio volumio[1252]: info: VolumeController:: Volume=undefined Mute =false Dec 16 10:33:42 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:42 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:42 volumio volumio[1252]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 10:33:42 volumio volumio[1252]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 10:33:42 volumio volumio[1252]: info: Cannot use regular scanning, forcing with ap-force Dec 16 10:33:42 volumio sudo[2568]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 10:33:42 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio volumio[1252]: command failed: Device or resource busy (-16) Dec 16 10:33:42 volumio volumio[1252]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 16 10:33:42 volumio volumio[1252]: command failed: Device or resource busy (-16) Dec 16 10:33:42 volumio volumio[1252]: info: Asound.conf file written Dec 16 10:33:42 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 16 10:33:42 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio volumio[1252]: No state is present for card PCH Dec 16 10:33:42 volumio volumio[1252]: Found hardware: "HDA-Intel" "Conexant Generic" "HDA:14f15098,103c829a,00100100 HDA:8086280b,80860101,00100000" "0x103c" "0x829a" Dec 16 10:33:42 volumio volumio[1252]: Hardware is initialized using a generic method Dec 16 10:33:42 volumio volumio[1252]: No state is present for card PCH Dec 16 10:33:42 volumio volumio[1252]: No state is present for card S2 Dec 16 10:33:42 volumio volumio[1252]: Found hardware: "USB-Audio" "USB Mixer" "USB30be:100e" "" "" Dec 16 10:33:42 volumio volumio[1252]: Hardware is initialized using a generic method Dec 16 10:33:42 volumio volumio[1252]: No state is present for card S2 Dec 16 10:33:42 volumio volumio[1252]: info: Output device has changed, restarting MPD Dec 16 10:33:42 volumio volumio[1252]: info: Output device has changed, restarting Shairport Sync Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:42 volumio sudo[2579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 10:33:42 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio sudo[2582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 10:33:42 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 10:33:42 volumio sudo[2579]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio volumio[1252]: info: Output device has changed, restarting MPD Dec 16 10:33:42 volumio sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 10:33:42 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio volumio[1252]: info: Output device has changed, restarting Shairport Sync Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:42 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:42 volumio sudo[2592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 10:33:42 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 10:33:42 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:42 volumio volumio[1252]: info: MPD Permissions set Dec 16 10:33:42 volumio volumio[1252]: info: MPD Permissions set Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 10:33:42 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:42 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:43 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 10:33:43 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 10:33:43 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:33:43 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:43 volumio volumio[1252]: info: Starting Shairport Sync Dec 16 10:33:43 volumio sudo[2631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 10:33:43 volumio sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 10:33:43 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:43 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 10:33:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 10:33:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:43 volumio systemd[1]: shairport-sync.service: Consumed 1.648s CPU time. Dec 16 10:33:43 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 10:33:43 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:43 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 16 10:33:43 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 16 10:33:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 16 10:33:43 volumio volumio[1252]: info: Shairport-Sync Started Dec 16 10:33:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 16 10:33:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 16 10:33:43 volumio volumio[1252]: info: Shairport-Sync Started Dec 16 10:33:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 16 10:33:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 16 10:33:43 volumio sudo[2636]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 10:33:43 volumio sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:33:43 volumio sudo[2636]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:43 volumio mpd[2658]: 2025-12-16T10:33:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 10:33:43 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 16 10:33:43 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:43 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:43 volumio volumio[1252]: error: MPD error: The expression evaluated to a falsy value: Dec 16 10:33:43 volumio volumio[1252]: assert.ok(self.idling) Dec 16 10:33:43 volumio volumio[1252]: error: The expression evaluated to a falsy value: Dec 16 10:33:43 volumio volumio[1252]: assert.ok(self.idling) Dec 16 10:33:43 volumio volumio[1252]: error: updateQueue error: null Dec 16 10:33:43 volumio ntpd[1070]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 16 10:33:46 volumio sudo[2682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 10:33:46 volumio sudo[2682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:46 volumio sudo[2684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 10:33:46 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:46 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 16 10:33:46 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 16 10:33:46 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 16 10:33:46 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 10:33:46 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:46 volumio sudo[2682]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:46 volumio volumio[1252]: info: Successfully started MPD Monitor Dec 16 10:33:46 volumio mpd_monitor.sh[2687]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 10:33:46 volumio volumio[1252]: info: Successfully started MPD Monitor Dec 16 10:33:46 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 16 10:33:46 volumio volumio[1252]: info: CoreStateMachine::getcurrentVolume Dec 16 10:33:46 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:46 volumio volumio[1252]: Unable to parse: Dec 16 10:33:46 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:46 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:46 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:46 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:46 volumio volumio[1252]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 16 10:33:46 volumio volumio[1252]: Unable to parse: Dec 16 10:33:46 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:46 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:46 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:46 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:46 volumio volumio[1252]: info: VolumeController:: Volume=undefined Mute =false Dec 16 10:33:46 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:46 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:46 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:46 volumio volumio[1252]: info: CoreStateMachine::updateTrackBlock Dec 16 10:33:46 volumio volumio[1252]: info: CorePlayQueue::getTrackBlock Dec 16 10:33:46 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 10:33:46 volumio volumio[1252]: Unable to parse: Dec 16 10:33:46 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:46 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:46 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:46 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:46 volumio volumio[1252]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 16 10:33:46 volumio volumio[1252]: Unable to parse: Dec 16 10:33:46 volumio volumio[1252]: Simple mixer control 'PCM',0 Dec 16 10:33:46 volumio volumio[1252]: Capabilities: pswitch pswitch-joined Dec 16 10:33:46 volumio volumio[1252]: Playback channels: Mono Dec 16 10:33:46 volumio volumio[1252]: Mono: Playback [on] Dec 16 10:33:46 volumio volumio[1252]: info: VolumeController:: Volume=undefined Mute =false Dec 16 10:33:46 volumio volumio[1252]: info: CoreStateMachine::pushState Dec 16 10:33:46 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:33:46 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Dec 16 10:33:48 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Dec 16 10:33:49 volumio ntpd[1070]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 16 10:33:49 volumio ntpd[1070]: DNS: dns_check: DNS error: -11, System error Dec 16 10:33:49 volumio ntpd[1070]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 16 10:33:54 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 16 10:33:54 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:33:57 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 16 10:33:57 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:33:59 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Dec 16 10:33:59 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Dec 16 10:33:59 volumio sudo[2735]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 10:33:59 volumio sudo[2735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:33:59 volumio sudo[2735]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:00 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 16 10:34:00 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:34:02 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:34:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 16 10:34:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 16 10:34:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 16 10:34:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 16 10:34:03 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 16 10:34:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 16 10:34:04 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:34:04 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 16 10:34:04 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::Reload Ui Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 16 10:34:05 volumio volumio[1252]: info: Wizard terminated Successfully Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 10:34:05 volumio volumio[1252]: info: Executing Deferred Wifi Connection Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 16 10:34:05 volumio volumio[1252]: info: Saving new wireless network Dec 16 10:34:05 volumio sudo[2767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 16 10:34:05 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:05 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 16 10:34:05 volumio volumio[1252]: info: Discovery: Restarting Advertising due to device name change Dec 16 10:34:05 volumio sudo[2770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 16 10:34:05 volumio sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:05 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 16 10:34:05 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 16 10:34:05 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 16 10:34:05 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 16 10:34:05 volumio wireless.js[2777]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 16 10:34:05 volumio wireless.js[2777]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 16 10:34:05 volumio wireless.js[2777]: WIRELESS.JS: Cleaning previous... Dec 16 10:34:05 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 16 10:34:05 volumio dnsmasq[2079]: exiting on receipt of SIGTERM Dec 16 10:34:05 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Dec 16 10:34:05 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 16 10:34:05 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 16 10:34:05 volumio dhcpcd[903]: wlan0: carrier lost Dec 16 10:34:05 volumio avahi-daemon[868]: Withdrawing address record for 169.254.55.208 on wlan0. Dec 16 10:34:05 volumio dhcpcd[903]: wlan0: deleting route to 169.254.0.0/16 Dec 16 10:34:05 volumio dhcpcd[903]: wlan0: deleting default route Dec 16 10:34:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:05 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:34:05 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:34:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:34:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:34:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:34:05 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:34:05 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:34:05 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:34:05 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:34:05 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:34:05 volumio avahi-daemon[868]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 16 10:34:05 volumio volumio[1252]: info: Discovery: A device disappeared from network Dec 16 10:34:05 volumio volumio[1252]: info: Discovery: Device volumio disappeared from network Dec 16 10:34:05 volumio welcome[2809]: Resolved ip:[1] 192.168.211.1 Dec 16 10:34:05 volumio systemd[1]: hostapd.service: Deactivated successfully. Dec 16 10:34:05 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 16 10:34:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:34:05 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:05 volumio sudo[2824]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 10:34:05 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:34:05 volumio avahi-daemon[868]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 16 10:34:05 volumio avahi-daemon[868]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 16 10:34:05 volumio avahi-daemon[868]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 10:34:05 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:05 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:34:05 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:34:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:34:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:34:05 volumio sudo[2827]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 10:34:05 volumio sudo[2827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:34:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:34:05 volumio sudo[2827]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:05 volumio welcome[2828]: Resolved ip:[0] Dec 16 10:34:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:34:05 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:05 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 10:34:05 volumio wireless.js[2777]: WIRELESS.JS: Stopped aP Dec 16 10:34:05 volumio sudo[2841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:34:05 volumio sudo[2841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:05 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:34:06 volumio sudo[2841]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:06 volumio sudo[2844]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 16 10:34:06 volumio sudo[2844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:06 volumio sudo[2844]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:06 volumio sudo[2852]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:34:06 volumio sudo[2852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:06 volumio sudo[2852]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:06 volumio sudo[2854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 16 10:34:06 volumio sudo[2854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:06 volumio volumio[1252]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:06 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 10:34:06 volumio volumio[1252]: info: Received Get System Info Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 10:34:06 volumio volumio[1252]: info: Discovery: Getting this device information Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:06 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:06 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:06 volumio volumio[1252]: info: Listing playlists Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 10:34:06 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 10:34:06 volumio volumio[1252]: info: Discovery: A device disappeared from network Dec 16 10:34:06 volumio sudo[2859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:06 volumio sudo[2859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:06 volumio sudo[2859]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:07 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:07 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:07 volumio sudo[2866]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:07 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:07 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:07 volumio ntpd[1070]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=56 secs Dec 16 10:34:07 volumio ntpd[1070]: IO: Deleting interface #5 wlan0, 169.254.55.208#123, interface stats: received=0, sent=0, dropped=0, active_time=45 secs Dec 16 10:34:07 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 10:34:08 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 10:34:08 volumio volumio[1252]: info: Received Get System Info Dec 16 10:34:08 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 10:34:08 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 10:34:08 volumio volumio[1252]: info: Discovery: Getting this device information Dec 16 10:34:08 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:08 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:08 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 10:34:08 volumio sudo[2876]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:08 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:08 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio bash[2604]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 16 10:34:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 16 10:34:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 16 10:34:09 volumio sudo[2881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:09 volumio sudo[2881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:09 volumio sudo[2881]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio sudo[2854]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: NZ Dec 16 10:34:09 volumio sudo[2885]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 16 10:34:09 volumio sudo[2885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:09 volumio sudo[2885]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio sudo[2887]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NZ Dec 16 10:34:09 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:09 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: NZ Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: Start wireless flow Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: Stopped hotspot (if there).. Dec 16 10:34:09 volumio sudo[2895]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 16 10:34:09 volumio sudo[2895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:34:09 volumio sudo[2895]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio sudo[2897]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 16 10:34:09 volumio sudo[2897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:34:09 volumio sudo[2897]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: DHCP IP fallback Dec 16 10:34:09 volumio wireless.js[2777]: WIRELESS.JS: Start ap Dec 16 10:34:09 volumio wpa_supplicant[2902]: Successfully initialized wpa_supplicant Dec 16 10:34:09 volumio sudo[2903]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 16 10:34:09 volumio sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 10:34:09 volumio sudo[2903]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:09 volumio dhcpcd[903]: control command: /sbin/dhcpcd Dec 16 10:34:09 volumio dhcpcd[903]: control_free: No such file or directory Dec 16 10:34:09 volumio dhcpcd[903]: lo: IAID 00:00:00:00 Dec 16 10:34:09 volumio dhcpcd[903]: lo: adding address fe80::8bef:7e77:c0cc:c94b Dec 16 10:34:09 volumio dhcpcd[903]: ipv6_addaddr1: Permission denied Dec 16 10:34:09 volumio dhcpcd[903]: lo: using static address 127.0.0.1/8 Dec 16 10:34:09 volumio dhcpcd[903]: lo: adding route to 127.0.0.0/8 Dec 16 10:34:09 volumio systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Dec 16 10:34:09 volumio wpa_supplicant[2933]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD Dec 16 10:34:10 volumio volumio[1252]: info: Discovery: Started advertising with name: Volumio Dec 16 10:34:10 volumio sudo[2937]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:10 volumio sudo[2937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:10 volumio sudo[2937]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:10 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:10 volumio sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:10 volumio sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:10 volumio sudo[2941]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:11 volumio volumio[1252]: info: Discovery: adding 074ea44c-3de3-44cb-86f3-5bb66967aafe Dec 16 10:34:11 volumio volumio[1252]: info: Discovery: Found device Volumio Dec 16 10:34:11 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Dec 16 10:34:11 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Dec 16 10:34:11 volumio sudo[2946]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:11 volumio sudo[2946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:11 volumio sudo[2946]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:11 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:11 volumio sudo[2950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:11 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:11 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:12 volumio sudo[2954]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:12 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:12 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:12 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:12 volumio sudo[2958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:12 volumio sudo[2958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:12 volumio sudo[2958]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=NZ Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: SME: Trying to authenticate with 24:5a:4c:93:65:1a (SSID='Chai-Fi' freq=5500 MHz) Dec 16 10:34:13 volumio kernel: wlan0: authenticate with 24:5a:4c:93:65:1a (local address=64:5d:86:61:ae:27) Dec 16 10:34:13 volumio kernel: wlan0: send auth to 24:5a:4c:93:65:1a (try 1/3) Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: Trying to associate with 24:5a:4c:93:65:1a (SSID='Chai-Fi' freq=5500 MHz) Dec 16 10:34:13 volumio kernel: wlan0: authenticated Dec 16 10:34:13 volumio kernel: wlan0: associate with 24:5a:4c:93:65:1a (try 1/3) Dec 16 10:34:13 volumio kernel: wlan0: RX AssocResp from 24:5a:4c:93:65:1a (capab=0x511 status=0 aid=2) Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: Associated with 24:5a:4c:93:65:1a Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 10:34:13 volumio kernel: wlan0: associated Dec 16 10:34:13 volumio kernel: wlan0: Limiting TX power to 21 (24 - 3) dBm as advertised by 24:5a:4c:93:65:1a Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: WPA: Key negotiation completed with 24:5a:4c:93:65:1a [PTK=CCMP GTK=CCMP] Dec 16 10:34:13 volumio wpa_supplicant[2933]: wlan0: CTRL-EVENT-CONNECTED - Connection to 24:5a:4c:93:65:1a completed [id=0 id_str=] Dec 16 10:34:13 volumio dhcpcd[903]: wlan0: carrier acquired Dec 16 10:34:13 volumio dhcpcd[903]: wlan0: connected to Access Point: Chai-Fi Dec 16 10:34:13 volumio dhcpcd[903]: wlan0: IAID 86:61:ae:27 Dec 16 10:34:13 volumio sudo[2963]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:13 volumio sudo[2963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:13 volumio sudo[2963]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:13 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:13 volumio sudo[2967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:13 volumio sudo[2967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:13 volumio sudo[2967]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:13 volumio dhcpcd[903]: wlan0: soliciting an IPv6 router Dec 16 10:34:14 volumio sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:14 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:14 volumio sudo[2985]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:14 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:14 volumio sudo[2989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:14 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:14 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:15 volumio dhcpcd[903]: wlan0: soliciting a DHCP lease Dec 16 10:34:15 volumio dhcpcd[903]: wlan0: offered 192.168.112.169 from 192.168.112.1 Dec 16 10:34:15 volumio dhcpcd[903]: wlan0: probing address 192.168.112.169/24 Dec 16 10:34:15 volumio sudo[2995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:15 volumio sudo[2995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:15 volumio sudo[2995]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:15 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:15 volumio sudo[2999]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:15 volumio sudo[2999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:15 volumio sudo[2999]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:16 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 10:34:16 volumio sudo[3003]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:16 volumio sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:16 volumio sudo[3003]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:16 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:16 volumio sudo[3007]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:16 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:16 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:17 volumio sudo[3011]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:17 volumio sudo[3011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:17 volumio sudo[3011]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:17 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:17 volumio sudo[3015]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:17 volumio sudo[3015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:17 volumio sudo[3015]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:18 volumio sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:18 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:18 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:18 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:18 volumio sudo[3023]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:18 volumio sudo[3023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:18 volumio sudo[3023]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:19 volumio sudo[3027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 10:34:19 volumio sudo[3027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:19 volumio sudo[3027]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:19 volumio wireless.js[2777]: WIRELESS.JS: trying... Dec 16 10:34:19 volumio sudo[3031]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 16 10:34:19 volumio sudo[3031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 10:34:19 volumio sudo[3031]: pam_unix(sudo:session): session closed for user root Dec 16 10:34:19 volumio dhcpcd[903]: wlan0: leased 192.168.112.169 for 3600 seconds Dec 16 10:34:19 volumio avahi-daemon[868]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.112.169. Dec 16 10:34:19 volumio dhcpcd[903]: wlan0: adding route to 192.168.112.0/24 Dec 16 10:34:19 volumio dhcpcd[903]: wlan0: adding default route via 192.168.112.1 Dec 16 10:34:19 volumio avahi-daemon[868]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 10:34:19 volumio avahi-daemon[868]: Registering new address record for 192.168.112.169 on wlan0.IPv4. Dec 16 10:34:19 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:19 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 16 10:34:19 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 16 10:34:19 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 16 10:34:19 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 16 10:34:19 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 16 10:34:19 volumio welcome[3046]: Resolved ip:[1] 192.168.112.169 Dec 16 10:34:19 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 16 10:34:19 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 16 10:34:19 volumio volumio[1252]: compat.c: read() failed: Resource temporarily unavailable Dec 16 10:34:19 volumio volumio[1252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 10:34:19 volumio volumio[1252]: Error: dns service error: unknown Dec 16 10:34:19 volumio volumio[1252]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 16 10:34:19 volumio volumio[1252]: errorCode: -65537 Dec 16 10:34:19 volumio volumio[1252]: } Dec 16 10:34:19 volumio volumio[1252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 10:34:19 volumio sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 10:33' Dec 16 10:34:19 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:56:11 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="eeb7136fb9e09f0ec773ced3a67e6728"