Dec 10 23:36:35 volumio ntpd[1234]: CLOCK: time stepped by 64.054389 Dec 10 23:36:35 volumio ntpd[1234]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Dec 10 23:36:38 volumio volumio-remote-updater[932]: [2025-12-10 23:36:38] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 10 23:36:38 volumio volumio-remote-updater[932]: [2025-12-10 23:36:38] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 10 23:36:38 volumio volumio-remote-updater[932]: [2025-12-10 23:36:38] [error] handle_connect error: Connection refused Dec 10 23:36:39 volumio firststart.sh[1363]: Creating /var/log/samba/cores folder Dec 10 23:36:39 volumio firststart.sh[1363]: Removing default SSH host keys Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_ecdsa_key' Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_ed25519_key' Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_rsa_key' Dec 10 23:36:39 volumio firststart.sh[1561]: removed '/etc/ssh/ssh_host_rsa_key.pub' Dec 10 23:36:39 volumio firststart.sh[1363]: Generating SSH host keys Dec 10 23:36:39 volumio startx[1513]: X.Org X Server 1.21.1.7 Dec 10 23:36:39 volumio startx[1513]: X Protocol Version 11, Revision 0 Dec 10 23:36:39 volumio startx[1513]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Dec 10 23:36:39 volumio startx[1513]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 bootpart=UUID=99F5-AE32 datapart=UUID=1ab2c24e-cdf4-4795-bf21-929b8f0fb55a 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 10 23:36:39 volumio startx[1513]: xorg-server 2:21.1.7-3+deb12u10 (https://www.debian.org/support) Dec 10 23:36:39 volumio startx[1513]: Current version of pixman: 0.42.2 Dec 10 23:36:39 volumio startx[1513]: Before reporting problems, check http://wiki.x.org Dec 10 23:36:39 volumio startx[1513]: to make sure that you have the latest version. Dec 10 23:36:39 volumio startx[1513]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 10 23:36:39 volumio startx[1513]: (++) from command line, (!!) notice, (II) informational, Dec 10 23:36:39 volumio startx[1513]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 10 23:36:39 volumio startx[1513]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Dec 10 23:36:39 2025 Dec 10 23:36:39 volumio startx[1513]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 10 23:36:39 volumio winbindd[1553]: [2025/12/10 23:36:39.954194, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 10 23:36:39 volumio winbindd[1553]: winbindd version 4.17.12-Debian started. Dec 10 23:36:39 volumio winbindd[1553]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 10 23:36:39 volumio winbindd[1553]: [2025/12/10 23:36:39.978098, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Dec 10 23:36:39 volumio winbindd[1553]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 10 23:36:40 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 10 23:36:40 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 10 23:36:41 volumio smbd[1570]: [2025/12/10 23:36:41.593440, 0] ../../source3/smbd/server.c:1741(main) Dec 10 23:36:41 volumio smbd[1570]: smbd version 4.17.12-Debian started. Dec 10 23:36:41 volumio smbd[1570]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 10 23:36:43 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:36:43 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Argument: 0x00000000 | Trn mode: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Present: 0x01ff0001 | Host ctl: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x00007d07 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Cmd: 0x0000371a | Max curr: 0x00000001 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:36:43 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:36:43 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 10 23:36:44 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 10 23:36:44 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 10 23:36:44 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 10 23:36:44 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 10 23:36:44 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 10 23:36:44 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 10 23:36:44 volumio systemd[1]: Startup finished in 5.334s (firmware) + 7.895s (loader) + 1min 28.349s (kernel) + 36.600s (userspace) = 2min 18.179s. Dec 10 23:36:46 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 10 23:36:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 10 23:36:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:36:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:36:47 volumio upmpdcli[1596]: Could not open config: /tmp/upmpdcli.conf Dec 10 23:36:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 23:36:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: unable to initialize frontend: Dialog Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: (TERM is not set, so the dialog frontend is not usable.) Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: falling back to frontend: Readline Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: unable to initialize frontend: Readline Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: (This frontend requires a controlling tty.) Dec 10 23:36:48 volumio firststart.sh[1562]: debconf: falling back to frontend: Teletype Dec 10 23:36:48 volumio volumio-remote-updater[932]: [2025-12-10 23:36:48] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 10 23:36:48 volumio volumio-remote-updater[932]: [2025-12-10 23:36:48] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 10 23:36:48 volumio volumio-remote-updater[932]: [2025-12-10 23:36:48] [error] handle_connect error: Connection refused Dec 10 23:36:51 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:36:51 volumio volumio[1362]: info: ----- Volumio3 ---- Dec 10 23:36:51 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:36:51 volumio volumio[1362]: info: ----- System startup ---- Dec 10 23:36:51 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:36:51 volumio firststart.sh[1630]: Creating SSH2 RSA key; this may take some time ... Dec 10 23:36:53 volumio firststart.sh[1724]: 3072 SHA256:03bdPEzHmDhNZPktDIBqBWio0svS9UZt+R626ab5UFg root@volumio (RSA) Dec 10 23:36:53 volumio firststart.sh[1630]: Creating SSH2 DSA key; this may take some time ... Dec 10 23:36:53 volumio firststart.sh[1728]: 1024 SHA256:YtqTYzMXfG701F7wugk/C6A5Kswg2izjkyK+dR/mmuc root@volumio (DSA) Dec 10 23:36:53 volumio firststart.sh[1630]: Creating SSH2 ECDSA key; this may take some time ... Dec 10 23:36:53 volumio firststart.sh[1732]: 256 SHA256:/NESd5jTtJpcyuTH13o2i7dhsnmELBv3/dv1Jr0fcuE root@volumio (ECDSA) Dec 10 23:36:53 volumio firststart.sh[1745]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 10 23:36:53 volumio firststart.sh[1745]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Dec 10 23:36:53 volumio firststart.sh[1745]: 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 10 23:36:53 volumio firststart.sh[1745]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 10 23:36:53 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:36:53 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Argument: 0x00000000 | Trn mode: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Present: 0x01ff0001 | Host ctl: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x00007d07 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Cmd: 0x00000102 | Max curr: 0x00000001 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:36:53 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:36:53 volumio systemd[1]: Reloading. Dec 10 23:36:53 volumio volumio[1362]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Dec 10 23:36:53 volumio volumio[1362]: info: MYVOLUMIO Environment detected Dec 10 23:36:53 volumio kernel: i915 0000:00:02.0: [drm] Reducing the compressed framebuffer size. This may lead to less power savings than a non-reduced-size. Try to increase stolen memory size if available in BIOS. Dec 10 23:36:53 volumio systemd-gpt-auto-generator[1758]: EFI loader partition unknown, exiting. Dec 10 23:36:53 volumio systemd-gpt-auto-generator[1758]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 10 23:36:53 volumio volumio[1362]: info: Plugin folders cleanup Dec 10 23:36:53 volumio volumio[1362]: info: Scanning into folder /volumio/app/plugins/ Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category audio_interface Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category miscellanea Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category music_service Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category plugins.json Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category system_controller Dec 10 23:36:53 volumio volumio[1362]: info: Scanning category user_interface Dec 10 23:36:53 volumio volumio[1362]: info: Scanning into folder /data/plugins/ Dec 10 23:36:53 volumio volumio[1362]: info: Plugin folders cleanup completed Dec 10 23:36:53 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:36:53 volumio volumio[1362]: info: ----- Core plugins startup ---- Dec 10 23:36:53 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:36:53 volumio volumio[1362]: info: Loading plugins from folder /volumio/app/plugins/ Dec 10 23:36:53 volumio volumio[1362]: info: Adding plugin upnp to MyMusic Plugins Dec 10 23:36:53 volumio volumio[1362]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 10 23:36:53 volumio volumio[1362]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 10 23:36:53 volumio volumio[1362]: info: Loading plugins from folder /data/plugins/ Dec 10 23:36:53 volumio volumio[1362]: info: Loading plugin "system"... Dec 10 23:36:53 volumio volumio[1362]: info: No id defined. Creating one Dec 10 23:36:53 volumio volumio[1362]: info: Loading plugin "appearance"... Dec 10 23:36:54 volumio systemd[1]: Reloading. Dec 10 23:36:54 volumio systemd-gpt-auto-generator[1803]: EFI loader partition unknown, exiting. Dec 10 23:36:54 volumio systemd-gpt-auto-generator[1803]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 10 23:36:55 volumio firststart.sh[1827]: rescue-ssh.target is a disabled or a static unit not running, not starting it. Dec 10 23:36:55 volumio firststart.sh[1827]: ssh.service is a disabled or a static unit not running, not starting it. Dec 10 23:36:55 volumio systemd[1]: Reloading. Dec 10 23:36:55 volumio systemd-gpt-auto-generator[1852]: EFI loader partition unknown, exiting. Dec 10 23:36:55 volumio systemd-gpt-auto-generator[1852]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "network"... Dec 10 23:36:55 volumio volumio[1362]: info: Refreshing Cached IP Addresses Dec 10 23:36:55 volumio sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:36:55 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:55 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root Dec 10 23:36:55 volumio sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:36:55 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "services"... Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "alsa_controller"... Dec 10 23:36:55 volumio sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 10 23:36:55 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Dec 10 23:36:55 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:55 volumio volumio[1362]: warn: The ALSA output card is not set, defaulting to card [object Object] Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 23:36:55 volumio volumio[1362]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 10 23:36:55 volumio volumio[1362]: info: Setting mixer Master for card Analog Out Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "wizard"... Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "networkfs"... Dec 10 23:36:55 volumio volumio[1362]: info: Starting Udev Watcher for removable devices Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: boot Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: volumio Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: volumio_data Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: hassos-boot Dec 10 23:36:55 volumio volumio[1362]: error: Cannot associate FS Label, not mounting Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: 05e03256-e9d2-49fd-a330-1c9b370ba0d1 Dec 10 23:36:55 volumio volumio[1362]: error: Cannot associate FS Label, not mounting Dec 10 23:36:55 volumio volumio[1362]: error: Cannot associate FS Label, not mounting Dec 10 23:36:55 volumio volumio[1362]: error: Cannot associate FS Label, not mounting Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: hassos-overlay Dec 10 23:36:55 volumio volumio[1362]: info: Ignoring mount for partition: hassos-data Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "volumio_command_line_client"... Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "upnp"... Dec 10 23:36:55 volumio volumio[1362]: info: [1765409815709] Starting Upmpd Daemon Dec 10 23:36:55 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "my_music"... Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "mpd"... Dec 10 23:36:55 volumio firststart.sh[1925]: ssh.socket is a disabled or a static unit not running, not starting it. Dec 10 23:36:55 volumio volumio[1362]: info: Loading plugin "upnp_browser"... Dec 10 23:36:56 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Dec 10 23:36:58 volumio volumio-remote-updater[932]: [2025-12-10 23:36:58] [connect] Successful connection Dec 10 23:37:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 10 23:37:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:37:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:37:02 volumio upmpdcli[1937]: Could not open config: /tmp/upmpdcli.conf Dec 10 23:37:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 23:37:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 23:37:02 volumio firststart.sh[1363]: Enabling SSH for first boot Dec 10 23:37:03 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Dec 10 23:37:03 volumio sshd[1941]: Server listening on 0.0.0.0 port 22. Dec 10 23:37:03 volumio sshd[1941]: Server listening on :: port 22. Dec 10 23:37:03 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Dec 10 23:37:03 volumio firststart.sh[1363]: Disabling firststart service Dec 10 23:37:03 volumio systemctl[1942]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". Dec 10 23:37:03 volumio systemd[1]: Reloading. Dec 10 23:37:03 volumio systemd-gpt-auto-generator[1950]: EFI loader partition unknown, exiting. Dec 10 23:37:03 volumio systemd-gpt-auto-generator[1950]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Dec 10 23:37:03 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:37:03 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Argument: 0x00000c00 | Trn mode: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Present: 0x01ff0001 | Host ctl: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x0000a707 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Cmd: 0x0000341a | Max curr: 0x00000001 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:37:03 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:37:03 volumio volumio[1362]: info: Starting UPNP Browser Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "alarm-clock"... Dec 10 23:37:03 volumio firststart.sh[1363]: Finalizing Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "airplay_emulation"... Dec 10 23:37:03 volumio volumio[1362]: info: Starting Shairport Sync Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "last_100"... Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "webradio"... Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "i2s_dacs"... Dec 10 23:37:03 volumio volumio[1362]: info: I2S DAC not set, start Auto-detection Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "volumiodiscovery"... Dec 10 23:37:03 volumio systemd[1]: firststart.service: Deactivated successfully. Dec 10 23:37:03 volumio systemd[1]: firststart.service: Consumed 3.742s CPU time. Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** For more information see Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:37:03 volumio volumio[1362]: *** WARNING *** For more information see Dec 10 23:37:03 volumio node[1362]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 23:37:03 volumio node[1362]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:37:03 volumio node[1362]: *** WARNING *** For more information see Dec 10 23:37:03 volumio node[1362]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 23:37:03 volumio node[1362]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:37:03 volumio node[1362]: *** WARNING *** For more information see Dec 10 23:37:03 volumio volumio[1362]: info: Discovery: Started advertising with name: Volumio Dec 10 23:37:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "outputs"... Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "albumart"... Dec 10 23:37:03 volumio volumio[1362]: info: Plugin example_plugin is not enabled Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "inputs"... Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "updater_comm"... Dec 10 23:37:03 volumio volumio[1962]: Forking 3 albumart workers Dec 10 23:37:03 volumio volumio[1362]: info: Plugin mpdemulation is not enabled Dec 10 23:37:03 volumio volumio[1362]: info: Loading plugin "rest_api"... Dec 10 23:37:04 volumio volumio[1362]: info: Loading plugin "websocket"... Dec 10 23:37:04 volumio volumio[1362]: info: Starting Socket.io Server version 1.7.4 Dec 10 23:37:04 volumio volumio[1362]: info: Loading i18n strings for locale en Dec 10 23:37:04 volumio volumio[1362]: Updating browse sources language Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::initPlayerControls Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: Express server listening on port 3000 Dec 10 23:37:04 volumio volumio[1362]: [Metrics] WebUI: 13s 681.09ms Dec 10 23:37:04 volumio volumio[1362]: info: Setting Device type: x86 Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::resetVolumioState Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::getcurrentVolume Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:37:04 volumio volumio[1973]: Starting albumart workers Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::getcurrentVolume Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:37:04 volumio volumio[1974]: Starting albumart workers Dec 10 23:37:04 volumio volumio[1972]: Starting albumart workers Dec 10 23:37:04 volumio volumio[1362]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 10 23:37:04 volumio volumio[1362]: info: Completed loading Core Plugins Dec 10 23:37:04 volumio volumio[1362]: info: Preparing to generate the ALSA configuration file Dec 10 23:37:04 volumio volumio[1362]: info: Cannot read play queue from file Dec 10 23:37:04 volumio volumio[1362]: info: Volumio Network Manager: Network status updated: 1 Dec 10 23:37:04 volumio volumio[1362]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 10 23:37:04 volumio volumio[1362]: info: VolumeController:: Volume=49 Mute =false Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:04 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::updateTrackBlock Dec 10 23:37:04 volumio volumio[1362]: info: CorePlayQueue::getTrackBlock Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:37:04 volumio volumio[1362]: info: Asound.conf file written Dec 10 23:37:04 volumio sudo[2016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 23:37:04 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:04 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:04 volumio volumio[1362]: No state is present for card PCH Dec 10 23:37:04 volumio volumio[1362]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0269,10ec10d0,00100203" "0x8086" "0x7270" Dec 10 23:37:04 volumio volumio[1362]: Hardware is initialized using a generic method Dec 10 23:37:04 volumio volumio[1362]: No state is present for card PCH Dec 10 23:37:04 volumio volumio[1362]: No state is present for card HDMI Dec 10 23:37:04 volumio volumio[1362]: Found hardware: "HDA-Intel" "Intel Broadwell HDMI" "HDA:80862808,80860101,00100000" "0x111e" "0x10ec" Dec 10 23:37:04 volumio volumio[1362]: Hardware is initialized using a generic method Dec 10 23:37:04 volumio volumio[1362]: No state is present for card HDMI Dec 10 23:37:04 volumio volumio[1362]: info: Output device has changed, restarting MPD Dec 10 23:37:04 volumio sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 23:37:04 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:04 volumio volumio[1362]: info: Output device has changed, restarting Shairport Sync Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 23:37:04 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:04 volumio volumio[1362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:37:04 volumio volumio[1362]: info: ___________ START PLUGINS ___________ Dec 10 23:37:04 volumio volumio[1362]: info: Setting Hotspot Unique name on first start: Volumio-2C346 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::Reload Ui Dec 10 23:37:04 volumio volumio[1362]: info: ControllerMpd::onStart: Initializing MPD Dec 10 23:37:04 volumio volumio[1362]: info: Creating MPD Configuration file Dec 10 23:37:04 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:37:04 volumio volumio[1362]: info: [1765409824600] CoreMusicLibrary::Adding element Media Servers Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:37:04 volumio sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 23:37:04 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:04 volumio volumio[1362]: info: UPNP Browser: Client initialized successfully Dec 10 23:37:04 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:04 volumio sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 23:37:04 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 23:37:04 volumio volumio[1362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:37:04 volumio volumio[1362]: info: [1765409824631] CoreMusicLibrary::Adding element Last_100 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:37:04 volumio volumio[1362]: info: [1765409824632] CoreMusicLibrary::Adding element Webradio Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:37:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:37:04 volumio volumio[1362]: info: Initializing BBC Radios Dec 10 23:37:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 23:37:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 23:37:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 23:37:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 23:37:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 23:37:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 23:37:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: Volumio Calling Home Dec 10 23:37:04 volumio sudo[2074]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 23:37:04 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 23:37:04 volumio sudo[2083]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 10 23:37:04 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:04 volumio volumio[1362]: info: VolumeController:: Volume=49 Mute =false Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:04 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::updateTrackBlock Dec 10 23:37:04 volumio volumio[1362]: info: CorePlayQueue::getTrackBlock Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:37:04 volumio volumio[1362]: info: MPD Permissions set Dec 10 23:37:04 volumio volumio[1362]: info: MPD Permissions set Dec 10 23:37:04 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.21:3000 from 192.168.0.150 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 10 23:37:04 volumio volumio[1362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 10 23:37:04 volumio volumio[1362]: info: VolumeController:: Volume=49 Mute =false Dec 10 23:37:04 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:04 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: adding f6ed94fe-438b-4b41-bfcd-68c8a7065f55 Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Found device Volumio Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio volumio[1362]: info: VolumeController:: Volume=41 Mute =false Dec 10 23:37:05 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio volumio[1362]: info: Volumio called home Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: this is already registered, f6ed94fe-438b-4b41-bfcd-68c8a7065f55 Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Found device Volumio Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: adding 0c3f87a6-d927-477b-83c7-addcdbaded6f Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Found device Livingroom Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Connecting to remote: 192.168.0.151 Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: adding 13411cd9-8e3b-40c6-b919-66266c3d88fd Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Found device Basement Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Connecting to remote: 192.168.0.150 Dec 10 23:37:05 volumio volumio[1362]: info: Starting Shairport Sync Dec 10 23:37:05 volumio volumio[1362]: info: Starting Shairport Sync Dec 10 23:37:05 volumio volumio[1362]: info: Starting Shairport Sync Dec 10 23:37:05 volumio sudo[2123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 23:37:05 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 23:37:05 volumio sudo[2127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 23:37:05 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 23:37:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 23:37:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 23:37:05 volumio systemd[1]: shairport-sync.service: Consumed 2.097s CPU time. Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Connected to remote: 192.168.0.150 Dec 10 23:37:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Dec 10 23:37:05 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:05 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 23:37:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 23:37:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 23:37:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 23:37:05 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio volumio[1362]: info: Shairport-Sync Started Dec 10 23:37:05 volumio volumio[1362]: Error adding Membership: Error: addMembership EINVAL Dec 10 23:37:05 volumio volumio[1362]: info: Shairport-Sync Started Dec 10 23:37:05 volumio volumio[1362]: info: Shairport-Sync Started Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Connected to remote: 192.168.0.151 Dec 10 23:37:05 volumio sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 10 23:37:05 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio volumio[1362]: info: Permissions for /etc/hostapd/hostapd.conf Dec 10 23:37:05 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 10 23:37:05 volumio volumio[1362]: info: Discovery: Restarting Advertising due to device name change Dec 10 23:37:05 volumio sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 10 23:37:05 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:37:05 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:37:05 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:05 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 10 23:37:05 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 10 23:37:05 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 10 23:37:05 volumio systemd[1]: wireless.service: Consumed 1.362s CPU time. Dec 10 23:37:05 volumio sudo[2174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 23:37:05 volumio sudo[2174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:05 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 10 23:37:05 volumio wireless.js[2175]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 10 23:37:05 volumio wireless.js[2175]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 10 23:37:05 volumio wireless.js[2175]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 10 23:37:05 volumio wireless.js[2175]: WIRELESS.JS: Cleaning previous... Dec 10 23:37:06 volumio sudo[2196]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 10 23:37:06 volumio sudo[2196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 23:37:06 volumio sudo[2196]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2198]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 10 23:37:06 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 23:37:06 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2174]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio volumio[1362]: error: Cannot start Upmpdcli: Error: Command failed: /usr/bin/sudo /bin/systemctl start upmpdcli.service Dec 10 23:37:06 volumio volumio[1362]: Terminated Dec 10 23:37:06 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio volumio[1362]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 10 23:37:06 volumio volumio[1362]: Terminated Dec 10 23:37:06 volumio volumio[1362]: info: Completed starting Core Plugins Dec 10 23:37:06 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:37:06 volumio volumio[1362]: info: ----- MyVolumio plugins startup ---- Dec 10 23:37:06 volumio volumio[1362]: info: ------------------------------------------- Dec 10 23:37:06 volumio volumio[1362]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 10 23:37:06 volumio wireless.js[2175]: WIRELESS.JS: Stopped aP Dec 10 23:37:06 volumio volumio[1362]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 10 23:37:06 volumio volumio[1362]: Terminated Dec 10 23:37:06 volumio sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 10 23:37:06 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:06 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Dec 10 23:37:06 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM Dec 10 23:37:06 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring Dec 10 23:37:06 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 10 23:37:06 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:06 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 10 23:37:06 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:06 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:06 volumio sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 10 23:37:06 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:06 volumio volumio[1362]: info: Discovery: A device disappeared from network Dec 10 23:37:06 volumio volumio[1362]: info: Discovery: Device volumio disappeared from network Dec 10 23:37:06 volumio volumio[1362]: info: Discovery: A device disappeared from network Dec 10 23:37:08 volumio mpd[2084]: 2025-12-10T23:37:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 23:37:08 volumio mpd[2084]: 2025-12-10T23:37:08 exception: Database corrupted Dec 10 23:37:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 23:37:08 volumio volumio[1362]: error: MPD error: The expression evaluated to a falsy value: Dec 10 23:37:08 volumio volumio[1362]: assert.ok(self.idling) Dec 10 23:37:08 volumio volumio[1362]: error: The expression evaluated to a falsy value: Dec 10 23:37:08 volumio volumio[1362]: assert.ok(self.idling) Dec 10 23:37:08 volumio volumio[1362]: error: updateQueue error: null Dec 10 23:37:08 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Dec 10 23:37:08 volumio volumio[1362]: info: Dec 10 23:37:08 volumio volumio[1362]: ---------------------------- MPD announces state update: update Dec 10 23:37:08 volumio volumio[1362]: info: ControllerMpd::getState Dec 10 23:37:08 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Dec 10 23:37:08 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Dec 10 23:37:08 volumio volumio[1362]: info: Dec 10 23:37:08 volumio volumio[1362]: ---------------------------- MPD announces state update: update Dec 10 23:37:08 volumio volumio[1362]: info: ControllerMpd::getState Dec 10 23:37:08 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Dec 10 23:37:08 volumio volumio[1362]: info: sendMpdCommand status took 6 milliseconds Dec 10 23:37:08 volumio volumio[1362]: verbose: ControllerMpd::parseState Dec 10 23:37:09 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:09 volumio wireless.js[2175]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: CA Dec 10 23:37:09 volumio sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 10 23:37:09 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:09 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:09 volumio sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CA Dec 10 23:37:09 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:09 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:13 volumio wireless.js[2175]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: CA Dec 10 23:37:13 volumio wireless.js[2175]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 10 23:37:13 volumio wireless.js[2175]: WIRELESS.JS: Notified systemd about wireless ready Dec 10 23:37:13 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 10 23:37:13 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:13 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:37:13 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Argument: 0x80000c08 | Trn mode: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Present: 0x01ff0001 | Host ctl: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x0000a707 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Cmd: 0x0000341a | Max curr: 0x00000001 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:37:13 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:37:13 volumio volumio-remote-updater[932]: [2025-12-10 23:37:13] [connect] Successful connection Dec 10 23:37:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 10 23:37:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:37:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 23:37:24 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:37:24 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Argument: 0x00000000 | Trn mode: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Present: 0x01ff0000 | Host ctl: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x0000a707 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018001 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Cmd: 0x00000000 | Max curr: 0x00000001 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:37:24 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:37:25 volumio volumio[1362]: info: Command Router : Notfying DB Updatefalse Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::Close All Modals sent Dec 10 23:37:25 volumio volumio[1362]: info: Discovery: Started advertising with name: Volumio Dec 10 23:37:25 volumio sudo[2303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 23:37:25 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:25 volumio sudo[2305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 23:37:25 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:25 volumio volumio[1362]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 10 23:37:25 volumio volumio[1362]: info: sendMpdCommand status took 17328 milliseconds Dec 10 23:37:25 volumio volumio[1362]: info: sendMpdCommand status took 17327 milliseconds Dec 10 23:37:25 volumio volumio[1362]: info: sendMpdCommand status took 17327 milliseconds Dec 10 23:37:25 volumio volumio[1362]: verbose: ControllerMpd::parseState Dec 10 23:37:25 volumio volumio[1362]: verbose: ControllerMpd::parseState Dec 10 23:37:25 volumio volumio[1362]: info: Command Router : Notfying DB Updatefalse Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::Close All Modals sent Dec 10 23:37:25 volumio volumio[1362]: verbose: ControllerMpd::parseState Dec 10 23:37:25 volumio volumio[1362]: info: ControllerMpd::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: 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 10 23:37:25 volumio volumio[1362]: verbose: CURRENT POSITION 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::syncState stateService stop Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus stop Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: No code Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: ControllerMpd::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: 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 10 23:37:25 volumio volumio[1362]: verbose: CURRENT POSITION 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::syncState stateService stop Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus stop Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: No code Dec 10 23:37:25 volumio volumio[1362]: info: CoreStateMachine::pushState Dec 10 23:37:25 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Dec 10 23:37:25 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Dec 10 23:37:25 volumio volumio[1362]: info: ------------------------------ 17380ms Dec 10 23:37:25 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 23:37:25 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:25 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:25 volumio mpd_monitor.sh[2312]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 23:37:25 volumio volumio[1362]: info: ------------------------------ 17420ms Dec 10 23:37:25 volumio volumio[1362]: compat.c: read() failed: Resource temporarily unavailable Dec 10 23:37:25 volumio volumio[1362]: error: Discovery: advertisement error: Error: dns service error: unknown Dec 10 23:37:25 volumio volumio[1362]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Dec 10 23:37:25 volumio volumio[1362]: info: Successfully started MPD Monitor Dec 10 23:37:25 volumio volumio[1362]: info: Successfully started MPD Monitor Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin bluetooth to MyMusic Plugins Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin multiroom to MyMusic Plugins Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin metavolumio to MyMusic Plugins Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin cd_controller to MyMusic Plugins Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 10 23:37:25 volumio volumio[1362]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 10 23:37:26 volumio volumio[1362]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 10 23:37:26 volumio volumio[1362]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 10 23:37:28 volumio volumio-remote-updater[932]: [2025-12-10 23:37:28] [connect] Successful connection Dec 10 23:37:31 volumio volumio[1362]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 10 23:37:31 volumio volumio[1362]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 10 23:37:31 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:31 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:31 volumio volumio[1362]: info: Starting MyVolumio Remote Streaming Endpoints Dec 10 23:37:31 volumio volumio[1362]: info: MyVolumio not started Dec 10 23:37:31 volumio volumio[1362]: info: Initializing device activation check Dec 10 23:37:31 volumio volumio[1362]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 10 23:37:31 volumio volumio[1362]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 10 23:37:34 volumio kernel: mmc0: Timeout waiting for hardware cmd interrupt. Dec 10 23:37:34 volumio kernel: mmc0: sdhci: ============ SDHCI REGISTER DUMP =========== Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Sys addr: 0x00000000 | Version: 0x0000a402 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Argument: 0x000001aa | Trn mode: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Present: 0x01ff0001 | Host ctl: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Power: 0x0000000f | Blk gap: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Wake-up: 0x00000000 | Clock: 0x0000a707 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Timeout: 0x00000000 | Int stat: 0x00018000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Int enab: 0x00ff0003 | Sig enab: 0x00ff0003 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000001 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Caps: 0x67ea64b2 | Caps_1: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Cmd: 0x0000081a | Max curr: 0x00000001 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Resp[0]: 0x00000000 | Resp[1]: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Resp[2]: 0x00000000 | Resp[3]: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: Host ctl2: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: ADMA Err: 0x00000000 | ADMA Ptr: 0x00000000 Dec 10 23:37:34 volumio kernel: mmc0: sdhci: ============================================ Dec 10 23:37:40 volumio volumio[1362]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 10 23:37:40 volumio volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 10 23:37:40 volumio volumio[1362]: info: Streaming services startup Dec 10 23:37:40 volumio volumio[1362]: info: Starting Streaming Daemon Dec 10 23:37:40 volumio volumio[1362]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 10 23:37:40 volumio sudo[2400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 10 23:37:40 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:40 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 10 23:37:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 10 23:37:40 volumio volumio[1362]: info: Discovery: Started advertising with name: Volumio Dec 10 23:37:40 volumio sudo[2407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:37:40 volumio sudo[2407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:40 volumio sudo[2407]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:42 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:37:42 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:37:42 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 23:37:42 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 10 23:37:42 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 10 23:37:42 volumio volumio[1362]: info: Refreshing Cached IP Addresses Dec 10 23:37:42 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 10 23:37:42 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:42 volumio sudo[2418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:37:42 volumio sudo[2420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:37:42 volumio volumio[1362]: error: Cannot start Volumio Streaming Daemon Dec 10 23:37:42 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:42 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:37:42 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:42 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:42 volumio volumio[1362]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 10 23:37:42 volumio volumio[1362]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 10 23:37:42 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.21:3000 from 192.168.0.150 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 10 23:37:42 volumio volumio[1362]: compat.c: read() failed: Resource temporarily unavailable Dec 10 23:37:42 volumio volumio[1362]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:37:42 volumio volumio[1362]: Error: dns service error: unknown Dec 10 23:37:42 volumio volumio[1362]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 10 23:37:42 volumio volumio[1362]: errorCode: -65537 Dec 10 23:37:42 volumio volumio[1362]: } Dec 10 23:37:42 volumio volumio[1362]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:37:42 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Dec 10 23:37:42 volumio sudo[2439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 23:36' Dec 10 23:37:42 volumio sudo[2439]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"