-- Logs begin at Thu 2019-02-14 07:11:58 -03, end at Tue 2026-03-24 13:13:29 -03. -- Mar 24 13:12:00 volumio volumio-remote-updater[591]: [2026-03-24 13:12:00] [info] asio async_connect error: system:111 (Connection refused) Mar 24 13:12:00 volumio volumio-remote-updater[591]: [2026-03-24 13:12:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 24 13:12:00 volumio volumio-remote-updater[591]: [2026-03-24 13:12:00] [error] handle_connect error: Underlying Transport Error Mar 24 13:12:00 volumio sudo[857]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:00 volumio wireless.js[598]: DHCP IP Mar 24 13:12:00 volumio wireless.js[598]: Start ap Mar 24 13:12:00 volumio wpa_supplicant[865]: Successfully initialized wpa_supplicant Mar 24 13:12:00 volumio sudo[866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 24 13:12:00 volumio sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 13:12:00 volumio dhcpcd[867]: dev: loaded udev Mar 24 13:12:00 volumio bthelper[675]: hci0 class of device changed: 0x00041c Mar 24 13:12:00 volumio bthelper[675]: [CHG] Controller D8:3A:DD:21:EF:DB Class: 0x0000041c (1052) Mar 24 13:12:00 volumio bthelper[675]: hci0 new_settings: powered bondable ssp br/edr le secure-conn Mar 24 13:12:00 volumio bthelper[675]: Changing power on succeeded Mar 24 13:12:00 volumio bthelper[675]: [CHG] Controller D8:3A:DD:21:EF:DB PowerState: on Mar 24 13:12:00 volumio bthelper[675]: [CHG] Controller D8:3A:DD:21:EF:DB Powered: yes Mar 24 13:12:00 volumio dhcpcd-run-hooks[885]: wlan0: starting wpa_supplicant Mar 24 13:12:00 volumio dhcpcd-run-hooks[890]: wlan0: failed to start wpa_supplicant Mar 24 13:12:00 volumio dhcpcd-run-hooks[891]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 24 13:12:00 volumio dhcpcd[867]: wlan0: connected to Access Point `' Mar 24 13:12:00 volumio dhcpcd[867]: no interfaces have a carrier Mar 24 13:12:00 volumio dhcpcd[867]: forked to background, child pid 901 Mar 24 13:12:00 volumio dhcpcd[901]: eth0: waiting for carrier Mar 24 13:12:00 volumio dhcpcd[901]: wlan0: waiting for carrier Mar 24 13:12:00 volumio sudo[866]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:01 volumio wireless.js[598]: trying... Mar 24 13:12:01 volumio sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:01 volumio sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:01 volumio sudo[903]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:02 volumio wireless.js[598]: trying... Mar 24 13:12:02 volumio sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:02 volumio sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:02 volumio sudo[906]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:03 volumio wireless.js[598]: trying... Mar 24 13:12:03 volumio sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:03 volumio sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:03 volumio sudo[909]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:04 volumio wireless.js[598]: trying... Mar 24 13:12:04 volumio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:04 volumio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:04 volumio sudo[912]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:04 volumio volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Mar 24 13:12:04 volumio volumio-time-update[594]: volumio-time-update-util: Date not found in response Mar 24 13:12:04 volumio volumio-time-update[594]: volumio-time-update-util: Retrying in 5 seconds... Mar 24 13:12:05 volumio wireless.js[598]: trying... Mar 24 13:12:05 volumio sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:05 volumio sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:05 volumio sudo[920]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:06 volumio wireless.js[598]: trying... Mar 24 13:12:06 volumio sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:06 volumio sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:06 volumio sudo[923]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:06 volumio wpa_supplicant[889]: wlan0: Trying to associate with SSID 'agusquito' Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: carrier acquired Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: connected to Access Point `agusquito' Mar 24 13:12:06 volumio wpa_supplicant[889]: wlan0: Associated with 22:53:83:d0:09:58 Mar 24 13:12:06 volumio wpa_supplicant[889]: wlan0: CTRL-EVENT-CONNECTED - Connection to 22:53:83:d0:09:58 completed [id=0 id_str=] Mar 24 13:12:06 volumio wpa_supplicant[889]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 24 13:12:06 volumio wpa_supplicant[889]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AR Mar 24 13:12:06 volumio dhcpcd[901]: DUID 00:01:00:01:30:a7:89:27:d8:3a:dd:21:ef:d9 Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: IAID dd:21:ef:d9 Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: adding address fe80::da3a:ddff:fe21:efd9 Mar 24 13:12:06 volumio dhcpcd[901]: ipv6_addaddr1: Permission denied Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: carrier lost Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: carrier acquired Mar 24 13:12:06 volumio dhcpcd[901]: wlan0: IAID dd:21:ef:d9 Mar 24 13:12:07 volumio wireless.js[598]: trying... Mar 24 13:12:07 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:07 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:07 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:07 volumio dhcpcd[901]: wlan0: soliciting an IPv6 router Mar 24 13:12:07 volumio dhcpcd[901]: wlan0: rebinding lease of 192.168.1.61 Mar 24 13:12:07 volumio volumio-remote-updater[591]: [2026-03-24 13:12:07] [info] asio async_connect error: system:111 (Connection refused) Mar 24 13:12:07 volumio volumio-remote-updater[591]: [2026-03-24 13:12:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 24 13:12:07 volumio volumio-remote-updater[591]: [2026-03-24 13:12:07] [error] handle_connect error: Underlying Transport Error Mar 24 13:12:07 volumio dhcpcd[901]: wlan0: probing address 192.168.1.61/24 Mar 24 13:12:08 volumio wireless.js[598]: trying... Mar 24 13:12:08 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:08 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:08 volumio sudo[941]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:09 volumio wireless.js[598]: trying... Mar 24 13:12:09 volumio sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:09 volumio sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:09 volumio sudo[944]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:09 volumio volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Mar 24 13:12:09 volumio volumio-time-update[594]: volumio-time-update-util: Date not found in response Mar 24 13:12:09 volumio volumio-time-update[594]: volumio-time-update-util: Retrying in 5 seconds... Mar 24 13:12:10 volumio wireless.js[598]: trying... Mar 24 13:12:10 volumio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:10 volumio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:10 volumio sudo[952]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:11 volumio wireless.js[598]: trying... Mar 24 13:12:11 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:11 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:11 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:12 volumio wireless.js[598]: trying... Mar 24 13:12:12 volumio sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:12 volumio sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:12 volumio sudo[958]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:12 volumio dhcpcd[901]: wlan0: leased 192.168.1.61 for 86400 seconds Mar 24 13:12:12 volumio avahi-daemon[608]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.61. Mar 24 13:12:12 volumio avahi-daemon[608]: New relevant interface wlan0.IPv4 for mDNS. Mar 24 13:12:12 volumio dhcpcd[901]: wlan0: adding route to 192.168.1.0/24 Mar 24 13:12:12 volumio avahi-daemon[608]: Registering new address record for 192.168.1.61 on wlan0.IPv4. Mar 24 13:12:12 volumio dhcpcd[901]: wlan0: adding default route via 192.168.1.1 Mar 24 13:12:13 volumio wireless.js[598]: trying... Mar 24 13:12:13 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 13:12:13 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:13 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:13 volumio wireless.js[598]: Connected to: ----agusquito Mar 24 13:12:13 volumio wireless.js[598]: ---- Mar 24 13:12:13 volumio sudo[984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:12:13 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:13 volumio sudo[984]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:13 volumio wireless.js[598]: ... joined AP, wlan0 IPv4 is 192.168.1.61, ipV6 is undefined Mar 24 13:12:13 volumio wireless.js[598]: It's done! AP Mar 24 13:12:13 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 13:12:13 volumio systemd[1]: Started Wireless Services. Mar 24 13:12:13 volumio systemd[1]: Started Volumio Backend Module. Mar 24 13:12:13 volumio systemd[1]: Started Volumio Cpu Tweaker. Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: Setting RT Priority for mpd Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: pid 35's current scheduling policy: SCHED_OTHER Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: pid 35's current scheduling priority: 0 Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: Setting MPD Affinity Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: pid 3's current affinity mask: f Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 24 13:12:13 volumio volumio-cpu-tweak[990]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 24 13:12:13 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 24 13:12:14 volumio ntpd[795]: Listen normally on 3 wlan0 192.168.1.61:123 Mar 24 13:12:14 volumio ntpd[795]: new interface(s) found: waking up resolver Mar 24 13:12:14 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:14 volumio volumio[989]: info: ----- Volumio3 ---- Mar 24 13:12:14 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:14 volumio volumio[989]: info: ----- System startup ---- Mar 24 13:12:14 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:14 volumio volumio-time-update[594]: volumio-time-update-util: Fetching time from Volumio... Mar 24 13:12:15 volumio volumio[989]: info: MYVOLUMIO Environment detected Mar 24 13:12:15 volumio volumio-time-update[594]: volumio-time-update-util: Setting system time to: 2026-03-24 13:12:42 Mar 24 13:12:15 volumio sudo[1021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-03-24 13:12:42 Mar 24 13:12:15 volumio sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:15 volumio dbus-daemon[609]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1022 comm="timedatectl set-time 2026-03-24 13:12:42 ") Mar 24 13:12:15 volumio volumio[989]: info: Plugin folders cleanup Mar 24 13:12:15 volumio volumio[989]: info: Scanning into folder /volumio/app/plugins/ Mar 24 13:12:15 volumio volumio[989]: info: Scanning category audio_interface Mar 24 13:12:15 volumio volumio[989]: info: Scanning category miscellanea Mar 24 13:12:15 volumio volumio[989]: info: Scanning category music_service Mar 24 13:12:15 volumio volumio[989]: info: Scanning category plugins.json Mar 24 13:12:15 volumio volumio[989]: info: Scanning category system_controller Mar 24 13:12:15 volumio volumio[989]: info: Scanning category user_interface Mar 24 13:12:15 volumio volumio[989]: info: Scanning into folder /data/plugins/ Mar 24 13:12:15 volumio volumio[989]: info: Scanning category music_service Mar 24 13:12:15 volumio volumio[989]: info: Scanning category system_controller Mar 24 13:12:15 volumio volumio[989]: info: Scanning category user_interface Mar 24 13:12:15 volumio volumio[989]: info: Plugin folders cleanup completed Mar 24 13:12:15 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:15 volumio volumio[989]: info: ----- Core plugins startup ---- Mar 24 13:12:15 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:15 volumio volumio[989]: info: Loading plugins from folder /volumio/app/plugins/ Mar 24 13:12:15 volumio volumio[989]: info: Adding plugin upnp to MyMusic Plugins Mar 24 13:12:15 volumio volumio[989]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 24 13:12:15 volumio volumio[989]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 24 13:12:15 volumio volumio[989]: info: Loading plugins from folder /data/plugins/ Mar 24 13:12:15 volumio volumio[989]: info: Loading plugin "system"... Mar 24 13:12:15 volumio systemd[1]: Starting Time & Date Service... Mar 24 13:12:15 volumio volumio[989]: info: Loading plugin "ampswitch"... Mar 24 13:12:15 volumio dbus-daemon[609]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 24 13:12:15 volumio systemd[1]: Started Time & Date Service. Mar 24 13:12:42 volumio systemd-timedated[1023]: Changed local time to Tue Mar 24 13:12:42 2026 Mar 24 13:12:42 volumio sudo[1021]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:42 volumio volumio-time-update[594]: volumio-time-update-util: System time updated successfully. Mar 24 13:12:42 volumio systemd[1]: Started Volumio Time Update Utility. Mar 24 13:12:42 volumio volumio[989]: info: Applying required configuration parameters for plugin ampswitch Mar 24 13:12:42 volumio nmbd[756]: [2026/03/24 13:12:42.238670, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 13:12:42 volumio nmbd[756]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 24 13:12:42 volumio systemd[1]: Started Samba NMB Daemon. Mar 24 13:12:42 volumio volumio[989]: info: Loading plugin "appearance"... Mar 24 13:12:42 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 24 13:12:42 volumio winbindd[1028]: [2026/03/24 13:12:42.530666, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 24 13:12:42 volumio winbindd[1028]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 24 13:12:42 volumio winbindd[1028]: [2026/03/24 13:12:42.548329, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 13:12:42 volumio winbindd[1028]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 24 13:12:42 volumio systemd[1]: Started Samba Winbind Daemon. Mar 24 13:12:42 volumio systemd[1]: Starting Samba SMB Daemon... Mar 24 13:12:42 volumio smbd[1033]: [2026/03/24 13:12:42.930694, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 13:12:42 volumio systemd[1]: Started Samba SMB Daemon. Mar 24 13:12:42 volumio smbd[1033]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 24 13:12:42 volumio systemd[1]: Reached target Multi-User System. Mar 24 13:12:42 volumio systemd[1]: Reached target Graphical Interface. Mar 24 13:12:43 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 24 13:12:43 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 24 13:12:43 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 24 13:12:43 volumio systemd[1]: Startup finished in 12.319s (kernel) + 25.709s (userspace) = 38.029s. Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "network"... Mar 24 13:12:43 volumio volumio[989]: info: Refreshing Cached IP Addresses Mar 24 13:12:43 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:12:43 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:43 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:43 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:12:43 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:43 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "services"... Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "volumio5onboarding"... Mar 24 13:12:43 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 24 13:12:43 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "alsa_controller"... Mar 24 13:12:43 volumio volumio[989]: warn: Unable to locate the audio output device SMSL USB AUDIO. Please configure a valid output device. Mar 24 13:12:43 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "wizard"... Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "networkfs"... Mar 24 13:12:43 volumio volumio[989]: info: Starting Udev Watcher for removable devices Mar 24 13:12:43 volumio volumio[989]: info: Ignoring mount for partition: boot Mar 24 13:12:43 volumio volumio[989]: info: Ignoring mount for partition: volumio Mar 24 13:12:43 volumio volumio[989]: info: Ignoring mount for partition: volumio_data Mar 24 13:12:43 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "volumio_command_line_client"... Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "upnp"... Mar 24 13:12:43 volumio volumio[989]: info: [1774368763520] Starting Upmpd Daemon Mar 24 13:12:43 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "my_music"... Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "mpd"... Mar 24 13:12:43 volumio volumio[989]: info: Loading plugin "upnp_browser"... Mar 24 13:12:43 volumio volumio-remote-updater[591]: [2026-03-24 13:12:43] [connect] Successful connection Mar 24 13:12:44 volumio volumio[989]: info: Starting UPNP Browser Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "alarm-clock"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "airplay_emulation"... Mar 24 13:12:44 volumio volumio[989]: info: Starting Shairport Sync Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "last_100"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "webradio"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "i2s_dacs"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "volumiodiscovery"... Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** For more information see Mar 24 13:12:44 volumio node[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 13:12:44 volumio volumio[989]: *** WARNING *** For more information see Mar 24 13:12:44 volumio node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 13:12:44 volumio node[989]: *** WARNING *** For more information see Mar 24 13:12:44 volumio node[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 13:12:44 volumio node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 13:12:44 volumio node[989]: *** WARNING *** For more information see Mar 24 13:12:44 volumio volumio[989]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 24 13:12:44 volumio volumio[989]: info: Discovery: Started advertising with name: Volumio Mar 24 13:12:44 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "peppy_screensaver"... Mar 24 13:12:44 volumio volumio[989]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "outputs"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "albumart"... Mar 24 13:12:44 volumio volumio[989]: info: Plugin example_plugin is not enabled Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "inputs"... Mar 24 13:12:44 volumio volumio[989]: info: Loading plugin "updater_comm"... Mar 24 13:12:45 volumio volumio[989]: info: Plugin mpdemulation is not enabled Mar 24 13:12:45 volumio volumio[989]: info: Loading plugin "rest_api"... Mar 24 13:12:45 volumio volumio[989]: info: Loading plugin "websocket"... Mar 24 13:12:45 volumio volumio[989]: Forking 3 albumart workers Mar 24 13:12:45 volumio volumio[989]: info: Starting Socket.io Server version 2.3.0 Mar 24 13:12:45 volumio volumio[989]: info: Loading plugin "touch_display"... Mar 24 13:12:45 volumio volumio[989]: info: Applying required configuration parameters for plugin touch_display Mar 24 13:12:45 volumio volumio[989]: info: Loading i18n strings for locale es Mar 24 13:12:45 volumio volumio[989]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 13:12:45 volumio volumio[989]: Updating browse sources language Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:45 volumio volumio[989]: Starting albumart workers Mar 24 13:12:45 volumio volumio[989]: Starting albumart workers Mar 24 13:12:45 volumio volumio[989]: Starting albumart workers Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::initPlayerControls Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:12:45 volumio volumio[989]: Express server listening on port 3000 Mar 24 13:12:45 volumio volumio[989]: [Metrics] WebUI: 5s 409.24ms Mar 24 13:12:45 volumio volumio[989]: info: CoreStateMachine::resetVolumioState Mar 24 13:12:45 volumio volumio[989]: info: CoreStateMachine::getcurrentVolume Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 13:12:45 volumio volumio[989]: info: CoreStateMachine::pushState Mar 24 13:12:45 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:12:45 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Mar 24 13:12:46 volumio volumio[989]: info: Volumio Network Manager: Network status updated: 2 Mar 24 13:12:46 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 fusion Build/U1SJS34.2-92-10-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 24 13:12:46 volumio volumio-remote-updater[591]: [2026-03-24 13:12:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774368763 101 Mar 24 13:12:46 volumio volumio[989]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 24 13:12:46 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 24 13:12:46 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 24 13:12:46 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: Executing start script for DAC IQaudIO DAC Plus Mar 24 13:12:46 volumio volumio[989]: info: Reloading queue from file Mar 24 13:12:46 volumio volumio[989]: info: CoreStateMachine::setRepeat null single undefined Mar 24 13:12:46 volumio volumio[989]: info: CoreStateMachine::pushState Mar 24 13:12:46 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Mar 24 13:12:46 volumio volumio[989]: info: CoreStateMachine::setRandom null Mar 24 13:12:46 volumio volumio[989]: info: CoreStateMachine::pushState Mar 24 13:12:46 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Mar 24 13:12:46 volumio volumio[989]: info: Setting Device type: Raspberry PI Mar 24 13:12:46 volumio volumio[989]: info: DAC script executed Mar 24 13:12:46 volumio volumio[989]: info: Completed loading Core Plugins Mar 24 13:12:46 volumio volumio[989]: info: Preparing to generate the ALSA configuration file Mar 24 13:12:46 volumio volumio[989]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 24 13:12:46 volumio volumio[989]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Mar 24 13:12:46 volumio volumio[989]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 24 13:12:46 volumio volumio[989]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 24 13:12:46 volumio volumio[989]: info: Reading ALSA contributions from plugins. Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:46 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:46 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:46 volumio volumio[989]: info: Asound.conf file unchanged, so no further update is needed Mar 24 13:12:46 volumio volumio[989]: info: Output device has changed, restarting MPD Mar 24 13:12:46 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 13:12:46 volumio volumio[989]: info: Output device has changed, restarting Shairport Sync Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:46 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 13:12:46 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 13:12:46 volumio volumio[989]: info: ___________ START PLUGINS ___________ Mar 24 13:12:46 volumio volumio[989]: info: ControllerMpd::onStart: Initializing MPD Mar 24 13:12:46 volumio volumio[989]: info: Creating MPD Configuration file Mar 24 13:12:46 volumio systemd[1]: Listening on mpd.socket. Mar 24 13:12:46 volumio systemd[1]: Starting Music Player Daemon... Mar 24 13:12:46 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 24 13:12:46 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 13:12:46 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 24 13:12:46 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 13:12:46 volumio volumio[989]: info: [1774368766600] CoreMusicLibrary::Adding element Media Servers Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:46 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 13:12:46 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio volumio[989]: info: UPNP Browser: Client initialized successfully Mar 24 13:12:46 volumio sudo[1159]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 13:12:46 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 24 13:12:46 volumio systemd[1]: mpd.service: Succeeded. Mar 24 13:12:46 volumio systemd[1]: Stopped Music Player Daemon. Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:46 volumio systemd[1]: Starting Music Player Daemon... Mar 24 13:12:46 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:46 volumio sudo[1167]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 13:12:46 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1167]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 24 13:12:46 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 13:12:46 volumio volumio[989]: info: [1774368766726] CoreMusicLibrary::Adding element Last_100 Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 13:12:46 volumio volumio[989]: info: [1774368766728] CoreMusicLibrary::Adding element Webradio Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:12:46 volumio volumio[989]: info: Initializing BBC Radios Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:46 volumio volumio[989]: info: Loading i18n strings for locale es Mar 24 13:12:46 volumio volumio[989]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 13:12:46 volumio volumio[989]: info: /tmp/myfifo created Mar 24 13:12:46 volumio volumio[989]: info: /tmp/myfifosa created Mar 24 13:12:46 volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 24 13:12:46 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:46 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio volumio[989]: info: snd-dummy loaded Mar 24 13:12:46 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:46 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 13:12:47 volumio volumio[989]: info: Loading i18n strings for locale es Mar 24 13:12:47 volumio volumio[989]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:12:47 volumio volumio[989]: info: Volumio Calling Home Mar 24 13:12:47 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:47.150-03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 24 13:12:47 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 24 13:12:47 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 24 13:12:47 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 24 13:12:47 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio[989]: info: Discovery: adding 4b167bfc-67cf-4862-b5bf-9a13a69fb0a7 Mar 24 13:12:47 volumio volumio[989]: info: Discovery: Found device Volumio Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: info: touch_display: Backlight interface detected. Mar 24 13:12:47 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.61:3000 from 192.168.1.23 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 24 13:12:47 volumio systemd[1]: Reloading. Mar 24 13:12:47 volumio volumio[989]: info: MPD Permissions set Mar 24 13:12:47 volumio volumio[989]: info: MPD Permissions set Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 13:12:47 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 24 13:12:47 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio[989]: info: [ASDebug] CurState: stop PrevState: na Mar 24 13:12:47 volumio volumio[989]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 24 13:12:47 volumio volumio[989]: info: [ASDebug] CurState: stop PrevState: na Mar 24 13:12:47 volumio volumio[989]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 24 13:12:47 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:47 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 13:12:47 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:47 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:47 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:47.573-03:00 level=INFO msg="system info for 24262416952564a59b504182bd8d67ea" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 24 13:12:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 24 13:12:47 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:47 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:47 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 13:12:47 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:47 volumio volumio[989]: info: touch_display: Raspberry Pi Foundation touch screen detected. Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 13:12:47 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Mar 24 13:12:47 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:47.689-03:00 level=INFO msg="enabling local network discovery" Mar 24 13:12:47 volumio volumio[989]: info: MPD Permissions set Mar 24 13:12:47 volumio volumio[989]: info: Starting Shairport Sync Mar 24 13:12:47 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:47 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:47.716-03:00 level=INFO msg="enabling BLE discovery" Mar 24 13:12:47 volumio sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 24 13:12:47 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio[989]: info: Starting Shairport Sync Mar 24 13:12:47 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 13:12:47 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio bluetoothd[718]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/D8:3A:DD:21:EF:DB/settings: (Failed to create file “/usr/var/lib/bluetooth/D8:3A:DD:21:EF:DB/settings.B34YM3”: No such file or directory) Mar 24 13:12:47 volumio volumio[989]: info: Starting Shairport Sync Mar 24 13:12:47 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:47.758-03:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 13:12:47 volumio volumio[989]: info: Starting Shairport Sync Mar 24 13:12:47 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:47 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 13:12:47 volumio sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 13:12:47 volumio sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 13:12:47 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:47 volumio volumio[989]: info: Volumio called home Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:12:47 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:47 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:47 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:47 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:47 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 24 13:12:47 volumio volumio[989]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 24 13:12:47 volumio volumio[989]: info: touch_display: File permissions for backlight brightness control set. Mar 24 13:12:47 volumio volumio-remote-updater[591]: No test mode Mar 24 13:12:47 volumio volumio-remote-updater[591]: No alpha test mode Mar 24 13:12:47 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:47 volumio volumio[989]: info: touch_display: systemctl daemon-reload succeeded. Mar 24 13:12:48 volumio systemd[1]: Reloading. Mar 24 13:12:48 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 24 13:12:48 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 24 13:12:48 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 13:12:48 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:48 volumio volumio[989]: info: [ASDebug] Togle GPIO: OFF Mar 24 13:12:48 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 24 13:12:48 volumio volumio[989]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:48 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:48.463-03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 24 13:12:48 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:48.464-03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 24 13:12:48 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:48.464-03:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:48 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio volumio[989]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 24 13:12:48 volumio systemd[1]: Started Volumio Kiosk. Mar 24 13:12:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 24 13:12:48 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio volumio[989]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 24 13:12:48 volumio volumio[989]: info: touch_display: Volumio Kiosk started. Mar 24 13:12:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 24 13:12:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 24 13:12:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 24 13:12:48 volumio systemd[1]: mpd.service: Succeeded. Mar 24 13:12:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:48 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:48 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:48 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio volumio[989]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 24 13:12:48 volumio volumio[989]: info: touch_display: X display number found: Mar 24 13:12:48 volumio systemd[1]: Starting Music Player Daemon... Mar 24 13:12:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 24 13:12:48 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio volumio[989]: info: Shairport-Sync Started Mar 24 13:12:48 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio sudo[1275]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio volumio[989]: Error adding Membership: Error: addMembership EINVAL Mar 24 13:12:48 volumio volumio[989]: info: Shairport-Sync Started Mar 24 13:12:48 volumio volumio[989]: info: Shairport-Sync Started Mar 24 13:12:48 volumio volumio[989]: info: Shairport-Sync Started Mar 24 13:12:48 volumio volumio[989]: info: touch_display: X display number found: 0 Mar 24 13:12:48 volumio sudo[1321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 13:12:48 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:48 volumio sudo[1321]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 24 13:12:48 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:48 volumio startx[1307]: X.Org X Server 1.20.4 Mar 24 13:12:48 volumio startx[1307]: X Protocol Version 11, Revision 0 Mar 24 13:12:48 volumio startx[1307]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 24 13:12:48 volumio startx[1307]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Mar 24 13:12:48 volumio startx[1307]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:21:EF:D8 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9b6a8fd4-d1e0-41c3-90e3-655c7141a81c imgfile=/volumio_current.sqsh bootpart=UUID=7883-9333 datapart=UUID=29aa5a37-b113-44c7-af7e-c21c814bee7e uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 24 13:12:48 volumio startx[1307]: Build Date: 04 April 2023 07:50:56AM Mar 24 13:12:48 volumio startx[1307]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 24 13:12:48 volumio startx[1307]: Current version of pixman: 0.36.0 Mar 24 13:12:48 volumio startx[1307]: Before reporting problems, check http://wiki.x.org Mar 24 13:12:48 volumio startx[1307]: to make sure that you have the latest version. Mar 24 13:12:48 volumio startx[1307]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 24 13:12:48 volumio startx[1307]: (++) from command line, (!!) notice, (II) informational, Mar 24 13:12:48 volumio startx[1307]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 24 13:12:48 volumio startx[1307]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Mar 24 13:12:48 2026 Mar 24 13:12:48 volumio startx[1307]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 24 13:12:48 volumio startx[1307]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 24 13:12:48 volumio volumio[989]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 24 13:12:48 volumio volumio[989]: info: touch_display: X display number found: 0 Mar 24 13:12:49 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:49.246-03:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 13:12:49 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:49.316-03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.23:49552 Mar 24 13:12:50 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 24 13:12:50 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 fusion Build/U1SJS34.2-92-10-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 24 13:12:50 volumio mpd[1339]: Mar 24 13:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 13:12:50 volumio systemd[1]: Started Music Player Daemon. Mar 24 13:12:50 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:50 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:50 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:12:50 volumio volumio[989]: info: Completed starting Core Plugins Mar 24 13:12:50 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:50 volumio volumio[989]: info: ----- MyVolumio plugins startup ---- Mar 24 13:12:50 volumio volumio[989]: info: ------------------------------------------- Mar 24 13:12:50 volumio volumio[989]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:50 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:12:50 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:50 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:50 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:50 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:50 volumio volumio[989]: info: Listing playlists Mar 24 13:12:50 volumio volumio[989]: info: MPD running with PID1339 Mar 24 13:12:50 volumio volumio[989]: ,establishing connection Mar 24 13:12:50 volumio volumio[989]: error: MPD error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: MPD error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: MPD error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: The expression evaluated to a falsy value: Mar 24 13:12:50 volumio volumio[989]: assert.ok(self.idling) Mar 24 13:12:50 volumio volumio[989]: error: updateQueue error: null Mar 24 13:12:50 volumio volumio[989]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 24 13:12:50 volumio sh[527]: timed out Mar 24 13:12:50 volumio dhcpcd[547]: timed out Mar 24 13:12:50 volumio sh[527]: dhcpcd exited Mar 24 13:12:50 volumio dhcpcd[547]: dhcpcd exited Mar 24 13:12:50 volumio sh[527]: ifup: failed to bring up eth0 Mar 24 13:12:50 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 24 13:12:50 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 24 13:12:53 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:12:53 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:53 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:53 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:12:53 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:53 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:53 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 13:12:53 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:53 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 24 13:12:53 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:53 volumio volumio[989]: info: Upmpdcli Daemon Started Mar 24 13:12:53 volumio volumio[1473]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 24 13:12:53 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:53.992-03:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 24 13:12:54 volumio dbus-daemon[609]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.15" (uid=0 pid=1163 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=718 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.724-03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.23:49552 @ 0x20aae70" latency=-2.640267626s timeout=10s Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.724-03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.728-03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.23:49552 @ 0x20aae70" latency=-2.639105108s platform=PLATFORM_ANDROID version=5.260209.0 Mar 24 13:12:54 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:54 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:54 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.733-03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" name=Volumio Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.737-03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" language=es Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.740-03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" timezone=America/Buenos_Aires Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.741-03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" available=true connected=false macAddress= ip4Address= ip6Address= Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.744-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" available=true connected=true macAddress=d8:3a:dd:21:ef:d9 ip4Address=192.168.1.61/24 ip6Address= ssid=agusquito Mar 24 13:12:54 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:54.746-03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" setupComplete=true Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:12:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:12:54 volumio volumio[989]: amixer -c 0 info | grep "bcm2835 Headphones" Mar 24 13:12:54 volumio volumio[989]: Card hw:0 'Headphones'/'bcm2835 Headphones' Mar 24 13:12:54 volumio volumio[989]: Invalid card number. Mar 24 13:12:54 volumio volumio[989]: amixer -c 5 info | grep "SMSL USB AUDIO" Mar 24 13:12:54 volumio volumio[989]: Invalid card number. Mar 24 13:12:54 volumio volumio[989]: amixer -c 7 info | grep "Dummy" Mar 24 13:12:55 volumio volumio[989]: Card hw:7 'Dummy'/'Dummy 1' Mar 24 13:12:55 volumio volumio[989]: Mixer name : 'Dummy Mixer' Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.012-03:00 level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=5 Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.013-03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" selectedOutputId=5 Mar 24 13:12:55 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:55 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.037-03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" currentVersion=3.912 latestVersion=3.912 Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.037-03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.23:49552 @ 0x20aae70" status=UPDATE_STATUS_NONE progress=0 Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.038-03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" userId= Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.038-03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" providers=3 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.041-03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" plugins=0 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.044-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.045-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.23:49552 @ 0x20aae70" id= title= Mar 24 13:12:55 volumio volumio5-onboarding[1163]: time=2026-03-24T13:12:55.063-03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.23:49552,00:00:00:00:00:00%01 @ 0x20aae70" latency=-2.663326089s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 24 13:12:55 volumio volumio[989]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 24 13:12:55 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:12:55 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:55 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:55 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:12:55 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:55 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:55 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 fusion Build/U1SJS34.2-92-10-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 24 13:12:55 volumio sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:12:55 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:55 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:55 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:12:55 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:12:55 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Mar 24 13:12:55 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.23 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 fusion Build/U1SJS34.2-92-10-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:12:55 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:55 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:55 volumio volumio[989]: info: Listing playlists Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 13:12:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:57 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:57 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:57 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:57 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 24 13:12:58 volumio volumio[989]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:12:58 volumio volumio[989]: info: Received Get System Info Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:12:58 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:12:58 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:12:58 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin bluetooth to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin multiroom to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin metavolumio to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin cd_controller to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 24 13:12:59 volumio volumio[989]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 24 13:13:00 volumio volumio[989]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 24 13:13:00 volumio volumio[989]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 24 13:13:00 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:00 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:00 volumio volumio[989]: info: Starting MyVolumio Remote Streaming Endpoints Mar 24 13:13:00 volumio volumio[989]: info: MyVolumio login type: Token Mar 24 13:13:00 volumio volumio[989]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 24 13:13:00 volumio volumio[989]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 24 13:13:01 volumio volumio[989]: info: Starting Streaming Service Transparent Proxy Mar 24 13:13:01 volumio volumio[989]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 24 13:13:01 volumio volumio[989]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 24 13:13:01 volumio volumio[989]: info: Streaming services startup Mar 24 13:13:01 volumio volumio[989]: info: Starting Streaming Daemon Mar 24 13:13:01 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 13:13:01 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:13:01 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Mar 24 13:13:01 volumio volumio[989]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 24 13:13:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 24 13:13:01 volumio volumio[989]: info: MyVolumio login type: Token Mar 24 13:13:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:13:01 volumio volumio[989]: error: Cannot start Volumio Streaming Daemon Mar 24 13:13:01 volumio volumio[989]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 13:13:01 volumio volumio[989]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 13:13:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:01 volumio volumio5-onboarding[1163]: time=2026-03-24T13:13:01.190-03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.23:49552,00:00:00:00:00:00%01 @ 0x20aae70" userId= Mar 24 13:13:01 volumio volumio[989]: STREAMING PROXY: Starting server on port 3245 Mar 24 13:13:01 volumio volumio[989]: Node JS runtime: 14 Mar 24 13:13:01 volumio volumio[989]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 24 13:13:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 13:13:02 volumio volumio[989]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 24 13:13:02 volumio volumio[989]: info: MyVolumio token set successfully Mar 24 13:13:02 volumio volumio[989]: info: MYVOLUMIO: Adding device Mar 24 13:13:02 volumio volumio[989]: info: MYVOLUMIO: Evaluating Server Mar 24 13:13:03 volumio volumio[989]: info: MyVolumio status changed Mar 24 13:13:03 volumio volumio[989]: info: Streaming services startup Mar 24 13:13:03 volumio volumio[989]: info: Starting Streaming Daemon Mar 24 13:13:03 volumio volumio[989]: info: Removing browser output: myVolumio user plan is not superstar Mar 24 13:13:03 volumio volumio[989]: info: Removing audio output: Mar 24 13:13:03 volumio volumio[989]: info: Stoppping Tunnel 1 Mar 24 13:13:03 volumio sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 13:13:03 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:13:03 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Mar 24 13:13:03 volumio volumio[989]: error: Cannot start Volumio Streaming Daemon Mar 24 13:13:03 volumio volumio[989]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 13:13:03 volumio volumio[989]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 13:13:03 volumio sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 24 13:13:03 volumio sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 13:13:03 volumio sudo[1853]: pam_unix(sudo:session): session closed for user root Mar 24 13:13:03 volumio volumio[989]: info: Remote SSH Stopped Mar 24 13:13:03 volumio volumio[989]: info: Setting Geolocation for MyVolumio to us4 Mar 24 13:13:03 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:03 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:03 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:03 volumio volumio[989]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: Listing playlists Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:13:04 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:04 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:04 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:04 volumio volumio[989]: info: Listing playlists Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 13:13:04 volumio volumio[989]: info: Updating MyVolumio device info Mar 24 13:13:04 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:05 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:05 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:05 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:05 volumio volumio[989]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 13:13:05 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:06 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:06 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:06 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:06 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:13:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 13:13:08 volumio volumio[989]: info: MYVOLUMIO: Adding device Mar 24 13:13:08 volumio volumio[989]: info: MYVOLUMIO: Evaluating Server Mar 24 13:13:08 volumio volumio[989]: info: Setting Geolocation for MyVolumio to us1 Mar 24 13:13:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:09 volumio volumio[989]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 13:13:09 volumio volumio[989]: info: Updating MyVolumio device info Mar 24 13:13:09 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:10 volumio volumio[989]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 13:13:12 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 24 13:13:12 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:13:14 volumio volumio[989]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 24 13:13:14 volumio volumio[989]: info: Received Get System Version Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:13:14 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:14 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:14 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:14 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:14 volumio volumio[1473]: ...............................................................................................................................................................................................................................++++ Mar 24 13:13:14 volumio volumio[989]: info: Starting Uninstall of plugin system_controller - ampswitch Mar 24 13:13:14 volumio volumio[989]: info: Uninstalling plugin ampswitch Mar 24 13:13:14 volumio volumio[989]: info: [ASDebug] Port: 24 Mar 24 13:13:14 volumio volumio[989]: info: [ASDebug] Inverted: false Mar 24 13:13:14 volumio volumio[989]: info: [ASDebug] Delay: 720 Mar 24 13:13:15 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:13:15 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 24 13:13:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:15 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:17 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:13:17 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:17 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:13:17 volumio volumio-remote-updater[591]: No test mode Mar 24 13:13:17 volumio volumio-remote-updater[591]: No alpha test mode Mar 24 13:13:17 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 24 13:13:17 volumio volumio[989]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 13:13:17 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:13:20 volumio volumio[989]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 24 13:13:20 volumio volumio[989]: info: Received Get System Version Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:13:20 volumio volumio[989]: info: Received Get System Info Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:13:20 volumio volumio[989]: info: Discovery: Getting this device information Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Mar 24 13:13:20 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Mar 24 13:13:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:13:21 volumio volumio[989]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 24 13:13:21 volumio volumio[989]: info: Completed starting MyVolumio Plugin Mar 24 13:13:21 volumio volumio[989]: [Metrics] CommandRouter: 40s 17.22ms Mar 24 13:13:21 volumio volumio[989]: info: CoreCommandRouter::volumiosetStartupVolume Mar 24 13:13:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:13:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:13:21 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Mar 24 13:13:21 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Mar 24 13:13:21 volumio volumio[989]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 24 13:13:21 volumio volumio[989]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 24 13:13:21 volumio volumio[989]: aplay: main:828: audio open error: No such device Mar 24 13:13:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 24 13:13:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:13:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 24 13:13:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 24 13:13:22 volumio volumio[1473]: ..................................................................................................++++ Mar 24 13:13:22 volumio volumio[1473]: e is 65537 (0x010001) Mar 24 13:13:22 volumio volumio[1473]: writing RSA key Mar 24 13:13:23 volumio volumio5-onboarding[1163]: time=2026-03-24T13:13:23.123-03:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 24 13:13:26 volumio ntpd[795]: Soliciting pool server 162.159.200.123 Mar 24 13:13:27 volumio ntpd[795]: Soliciting pool server 162.159.200.1 Mar 24 13:13:28 volumio volumio[989]: info: BOOT COMPLETED Mar 24 13:13:28 volumio ntpd[795]: Soliciting pool server 170.210.222.10 Mar 24 13:13:29 volumio volumio[989]: info: Starting Uninstall of plugin system_controller - ampswitch Mar 24 13:13:29 volumio volumio[989]: info: Uninstalling plugin ampswitch Mar 24 13:13:29 volumio volumio[989]: info: [ASDebug] Port: 24 Mar 24 13:13:29 volumio volumio[989]: info: [ASDebug] Inverted: false Mar 24 13:13:29 volumio volumio[989]: info: [ASDebug] Delay: 720 Mar 24 13:13:29 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:13:29 volumio volumio[989]: Error: EBADF: bad file descriptor, close Mar 24 13:13:29 volumio volumio[989]: at Object.closeSync (fs.js:439:3) Mar 24 13:13:29 volumio volumio[989]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Mar 24 13:13:29 volumio volumio[989]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Mar 24 13:13:29 volumio volumio[989]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Mar 24 13:13:29 volumio volumio[989]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Mar 24 13:13:29 volumio volumio[989]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Mar 24 13:13:29 volumio volumio[989]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 24 13:13:29 volumio volumio[989]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Mar 24 13:13:29 volumio volumio[989]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Mar 24 13:13:29 volumio volumio[989]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1271:45) Mar 24 13:13:29 volumio volumio[989]: at Socket.emit (events.js:315:20) Mar 24 13:13:29 volumio volumio[989]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 24 13:13:29 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 24 13:13:29 volumio volumio[989]: errno: -9, Mar 24 13:13:29 volumio volumio[989]: syscall: 'close', Mar 24 13:13:29 volumio volumio[989]: code: 'EBADF' Mar 24 13:13:29 volumio volumio[989]: } Mar 24 13:13:29 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:13:29 volumio ntpd[795]: Soliciting pool server 168.96.251.195 Mar 24 13:13:29 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-24 13:12 Mar 24 13:13:29 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"