-- Logs begin at Mon 2025-09-01 19:27:57 CEST, end at Mon 2025-09-01 19:29:06 CEST. -- Sep 01 19:28:00 volumio systemd[1]: Starting Permit User Sessions... Sep 01 19:28:00 volumio systemd[1]: Reached target Network is Online. Sep 01 19:28:00 volumio systemd[1]: Starting Samba NMB Daemon... Sep 01 19:28:00 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Sep 01 19:28:00 volumio systemd[1]: Starting /etc/rc.local Compatibility... Sep 01 19:28:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 19:28:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 01 19:28:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 01 19:28:00 volumio volumio-time-update[614]: volumio-time-update-util: Date not found in response Sep 01 19:28:00 volumio volumio-time-update[614]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 19:28:00 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 01 19:28:00 volumio systemd[1]: Started Permit User Sessions. Sep 01 19:28:00 volumio systemd[1]: Starting Hold until boot process finishes up... Sep 01 19:28:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 01 19:28:00 volumio ifplugd(eth0)[780]: ifplugd 0.28 initializing. Sep 01 19:28:00 volumio ifplugd(eth0)[780]: Using interface eth0/DC:A6:32:9F:DB:55 with driver (version: 6.6.62-v7l+) Sep 01 19:28:00 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 01 19:28:00 volumio ifplugd(eth0)[780]: Using detection mode: SIOCETHTOOL Sep 01 19:28:00 volumio ifplugd(eth0)[780]: Initialization complete, link beat not detected. Sep 01 19:28:00 volumio systemd[1]: Started Hold until boot process finishes up. Sep 01 19:28:00 volumio systemd[1]: plymouth-quit.service: Succeeded. Sep 01 19:28:00 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 01 19:28:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 209 (n/a). Sep 01 19:28:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 209 (n/a). Sep 01 19:28:00 volumio loadcpufreq[704]: Loading cpufreq kernel modules...done (none). Sep 01 19:28:00 volumio ntpd[728]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 19:28:00 volumio ntpd[728]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Sep 01 19:28:00 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 01 19:28:00 volumio ntpd[784]: proto: precision = 0.797 usec (-20) Sep 01 19:28:00 volumio systemd[1]: Started Network Time Service. Sep 01 19:28:00 volumio systemd[1]: iptables.service: Succeeded. Sep 01 19:28:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 01 19:28:00 volumio systemd[1]: Started Getty on tty1. Sep 01 19:28:00 volumio systemd[1]: Reached target Login Prompts. Sep 01 19:28:00 volumio ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 19:28:00 volumio ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 19:28:00 volumio ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 248 days ago Sep 01 19:28:00 volumio ntpd[784]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 19:28:00 volumio ntpd[784]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 19:28:00 volumio ntpd[784]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 19:28:00 volumio ntpd[784]: Listening on routing socket on fd #19 for interface updates Sep 01 19:28:00 volumio ntpd[784]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 19:28:00 volumio ntpd[784]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 19:28:00 volumio ifplugd[733]: Network Interface Plugging Daemon...start eth0...done. Sep 01 19:28:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. Sep 01 19:28:00 volumio haveged[588]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Sep 01 19:28:00 volumio haveged[588]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Sep 01 19:28:00 volumio haveged[588]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00096 Sep 01 19:28:00 volumio haveged[588]: haveged: fills: 0, generated: 0 Sep 01 19:28:00 volumio bluetoothd[727]: Bluetooth daemon 5.50 Sep 01 19:28:00 volumio bluetoothd[727]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 01 19:28:00 volumio systemd[1]: Started Bluetooth service. Sep 01 19:28:00 volumio systemd[1]: Reached target Bluetooth. Sep 01 19:28:00 volumio cpufrequtils[785]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 01 19:28:00 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 01 19:28:00 volumio bluetoothd[727]: Starting SDP server Sep 01 19:28:00 volumio bluetoothd[727]: Excluding (cli) sap Sep 01 19:28:00 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 01 19:28:00 volumio kernel: Bluetooth: BNEP filters: protocol multicast Sep 01 19:28:00 volumio kernel: Bluetooth: BNEP socket layer initialized Sep 01 19:28:00 volumio kernel: Bluetooth: MGMT ver 1.22 Sep 01 19:28:00 volumio bluetoothd[727]: Bluetooth management interface 1.22 initialized Sep 01 19:28:00 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=727 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 01 19:28:00 volumio sudo[729]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 19:28:00 volumio sudo[729]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:00 volumio sudo[729]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 01 19:28:00 volumio sudo[729]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:00 volumio bluetoothd[727]: Failed to set privacy: Rejected (0x0b) Sep 01 19:28:00 volumio systemd[1]: Starting Hostname Service... Sep 01 19:28:00 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 01 19:28:00 volumio systemd[1]: Started Hostname Service. Sep 01 19:28:01 volumio ntpd[784]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 19:28:01 volumio nmbd[739]: [2025/09/01 19:28:01.290357, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 01 19:28:01 volumio nmbd[739]: started asyncdns process 811 Sep 01 19:28:01 volumio nmbd[739]: [2025/09/01 19:28:01.291879, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 01 19:28:01 volumio nmbd[739]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 01 19:28:01 volumio nmbd[739]: [2025/09/01 19:28:01.292009, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 01 19:28:01 volumio nmbd[739]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 01 19:28:01 volumio wireless.js[610]: Cleaning previous... Sep 01 19:28:01 volumio sudo[819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 01 19:28:01 volumio sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:01 volumio sudo[819]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:01 volumio sudo[821]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 01 19:28:01 volumio sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:02 volumio sudo[821]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:02 volumio ntpd[784]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 19:28:02 volumio wireless.js[610]: Stopped aP Sep 01 19:28:02 volumio sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 01 19:28:02 volumio sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:02 volumio sudo[828]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 01 19:28:02 volumio sudo[830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 01 19:28:02 volumio sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:02 volumio sudo[830]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:02 volumio sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 01 19:28:02 volumio sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:02 volumio sudo[837]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:02 volumio sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 01 19:28:02 volumio sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:02 volumio mpd[808]: Sep 01 19:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 19:28:02 volumio systemd[1]: Started Music Player Daemon. Sep 01 19:28:02 volumio mpd_monitor.sh[620]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 01 19:28:03 volumio ntpd[784]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 19:28:03 volumio systemd[1]: systemd-rfkill.service: Succeeded. Sep 01 19:28:04 volumio ntpd[784]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 19:28:04 volumio sudo[839]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:04 volumio wireless.js[610]: SETTING APPROPRIATE REG DOMAIN: ES Sep 01 19:28:04 volumio sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 01 19:28:04 volumio volumio-remote-updater[632]: [2025-09-01 19:28:04] [info] asio async_connect error: system:111 (Connection refused) Sep 01 19:28:04 volumio volumio-remote-updater[632]: [2025-09-01 19:28:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 19:28:04 volumio volumio-remote-updater[632]: [2025-09-01 19:28:04] [error] handle_connect error: Underlying Transport Error Sep 01 19:28:04 volumio sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:04 volumio sudo[847]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:04 volumio sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set ES Sep 01 19:28:04 volumio sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:04 volumio sudo[849]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:04 volumio wireless.js[610]: SUCCESSFULLY SET NEW REGDOMAIN: ES Sep 01 19:28:04 volumio wireless.js[610]: Start wireless flow Sep 01 19:28:04 volumio wireless.js[610]: Stopped hotspot (if there).. Sep 01 19:28:04 volumio sudo[855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 01 19:28:04 volumio sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:04 volumio bthelper[699]: Changing power off succeeded Sep 01 19:28:04 volumio bthelper[699]: [CHG] Controller DC:A6:32:9F:DB:57 Powered: no Sep 01 19:28:04 volumio bthelper[699]: [CHG] Controller DC:A6:32:9F:DB:57 Discovering: no Sep 01 19:28:04 volumio bthelper[699]: [CHG] Controller DC:A6:32:9F:DB:57 Class: 0x00000000 Sep 01 19:28:04 volumio sudo[855]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:05 volumio sudo[857]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 01 19:28:05 volumio sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:05 volumio volumio-time-update[614]: volumio-time-update-util: Fetching time from Volumio... Sep 01 19:28:05 volumio volumio-time-update[614]: volumio-time-update-util: Date not found in response Sep 01 19:28:05 volumio volumio-time-update[614]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 19:28:05 volumio bthelper[699]: [CHG] Controller DC:A6:32:9F:DB:57 Class: 0x0000041c Sep 01 19:28:05 volumio bthelper[699]: Changing power on succeeded Sep 01 19:28:05 volumio sudo[857]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:05 volumio wireless.js[610]: DHCP IP Sep 01 19:28:05 volumio wireless.js[610]: Start ap Sep 01 19:28:05 volumio wpa_supplicant[871]: Successfully initialized wpa_supplicant Sep 01 19:28:05 volumio sudo[872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 01 19:28:05 volumio sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 01 19:28:05 volumio dhcpcd[873]: dev: loaded udev Sep 01 19:28:05 volumio dhcpcd-run-hooks[891]: wlan0: starting wpa_supplicant Sep 01 19:28:05 volumio dhcpcd-run-hooks[896]: wlan0: failed to start wpa_supplicant Sep 01 19:28:05 volumio dhcpcd-run-hooks[897]: 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 Sep 01 19:28:05 volumio dhcpcd[873]: wlan0: connected to Access Point `' Sep 01 19:28:05 volumio dhcpcd[873]: no interfaces have a carrier Sep 01 19:28:05 volumio dhcpcd[873]: forked to background, child pid 907 Sep 01 19:28:05 volumio dhcpcd[907]: eth0: waiting for carrier Sep 01 19:28:05 volumio dhcpcd[907]: wlan0: waiting for carrier Sep 01 19:28:05 volumio sudo[872]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:06 volumio wireless.js[610]: trying... Sep 01 19:28:06 volumio sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:06 volumio sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:06 volumio sudo[909]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:07 volumio wireless.js[610]: trying... Sep 01 19:28:07 volumio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:07 volumio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:07 volumio sudo[912]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:08 volumio wireless.js[610]: trying... Sep 01 19:28:08 volumio sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:08 volumio sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:08 volumio sudo[915]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:09 volumio wireless.js[610]: trying... Sep 01 19:28:09 volumio sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:09 volumio sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:09 volumio sudo[918]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:10 volumio volumio-time-update[614]: volumio-time-update-util: Fetching time from Volumio... Sep 01 19:28:10 volumio volumio-time-update[614]: volumio-time-update-util: Date not found in response Sep 01 19:28:10 volumio volumio-time-update[614]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 19:28:10 volumio wireless.js[610]: trying... Sep 01 19:28:10 volumio sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:10 volumio sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:10 volumio sudo[926]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:11 volumio wireless.js[610]: trying... Sep 01 19:28:11 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:11 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:11 volumio sudo[929]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:11 volumio wpa_supplicant[895]: wlan0: Trying to associate with SSID 'DIGIFIBRA-PLUS-b7T2' Sep 01 19:28:11 volumio dhcpcd[907]: wlan0: carrier acquired Sep 01 19:28:11 volumio dhcpcd[907]: wlan0: connected to Access Point `DIGIFIBRA-PLUS-b7T2' Sep 01 19:28:11 volumio wpa_supplicant[895]: wlan0: Associated with e8:6e:44:04:c8:f5 Sep 01 19:28:11 volumio wpa_supplicant[895]: wlan0: CTRL-EVENT-CONNECTED - Connection to e8:6e:44:04:c8:f5 completed [id=0 id_str=] Sep 01 19:28:11 volumio wpa_supplicant[895]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 01 19:28:11 volumio wpa_supplicant[895]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=ES Sep 01 19:28:11 volumio dhcpcd[907]: DUID 00:01:00:01:2d:f2:1e:24:dc:a6:32:9f:db:56 Sep 01 19:28:11 volumio dhcpcd[907]: wlan0: IAID 32:9f:db:56 Sep 01 19:28:11 volumio dhcpcd[907]: wlan0: adding address fe80::ea5c:294:b062:245a Sep 01 19:28:11 volumio dhcpcd[907]: ipv6_addaddr1: Permission denied Sep 01 19:28:11 volumio dhcpcd[907]: wlan0: carrier lost Sep 01 19:28:12 volumio dhcpcd[907]: wlan0: carrier acquired Sep 01 19:28:12 volumio dhcpcd[907]: wlan0: IAID 32:9f:db:56 Sep 01 19:28:12 volumio dhcpcd[907]: wlan0: soliciting an IPv6 router Sep 01 19:28:12 volumio volumio-remote-updater[632]: [2025-09-01 19:28:12] [info] asio async_connect error: system:111 (Connection refused) Sep 01 19:28:12 volumio volumio-remote-updater[632]: [2025-09-01 19:28:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 19:28:12 volumio volumio-remote-updater[632]: [2025-09-01 19:28:12] [error] handle_connect error: Underlying Transport Error Sep 01 19:28:12 volumio wireless.js[610]: trying... Sep 01 19:28:12 volumio sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:12 volumio sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:12 volumio sudo[945]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:12 volumio dhcpcd[907]: wlan0: soliciting a DHCP lease Sep 01 19:28:13 volumio wireless.js[610]: trying... Sep 01 19:28:13 volumio sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:13 volumio sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:13 volumio sudo[948]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:14 volumio wireless.js[610]: trying... Sep 01 19:28:14 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:14 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:14 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:14 volumio dhcpcd[907]: wlan0: offered 192.168.0.140 from 192.168.0.1 Sep 01 19:28:14 volumio dhcpcd[907]: wlan0: probing address 192.168.0.140/24 Sep 01 19:28:15 volumio volumio-time-update[614]: volumio-time-update-util: Fetching time from Volumio... Sep 01 19:28:15 volumio volumio-time-update[614]: volumio-time-update-util: Date not found in response Sep 01 19:28:15 volumio volumio-time-update[614]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 19:28:15 volumio wireless.js[610]: trying... Sep 01 19:28:15 volumio sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:15 volumio sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:15 volumio sudo[959]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:16 volumio wireless.js[610]: trying... Sep 01 19:28:16 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:16 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:16 volumio sudo[962]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:17 volumio wireless.js[610]: trying... Sep 01 19:28:17 volumio sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:17 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:17 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:18 volumio wireless.js[610]: trying... Sep 01 19:28:18 volumio sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:18 volumio sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:18 volumio sudo[968]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:19 volumio wireless.js[610]: trying... Sep 01 19:28:19 volumio sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:19 volumio sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:19 volumio sudo[971]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:20 volumio volumio-time-update[614]: volumio-time-update-util: Fetching time from Volumio... Sep 01 19:28:20 volumio volumio-time-update[614]: volumio-time-update-util: Date not found in response Sep 01 19:28:20 volumio volumio-time-update[614]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 19:28:20 volumio dhcpcd[907]: wlan0: leased 192.168.0.140 for 86400 seconds Sep 01 19:28:20 volumio avahi-daemon[606]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.140. Sep 01 19:28:20 volumio avahi-daemon[606]: New relevant interface wlan0.IPv4 for mDNS. Sep 01 19:28:20 volumio dhcpcd[907]: wlan0: adding route to 192.168.0.0/24 Sep 01 19:28:20 volumio avahi-daemon[606]: Registering new address record for 192.168.0.140 on wlan0.IPv4. Sep 01 19:28:20 volumio dhcpcd[907]: wlan0: adding default route via 192.168.0.1 Sep 01 19:28:20 volumio wireless.js[610]: trying... Sep 01 19:28:20 volumio sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 01 19:28:20 volumio sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:20 volumio sudo[999]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:20 volumio wireless.js[610]: Connected to: ----DIGIFIBRA-PLUS-b7T2 Sep 01 19:28:20 volumio wireless.js[610]: ---- Sep 01 19:28:20 volumio sudo[1002]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 19:28:20 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:20 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:20 volumio wireless.js[610]: ... joined AP, wlan0 IPv4 is 192.168.0.140, ipV6 is undefined Sep 01 19:28:20 volumio wireless.js[610]: It's done! AP Sep 01 19:28:20 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 01 19:28:20 volumio systemd[1]: Started Wireless Services. Sep 01 19:28:20 volumio systemd[1]: Started Volumio Backend Module. Sep 01 19:28:20 volumio systemd[1]: Started Volumio Cpu Tweaker. Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: Setting RT Priority for mpd Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: Setting MPD Affinity Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: pid 808's current affinity mask: f Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: pid 808's new affinity mask: 3 Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 01 19:28:20 volumio volumio-cpu-tweak[1008]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 01 19:28:20 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 01 19:28:21 volumio ntpd[784]: Listen normally on 3 wlan0 192.168.0.140:123 Sep 01 19:28:21 volumio ntpd[784]: new interface(s) found: waking up resolver Sep 01 19:28:21 volumio nmbd[739]: [2025/09/01 19:28:21.297457, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 19:28:21 volumio nmbd[739]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 01 19:28:21 volumio systemd[1]: Started Samba NMB Daemon. Sep 01 19:28:21 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 01 19:28:21 volumio winbindd[1024]: [2025/09/01 19:28:21.560443, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 01 19:28:21 volumio winbindd[1024]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 01 19:28:21 volumio winbindd[1024]: [2025/09/01 19:28:21.611047, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 19:28:21 volumio systemd[1]: Started Samba Winbind Daemon. Sep 01 19:28:21 volumio winbindd[1024]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 01 19:28:21 volumio systemd[1]: Starting Samba SMB Daemon... Sep 01 19:28:22 volumio smbd[1029]: [2025/09/01 19:28:22.153451, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 19:28:22 volumio systemd[1]: Started Samba SMB Daemon. Sep 01 19:28:22 volumio smbd[1029]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 01 19:28:22 volumio volumio-remote-updater[632]: [2025-09-01 19:28:22] [connect] Successful connection Sep 01 19:28:22 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:22 volumio volumio[1007]: info: ----- Volumio3 ---- Sep 01 19:28:22 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:22 volumio volumio[1007]: info: ----- System startup ---- Sep 01 19:28:22 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:23 volumio volumio[1007]: info: MYVOLUMIO Environment detected Sep 01 19:28:23 volumio volumio[1007]: info: Plugin folders cleanup Sep 01 19:28:23 volumio volumio[1007]: info: Scanning into folder /volumio/app/plugins/ Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category audio_interface Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category miscellanea Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category music_service Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category plugins.json Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category system_controller Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category user_interface Sep 01 19:28:23 volumio volumio[1007]: info: Scanning into folder /data/plugins/ Sep 01 19:28:23 volumio volumio[1007]: info: Scanning category music_service Sep 01 19:28:23 volumio volumio[1007]: info: Plugin folders cleanup completed Sep 01 19:28:23 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:23 volumio volumio[1007]: info: ----- Core plugins startup ---- Sep 01 19:28:23 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:23 volumio volumio[1007]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 19:28:23 volumio volumio[1007]: info: Adding plugin upnp to MyMusic Plugins Sep 01 19:28:23 volumio volumio[1007]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 19:28:23 volumio volumio[1007]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 19:28:23 volumio volumio[1007]: info: Loading plugins from folder /data/plugins/ Sep 01 19:28:23 volumio volumio[1007]: info: Loading plugin "system"... Sep 01 19:28:23 volumio volumio[1007]: info: Loading plugin "appearance"... Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "network"... Sep 01 19:28:24 volumio volumio[1007]: info: Refreshing Cached IP Addresses Sep 01 19:28:24 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 19:28:24 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:24 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:24 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 19:28:24 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:24 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "services"... Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "alsa_controller"... Sep 01 19:28:24 volumio sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 19:28:24 volumio sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:24 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "wizard"... Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "networkfs"... Sep 01 19:28:24 volumio volumio[1007]: info: Starting Udev Watcher for removable devices Sep 01 19:28:24 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Ivan6844,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.100/Multimedia/Music /mnt/NAS/IvanNas Sep 01 19:28:24 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:24 volumio volumio[1007]: info: Ignoring mount for partition: boot Sep 01 19:28:24 volumio volumio[1007]: info: Ignoring mount for partition: volumio Sep 01 19:28:24 volumio volumio[1007]: info: Ignoring mount for partition: volumio_data Sep 01 19:28:24 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "volumio_command_line_client"... Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "upnp"... Sep 01 19:28:24 volumio volumio[1007]: info: [1756747704566] Starting Upmpd Daemon Sep 01 19:28:24 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "my_music"... Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "mpd"... Sep 01 19:28:24 volumio kernel: Key type cifs.spnego registered Sep 01 19:28:24 volumio kernel: Key type cifs.idmap registered Sep 01 19:28:24 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Sep 01 19:28:24 volumio kernel: CIFS: Attempting to mount //192.168.0.100/Multimedia/Music Sep 01 19:28:24 volumio kernel: cryptd: max_cpu_qlen set to 1000 Sep 01 19:28:24 volumio volumio[1007]: info: Loading plugin "upnp_browser"... Sep 01 19:28:25 volumio volumio-time-update[614]: volumio-time-update-util: Fetching time from Volumio... Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "alarm-clock"... Sep 01 19:28:25 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "airplay_emulation"... Sep 01 19:28:25 volumio volumio[1007]: info: Starting Shairport Sync Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "last_100"... Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "webradio"... Sep 01 19:28:25 volumio volumio-time-update[614]: volumio-time-update-util: Setting system time to: 2025-09-01 19:28:52 Sep 01 19:28:25 volumio sudo[1123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-01 19:28:52 Sep 01 19:28:25 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:25 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1124 comm="timedatectl set-time 2025-09-01 19:28:52 ") Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "i2s_dacs"... Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "volumiodiscovery"... Sep 01 19:28:25 volumio systemd[1]: Starting Time & Date Service... Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** For more information see Sep 01 19:28:25 volumio node[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 19:28:25 volumio volumio[1007]: *** WARNING *** For more information see Sep 01 19:28:25 volumio node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 19:28:25 volumio node[1007]: *** WARNING *** For more information see Sep 01 19:28:25 volumio node[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 19:28:25 volumio node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 19:28:25 volumio node[1007]: *** WARNING *** For more information see Sep 01 19:28:25 volumio volumio[1007]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 19:28:25 volumio volumio[1007]: info: Discovery: Started advertising with name: Volumio Sep 01 19:28:25 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 19:28:25 volumio volumio[1007]: info: Loading plugin "spop"... Sep 01 19:28:25 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 19:28:25 volumio systemd[1]: Started Time & Date Service. Sep 01 19:28:52 volumio systemd-timedated[1125]: Changed local time to Mon Sep 1 19:28:52 2025 Sep 01 19:28:52 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:52 volumio volumio-time-update[614]: volumio-time-update-util: System time updated successfully. Sep 01 19:28:52 volumio systemd[1]: Started Volumio Time Update Utility. Sep 01 19:28:52 volumio systemd[1]: Reached target Multi-User System. Sep 01 19:28:52 volumio systemd[1]: Reached target Graphical Interface. Sep 01 19:28:52 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 01 19:28:52 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 01 19:28:52 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 01 19:28:52 volumio systemd[1]: Startup finished in 11.792s (kernel) + 29.497s (userspace) = 41.290s. Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "outputs"... Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "albumart"... Sep 01 19:28:53 volumio volumio[1007]: info: Plugin example_plugin is not enabled Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "inputs"... Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "updater_comm"... Sep 01 19:28:53 volumio volumio[1007]: info: Plugin mpdemulation is not enabled Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "rest_api"... Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "websocket"... Sep 01 19:28:53 volumio volumio[1007]: info: Starting Socket.io Server version 2.3.0 Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "podcast"... Sep 01 19:28:53 volumio volumio[1007]: Forking 3 albumart workers Sep 01 19:28:53 volumio volumio[1007]: info: ControllerPodcast::constructor Sep 01 19:28:53 volumio volumio[1007]: info: Loading plugin "radio_paradise"... Sep 01 19:28:53 volumio volumio[1007]: Starting albumart workers Sep 01 19:28:53 volumio volumio[1007]: Starting albumart workers Sep 01 19:28:53 volumio volumio[1007]: Starting albumart workers Sep 01 19:28:54 volumio volumio[1007]: info: Applying required configuration parameters for plugin radio_paradise Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734059] [RadioParadise] API delay: 5 Sep 01 19:28:54 volumio volumio[1007]: info: Loading i18n strings for locale es Sep 01 19:28:54 volumio volumio[1007]: Updating browse sources language Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::initPlayerControls Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: Express server listening on port 3000 Sep 01 19:28:54 volumio volumio[1007]: [Metrics] WebUI: 6s 104.73ms Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::resetVolumioState Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::getcurrentVolume Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::pushState Sep 01 19:28:54 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Sep 01 19:28:54 volumio sudo[1065]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:54 volumio volumio[1007]: info: Volumio Network Manager: Network status updated: 2 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: Reloading queue from file Sep 01 19:28:54 volumio volumio[1007]: info: Setting Device type: Raspberry PI Sep 01 19:28:54 volumio volumio[1007]: info: Completed loading Core Plugins Sep 01 19:28:54 volumio volumio[1007]: info: Preparing to generate the ALSA configuration file Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::setRepeat null single undefined Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::pushState Sep 01 19:28:54 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::setRandom null Sep 01 19:28:54 volumio volumio[1007]: info: CoreStateMachine::pushState Sep 01 19:28:54 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Sep 01 19:28:54 volumio volumio[1007]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 01 19:28:54 volumio volumio[1007]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Sep 01 19:28:54 volumio volumio[1007]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 01 19:28:54 volumio volumio[1007]: info: Asound.conf file unchanged, so no further update is needed Sep 01 19:28:54 volumio volumio[1007]: info: Output device has changed, restarting MPD Sep 01 19:28:54 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 19:28:54 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:54 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:54 volumio volumio[1007]: info: Output device has changed, restarting Shairport Sync Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:54 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 19:28:54 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:54 volumio systemd[1]: Stopping Music Player Daemon... Sep 01 19:28:54 volumio volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 19:28:54 volumio volumio[1007]: info: ___________ START PLUGINS ___________ Sep 01 19:28:54 volumio volumio[1007]: info: ControllerMpd::onStart: Initializing MPD Sep 01 19:28:54 volumio volumio[1007]: info: Creating MPD Configuration file Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734684] CoreMusicLibrary::Adding element Media Servers Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 19:28:54 volumio sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:54 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 19:28:54 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:54 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:54 volumio volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:54 volumio systemd[1]: mpd.service: Succeeded. Sep 01 19:28:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 19:28:54 volumio volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734775] CoreMusicLibrary::Adding element Last_100 Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734777] CoreMusicLibrary::Adding element Webradio Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 19:28:54 volumio volumio[1007]: info: Initializing BBC Radios Sep 01 19:28:54 volumio systemd[1]: Starting Music Player Daemon... Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:54 volumio volumio[1007]: info: Creating Spotify config file Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:54 volumio sudo[1207]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 19:28:54 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:54 volumio sudo[1207]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734927] CoreMusicLibrary::Adding element Podcast Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio volumio[1007]: Cannot find translation for source Podcast Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:54 volumio volumio[1007]: info: [1756747734932] CoreMusicLibrary::Adding element Radio Paradise Sep 01 19:28:54 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:54 volumio volumio[1007]: Cannot find translation for source Podcast Sep 01 19:28:54 volumio volumio[1007]: Cannot find translation for source Radio Paradise Sep 01 19:28:54 volumio volumio[1007]: info: Volumio Calling Home Sep 01 19:28:55 volumio volumio[1007]: info: MPD Permissions set Sep 01 19:28:55 volumio volumio[1007]: info: MPD Permissions set Sep 01 19:28:55 volumio volumio[1007]: info: Spotify config file written Sep 01 19:28:55 volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 19:28:55 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:55 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:55 volumio systemd[1]: Started go-librespot Daemon. Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 19:28:55 volumio volumio[1007]: info: No need to fix Spotify hosts Sep 01 19:28:55 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio go-librespot[1230]: go-librespot daemon starting... Sep 01 19:28:55 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 01 19:28:55 volumio volumio[1007]: info: Volumio called home Sep 01 19:28:55 volumio volumio[1007]: info: Starting Shairport Sync Sep 01 19:28:55 volumio volumio[1007]: info: Starting Shairport Sync Sep 01 19:28:55 volumio volumio[1007]: info: Starting Shairport Sync Sep 01 19:28:55 volumio sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 19:28:55 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:55 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 19:28:55 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 19:28:55 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 19:28:55 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 01 19:28:55 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:28:55 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 01 19:28:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 19:28:55 volumio volumio[1007]: info: Discovery: adding 6919a5a6-bb70-49b5-9b27-ed0265246e37 Sep 01 19:28:55 volumio volumio[1007]: info: Discovery: Found device Volumio Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 01 19:28:55 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:28:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 19:28:55 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio volumio[1007]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 19:28:55 volumio volumio[1007]: SPOTIFY: BQBlNaNM2fpq3MBheHFG9o5CdT56t7-Q4zywuvNK3Dk3u8iZqG4fVRGpNuN6dzGIkO1wALGbzmTVs7wMeZBSbRJaFgg6SYJuANyjhoOQUtHatxmgqa05EhWGlwetJOXp_AVrHdVysy7LBWRmsdov0bgWlaOOT5b6r347hQZQ-3pYkVTXMA3nhhnuXroxRyVK2COmZ7BqmS2ZphJl50mAVOW3x5gVw8LY4JTyKK4qiDEQLg6M2F340xqYuoEEcj_D9rvoaFXAT91T8XP6zMoFYC01gGU3MtwpekaS4Q Sep 01 19:28:55 volumio volumio[1007]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 19:28:55 volumio volumio[1007]: info: New Spotify access token = BQBlNaNM2fpq3MBheHFG9o5CdT56t7-Q4zywuvNK3Dk3u8iZqG4fVRGpNuN6dzGIkO1wALGbzmTVs7wMeZBSbRJaFgg6SYJuANyjhoOQUtHatxmgqa05EhWGlwetJOXp_AVrHdVysy7LBWRmsdov0bgWlaOOT5b6r347hQZQ-3pYkVTXMA3nhhnuXroxRyVK2COmZ7BqmS2ZphJl50mAVOW3x5gVw8LY4JTyKK4qiDEQLg6M2F340xqYuoEEcj_D9rvoaFXAT91T8XP6zMoFYC01gGU3MtwpekaS4Q Sep 01 19:28:55 volumio volumio[1007]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 19:28:55 volumio volumio[1007]: info: Shairport-Sync Started Sep 01 19:28:55 volumio volumio[1007]: Error adding Membership: Error: addMembership EINVAL Sep 01 19:28:55 volumio volumio[1007]: info: Shairport-Sync Started Sep 01 19:28:55 volumio volumio[1007]: info: Shairport-Sync Started Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=info msg="running go-librespot 0.3.2" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="no app state found" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="stored credentials not found" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=info msg="generated new device id: 592c9290186b3e15acb82948b6e7579009cb7636" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 19:28:55 volumio volumio[1007]: SPOTIFY: User informations: {"country":"ES","display_name":"Iván Castaño","email":"hansen5815@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/hansen5815"},"followers":{"href":null,"total":10},"href":"https://api.spotify.com/v1/users/hansen5815","id":"hansen5815","images":[{"height":300,"url":"https://scontent-ord5-2.xx.fbcdn.net/v/t39.30808-1/441899507_7648279888598280_6097359537722790580_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=102&ccb=1-7&_nc_sid=79bf43&_nc_ohc=ZUNMhABlM-wQ7kNvwEGLHSJ&_nc_oc=AdlaWYXSbEPxuTuw4PvZ4KUKgmtutqdNm-R6pRwbMsgNvmfKgTCz2l6IKPtQvGyxEI7xVekXUBUjmTGKNZbfVFcv&_nc_zt=24&_nc_ht=scontent-ord5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=HmVyNubWDLSIIy_t6U8n5w&oh=00_AfVxXt_4OYI8WyKgPRaJ-LRhxWWKw-G54E1YztugDwPU1Q&oe=68BB5CB5","width":300},{"height":64,"url":"https://scontent-ord5-2.xx.fbcdn.net/v/t39.30808-1/441899507_7648279888598280_6097359537722790580_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=102&ccb=1-7&_nc_sid=28885b&_nc_ohc=ZUNMhABlM-wQ7kNvwEGLHSJ&_nc_oc=AdlaWYXSbEPxuTuw4PvZ4KUKgmtutqdNm-R6pRwbMsgNvmfKgTCz2l6IKPtQvGyxEI7xVekXUBUjmTGKNZbfVFcv&_nc_zt=24&_nc_ht=scontent-ord5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=HmVyNubWDLSIIy_t6U8n5w&oh=00_AfXPdLAN6chu72T-guOKsHKvrZRi0A-QQCi5hcPRG68hKQ&oe=68BB5CB5","width":64}],"product":"premium","type":"user","uri":"spotify:user:hansen5815"} Sep 01 19:28:55 volumio volumio[1007]: info: Spotify Successfully logged in Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 19:28:55 volumio volumio[1007]: info: [1756747735610] CoreMusicLibrary::Adding element Spotify Sep 01 19:28:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:28:55 volumio volumio[1007]: Cannot find translation for source Podcast Sep 01 19:28:55 volumio volumio[1007]: Cannot find translation for source Radio Paradise Sep 01 19:28:55 volumio volumio[1007]: Cannot find translation for source Spotify Sep 01 19:28:55 volumio mpd[1221]: Sep 01 19:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 19:28:55 volumio systemd[1]: Started Music Player Daemon. Sep 01 19:28:55 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root Sep 01 19:28:55 volumio volumio[1007]: error: updateQueue error: null Sep 01 19:28:55 volumio volumio[1007]: info: Completed starting Core Plugins Sep 01 19:28:55 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:55 volumio volumio[1007]: info: ----- MyVolumio plugins startup ---- Sep 01 19:28:55 volumio volumio[1007]: info: ------------------------------------------- Sep 01 19:28:55 volumio volumio[1007]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 19:28:55 volumio volumio[1007]: error: updateQueue error: null Sep 01 19:28:55 volumio volumio[1007]: info: MPD running with PID1221 Sep 01 19:28:55 volumio volumio[1007]: ,establishing connection Sep 01 19:28:55 volumio volumio[1007]: error: updateQueue error: null Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=info msg="zeroconf server listening on port 43975" Sep 01 19:28:55 volumio go-librespot[1230]: time="2025-09-01T19:28:55+02:00" level=debug msg="obtained new client token: AABtptqms2NOZOPCUGaf6WM6h6gShRFVbr2JWF6sj498QQPTIwb+hIfR3XFdKRExZrwE6/WnxVDOUxLS8FmUVuX/4Q4vPeN7k7LGS3jsRJJTmOozKiwXdmKs6CxJcn5+XCn0eJwsTVlPTT/RoNgLVK8wLfPE02Ji3QP5Bgn0coONqqogPqxQ+z1msua7Is0Hj62nWbM7/9FrFe31/RyM6hSnLa8NpLrl43tQLdoNo1ouzXP7TpBGfwFLCQ==" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="completed keyexchange" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="completed challenge" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=info msg="authenticated AP" username="ha******15" Sep 01 19:28:56 volumio sh[528]: timed out Sep 01 19:28:56 volumio dhcpcd[577]: timed out Sep 01 19:28:56 volumio sh[528]: dhcpcd exited Sep 01 19:28:56 volumio dhcpcd[577]: dhcpcd exited Sep 01 19:28:56 volumio sh[528]: ifup: failed to bring up eth0 Sep 01 19:28:56 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 01 19:28:56 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=info msg="authenticated Login5" username="ha******15" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="stored credentials" username="ha******15" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="initializing zeroconf session" username="ha******15" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="dealer connection opened" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=trace msg="starting accesspoint recv loop" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=trace msg="starting dealer recv loop" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=trace msg="received accesspoint ping" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="received connection id: OTQ5ZDdkZTgtNGNk...OTkxNjA5MkMwNQ==" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=trace msg="received accesspoint pong ack" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="update volume requested to 65535/65535" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 01 19:28:56 volumio go-librespot[1230]: time="2025-09-01T19:28:56+02:00" level=trace msg="emitting websocket event: volume" Sep 01 19:28:57 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 01 19:28:58 volumio volumio[1007]: info: go-librespot daemon successfully initialized Sep 01 19:29:00 volumio volumio[1007]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 19:29:01 volumio sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 19:29:01 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:29:01 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Sep 01 19:29:01 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 19:29:01 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:29:01 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Sep 01 19:29:01 volumio sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 19:29:01 volumio sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:29:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 19:29:01 volumio sudo[1287]: pam_unix(sudo:session): session closed for user root Sep 01 19:29:01 volumio volumio[1007]: info: Upmpdcli Daemon Started Sep 01 19:29:01 volumio volumio[1289]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 01 19:29:01 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Sep 01 19:29:01 volumio go-librespot[1230]: time="2025-09-01T19:29:01+02:00" level=debug msg="new websocket client" Sep 01 19:29:01 volumio volumio[1007]: info: Connection to go-librespot Websocket established Sep 01 19:29:03 volumio volumio-remote-updater[632]: [2025-09-01 19:29:03] [connect] Successful connection Sep 01 19:29:03 volumio volumio-remote-updater[632]: [2025-09-01 19:29:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756747743 101 Sep 01 19:29:03 volumio volumio[1007]: 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 Sep 01 19:29:03 volumio go-librespot[1230]: time="2025-09-01T19:29:03+02:00" level=debug msg="handling transfer player command from 999c8c138c2ff2aba7f52130a369781be72aa577" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="resolved context of track" uri="spotify:artist:6ra4GIOgCZQZMOaUECftGN" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:artist:6ra4GIOgCZQZMOaUECftGN" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="fetched new page 1 with 25 items (list: 35)" uri="spotify:artist:6ra4GIOgCZQZMOaUECftGN" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="loading track (paused: false, position: 108765ms)" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="emitting websocket event: will_play" Sep 01 19:29:04 volumio volumio[1007]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3H1nEYMvH7yMkE9Qr4uhHa","play_origin":"com.spotify.service.freetierartist"}} Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 19:29:04 volumio volumio[1007]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3184" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="selected format OGG_VORBIS_320 (bac708407a7b3c61bfb9e578cfc5a24c78715f51)" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="requested aes key for file bac708407a7b3c61bfb9e578cfc5a24c78715f51, gid: 3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2036" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="fetched first chunk of 16, total size is 8341332 bytes" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="fetched chunk 10/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="seek to 108765ms (diff: 183ms, samples: 4796536, bytes: 5444327)" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="created new output device" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=info msg="loaded track \"Bobby Brown Goes Down\" (paused: false, position: 108765ms, duration: 169066ms, prefetched: false)" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="scheduling prefetch in 30s" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="emitting websocket event: metadata" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=trace msg="emitting websocket event: active" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="sending successful reply for dealer request" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="fetched chunk 11/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:04 volumio go-librespot[1230]: time="2025-09-01T19:29:04+02:00" level=debug msg="fetched chunk 13/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:05 volumio go-librespot[1230]: time="2025-09-01T19:29:05+02:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:05 volumio go-librespot[1230]: time="2025-09-01T19:29:05+02:00" level=debug msg="fetched chunk 12/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:05 volumio go-librespot[1230]: time="2025-09-01T19:29:05+02:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:3H1nEYMvH7yMkE9Qr4uhHa" Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 19:29:05 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:29:05 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 19:29:05 volumio volumio[1007]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 19:29:05 volumio volumio[1007]: info: MyVolumio login type: Token Sep 01 19:29:05 volumio go-librespot[1230]: time="2025-09-01T19:29:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 19:29:05 volumio go-librespot[1230]: time="2025-09-01T19:29:05+02:00" level=trace msg="emitting websocket event: playing" Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 19:29:05 volumio volumio[1007]: info: Starting Streaming Service Transparent Proxy Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 19:29:05 volumio volumio[1007]: info: Streaming services startup Sep 01 19:29:05 volumio volumio[1007]: info: Starting Streaming Daemon Sep 01 19:29:05 volumio sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 19:29:05 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:29:05 volumio volumio[1007]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 19:29:06 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Sep 01 19:29:06 volumio volumio[1007]: info: Getting Spotify volume Sep 01 19:29:06 volumio volumio[1007]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3H1nEYMvH7yMkE9Qr4uhHa","name":"Bobby Brown Goes Down","artist_names":["Frank Zappa"],"album_name":"Sheik Yerbouti","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0271984d531980f54727d10aea","position":108765,"duration":169066,"release_date":"year:2012 month:1 day:1","track_number":9,"disc_number":1}} Sep 01 19:29:06 volumio volumio[1007]: SPOTIFY: received: {"type":"active","data":null} Sep 01 19:29:06 volumio volumio[1007]: info: Aligning Spotify Volume to Volumio Volume Sep 01 19:29:06 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 01 19:29:06 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Sep 01 19:29:06 volumio volumio[1007]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3H1nEYMvH7yMkE9Qr4uhHa","resume":false,"play_origin":"com.spotify.service.freetierartist"}} Sep 01 19:29:06 volumio volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 19:29:06 volumio volumio[1007]: TypeError: Cannot read property 'service' of undefined Sep 01 19:29:06 volumio volumio[1007]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Sep 01 19:29:06 volumio volumio[1007]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Sep 01 19:29:06 volumio volumio[1007]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Sep 01 19:29:06 volumio volumio[1007]: at WebSocket.emit (events.js:315:20) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.emit (events.js:315:20) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Sep 01 19:29:06 volumio volumio[1007]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Sep 01 19:29:06 volumio volumio[1007]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 01 19:29:06 volumio volumio[1007]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Sep 01 19:29:06 volumio volumio[1007]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Sep 01 19:29:06 volumio volumio[1007]: at Socket.emit (events.js:315:20) Sep 01 19:29:06 volumio volumio[1007]: at addChunk (internal/streams/readable.js:309:12) Sep 01 19:29:06 volumio volumio[1007]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 01 19:29:06 volumio volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 19:29:06 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 19:28 Sep 01 19:29:06 volumio sudo[1329]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"