-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Tue 2026-04-14 08:17:35 MSK. -- Apr 14 08:16:00 volumio sudo[855]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio wireless.js[590]: Stopped aP Apr 14 08:16:00 volumio sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 14 08:16:00 volumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 14 08:16:00 volumio sudo[862]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 14 08:16:00 volumio sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio sudo[864]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 14 08:16:00 volumio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio sudo[871]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 14 08:16:00 volumio sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 14 08:16:00 volumio sudo[873]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio wireless.js[590]: SETTING APPROPRIATE REG DOMAIN: RU Apr 14 08:16:00 volumio sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 14 08:16:00 volumio sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio sudo[878]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set RU Apr 14 08:16:00 volumio sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:00 volumio sudo[880]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:00 volumio wireless.js[590]: SUCCESSFULLY SET NEW REGDOMAIN: RU Apr 14 08:16:00 volumio ntpd[824]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 14 08:16:00 volumio sudo[887]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 14 08:16:00 volumio sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:01 volumio sudo[887]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:01 volumio sudo[889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 14 08:16:01 volumio sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:01 volumio sudo[889]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:01 volumio avahi-daemon[594]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 14 08:16:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 14 08:16:01 volumio avahi-daemon[594]: New relevant interface wlan0.IPv4 for mDNS. Apr 14 08:16:01 volumio avahi-daemon[594]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 14 08:16:01 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 14 08:16:01 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 14 08:16:01 volumio dnsmasq[896]: dnsmasq: syntax check OK. Apr 14 08:16:01 volumio hostapd[895]: Configuration file: /etc/hostapd/hostapd.conf Apr 14 08:16:01 volumio hostapd[895]: wlan0: Could not connect to kernel driver Apr 14 08:16:01 volumio hostapd[895]: Using interface wlan0 with hwaddr b8:27:eb:a8:2f:a6 and ssid "Volumio-1C844" Apr 14 08:16:01 volumio dnsmasq[902]: started, version 2.80 cachesize 150 Apr 14 08:16:01 volumio dnsmasq[902]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 14 08:16:01 volumio dnsmasq-dhcp[902]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 14 08:16:01 volumio dnsmasq[902]: reading /etc/resolv.conf Apr 14 08:16:01 volumio dnsmasq[902]: using nameserver 208.67.222.222#53 Apr 14 08:16:01 volumio dnsmasq[902]: using nameserver 208.67.220.220#53 Apr 14 08:16:01 volumio dnsmasq[902]: read /etc/hosts - 5 addresses Apr 14 08:16:01 volumio hostapd[895]: wlan0: interface state UNINITIALIZED->ENABLED Apr 14 08:16:01 volumio hostapd[895]: wlan0: AP-ENABLED Apr 14 08:16:01 volumio dhcpcd[637]: wlan0: carrier acquired Apr 14 08:16:01 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 14 08:16:01 volumio dhcpcd[637]: wlan0: IAID eb:a8:2f:a6 Apr 14 08:16:01 volumio dhcpcd[637]: wlan0: adding address fe80::ba27:ebff:fea8:2fa6 Apr 14 08:16:01 volumio dhcpcd[637]: ipv6_addaddr1: Permission denied Apr 14 08:16:01 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 14 08:16:01 volumio systemd[1]: Reached target Host and Network Name Lookups. Apr 14 08:16:01 volumio iwconfig[911]: Error for wireless request "Set Power Management" (8B2C) : Apr 14 08:16:01 volumio iwconfig[911]: SET failed on device wlan0 ; Invalid argument. Apr 14 08:16:01 volumio ntpd[824]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 14 08:16:01 volumio systemd[1]: wireless.service: Succeeded. Apr 14 08:16:01 volumio systemd[1]: Started Wireless Services. Apr 14 08:16:01 volumio systemd[1]: Started Volumio Backend Module. Apr 14 08:16:01 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 14 08:16:02 volumio dhcpcd[637]: wlan0: soliciting an IPv6 router Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: Setting RT Priority for mpd Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: pid 35's current scheduling policy: SCHED_OTHER Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: pid 35's current scheduling priority: 0 Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: Setting MPD Affinity Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: pid 3's current affinity mask: f Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 14 08:16:02 volumio volumio-cpu-tweak[913]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 14 08:16:02 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 14 08:16:02 volumio volumio-remote-updater[586]: [2026-04-14 08:16:02] [info] asio async_connect error: system:111 (Connection refused) Apr 14 08:16:02 volumio volumio-remote-updater[586]: [2026-04-14 08:16:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 14 08:16:02 volumio volumio-remote-updater[586]: [2026-04-14 08:16:02] [error] handle_connect error: Underlying Transport Error Apr 14 08:16:02 volumio bluetoothd[705]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Apr 14 08:16:02 volumio bthelper[645]: [NEW] Media /org/bluez/hci0 Apr 14 08:16:02 volumio bthelper[645]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 14 08:16:02 volumio bthelper[645]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 14 08:16:02 volumio bthelper[645]: hci0 class of device changed: 0x000000 Apr 14 08:16:02 volumio bthelper[645]: hci0 new_settings: bondable ssp br/edr le secure-conn Apr 14 08:16:02 volumio bthelper[645]: [CHG] Controller B8:27:EB:57:D0:59 PowerState: on-disabling Apr 14 08:16:02 volumio bthelper[645]: Changing power off succeeded Apr 14 08:16:02 volumio volumio-time-update[574]: volumio-time-update-util: Fetching time from Volumio... Apr 14 08:16:02 volumio bthelper[645]: [NEW] Media /org/bluez/hci0 Apr 14 08:16:02 volumio bthelper[645]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 14 08:16:02 volumio bthelper[645]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 14 08:16:02 volumio bthelper[645]: [CHG] Controller B8:27:EB:57:D0:59 PowerState: off-enabling Apr 14 08:16:02 volumio volumio-time-update[574]: volumio-time-update-util: Date not found in response Apr 14 08:16:02 volumio volumio-time-update[574]: volumio-time-update-util: Retrying in 5 seconds... Apr 14 08:16:02 volumio bthelper[645]: hci0 class of device changed: 0x00041c Apr 14 08:16:02 volumio bthelper[645]: [CHG] Controller B8:27:EB:57:D0:59 Class: 0x0000041c (1052) Apr 14 08:16:02 volumio bthelper[645]: hci0 new_settings: powered bondable ssp br/edr le secure-conn Apr 14 08:16:02 volumio bthelper[645]: Changing power on succeeded Apr 14 08:16:02 volumio dhcpcd[637]: eth0: using static address 192.168.3.94/24 Apr 14 08:16:02 volumio dhcpcd[637]: eth0: adding route to 192.168.3.0/24 Apr 14 08:16:02 volumio dhcpcd[637]: eth0: adding default route via 192.168.3.1 Apr 14 08:16:02 volumio avahi-daemon[594]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.94. Apr 14 08:16:02 volumio avahi-daemon[594]: New relevant interface eth0.IPv4 for mDNS. Apr 14 08:16:02 volumio avahi-daemon[594]: Registering new address record for 192.168.3.94 on eth0.IPv4. Apr 14 08:16:02 volumio dnsmasq[902]: reading /etc/resolv.conf Apr 14 08:16:02 volumio dnsmasq[902]: using nameserver 192.168.3.1#53 Apr 14 08:16:02 volumio dnsmasq[902]: using nameserver 208.67.222.222#53 Apr 14 08:16:02 volumio dnsmasq[902]: using nameserver 208.67.222.222#53 Apr 14 08:16:02 volumio dnsmasq[902]: using nameserver 208.67.220.220#53 Apr 14 08:16:02 volumio dhcpcd[637]: wlan0: soliciting a DHCP lease Apr 14 08:16:03 volumio nmbd[785]: [2026/04/14 08:16:03.881608, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 14 08:16:03 volumio systemd[1]: Started Samba NMB Daemon. Apr 14 08:16:03 volumio nmbd[785]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 14 08:16:03 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 14 08:16:03 volumio ntpd[824]: Listen normally on 3 eth0 192.168.3.94:123 Apr 14 08:16:03 volumio ntpd[824]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 14 08:16:03 volumio ntpd[824]: new interface(s) found: waking up resolver Apr 14 08:16:04 volumio winbindd[955]: [2026/04/14 08:16:04.301413, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 14 08:16:04 volumio winbindd[955]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 14 08:16:04 volumio winbindd[955]: [2026/04/14 08:16:04.342919, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 14 08:16:04 volumio systemd[1]: Started Samba Winbind Daemon. Apr 14 08:16:04 volumio winbindd[955]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 14 08:16:04 volumio systemd[1]: Starting Samba SMB Daemon... Apr 14 08:16:05 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:05 volumio volumio[912]: info: ----- Volumio3 ---- Apr 14 08:16:05 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:05 volumio volumio[912]: info: ----- System startup ---- Apr 14 08:16:05 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:05 volumio smbd[960]: [2026/04/14 08:16:05.037631, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 14 08:16:05 volumio smbd[960]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 14 08:16:05 volumio systemd[1]: Started Samba SMB Daemon. Apr 14 08:16:06 volumio volumio[912]: info: MYVOLUMIO Environment detected Apr 14 08:16:06 volumio volumio[912]: info: Plugin folders cleanup Apr 14 08:16:06 volumio volumio[912]: info: Scanning into folder /volumio/app/plugins/ Apr 14 08:16:06 volumio volumio[912]: info: Scanning category audio_interface Apr 14 08:16:06 volumio volumio[912]: info: Scanning category miscellanea Apr 14 08:16:06 volumio volumio[912]: info: Scanning category music_service Apr 14 08:16:06 volumio volumio[912]: info: Scanning category plugins.json Apr 14 08:16:06 volumio volumio[912]: info: Scanning category system_controller Apr 14 08:16:06 volumio volumio[912]: info: Scanning category user_interface Apr 14 08:16:06 volumio volumio[912]: info: Scanning into folder /data/plugins/ Apr 14 08:16:06 volumio volumio[912]: info: Plugin folders cleanup completed Apr 14 08:16:06 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:06 volumio volumio[912]: info: ----- Core plugins startup ---- Apr 14 08:16:06 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:06 volumio volumio[912]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 08:16:06 volumio volumio[912]: info: Adding plugin upnp to MyMusic Plugins Apr 14 08:16:06 volumio volumio[912]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 08:16:06 volumio volumio[912]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 08:16:06 volumio volumio[912]: info: Loading plugins from folder /data/plugins/ Apr 14 08:16:06 volumio volumio[912]: info: Loading plugin "system"... Apr 14 08:16:06 volumio volumio[912]: info: Loading plugin "appearance"... Apr 14 08:16:07 volumio volumio-time-update[574]: volumio-time-update-util: Fetching time from Volumio... Apr 14 08:16:07 volumio volumio-time-update[574]: volumio-time-update-util: Setting system time to: 2026-04-14 08:16:28 Apr 14 08:16:07 volumio sudo[986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-14 08:16:28 Apr 14 08:16:07 volumio sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:07 volumio dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=987 comm="timedatectl set-time 2026-04-14 08:16:28 ") Apr 14 08:16:07 volumio systemd[1]: Starting Time & Date Service... Apr 14 08:16:07 volumio dhcpcd[637]: wlan0: probing for an IPv4LL address Apr 14 08:16:07 volumio dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 14 08:16:07 volumio systemd[1]: Started Time & Date Service. Apr 14 08:16:28 volumio systemd-timedated[988]: Changed local time to Tue Apr 14 08:16:28 2026 Apr 14 08:16:28 volumio sudo[986]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:28 volumio volumio-time-update[574]: volumio-time-update-util: System time updated successfully. Apr 14 08:16:28 volumio systemd[1]: Started Volumio Time Update Utility. Apr 14 08:16:28 volumio systemd[1]: Reached target Multi-User System. Apr 14 08:16:28 volumio systemd[1]: Reached target Graphical Interface. Apr 14 08:16:28 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 14 08:16:28 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 14 08:16:28 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 14 08:16:28 volumio systemd[1]: Startup finished in 13.620s (kernel) + 16.286s (userspace) = 29.907s. Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "network"... Apr 14 08:16:28 volumio volumio[912]: info: Refreshing Cached IP Addresses Apr 14 08:16:28 volumio sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 08:16:28 volumio sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:28 volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 08:16:28 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:28 volumio sudo[991]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:28 volumio sudo[993]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "services"... Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "volumio5onboarding"... Apr 14 08:16:28 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 08:16:28 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "alsa_controller"... Apr 14 08:16:28 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "wizard"... Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "networkfs"... Apr 14 08:16:28 volumio volumio[912]: info: Starting Udev Watcher for removable devices Apr 14 08:16:28 volumio volumio[912]: info: Ignoring mount for partition: boot Apr 14 08:16:28 volumio volumio[912]: info: Ignoring mount for partition: volumio Apr 14 08:16:28 volumio volumio[912]: info: Ignoring mount for partition: volumio_data Apr 14 08:16:28 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "volumio_command_line_client"... Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "upnp"... Apr 14 08:16:28 volumio volumio[912]: info: [1776143788624] Starting Upmpd Daemon Apr 14 08:16:28 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "my_music"... Apr 14 08:16:28 volumio volumio[912]: info: Loading plugin "mpd"... Apr 14 08:16:29 volumio volumio[912]: info: Loading plugin "upnp_browser"... Apr 14 08:16:29 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:29 volumio volumio[912]: info: Starting UPNP Browser Apr 14 08:16:29 volumio volumio[912]: info: Loading plugin "alarm-clock"... Apr 14 08:16:29 volumio volumio[912]: info: Loading plugin "airplay_emulation"... Apr 14 08:16:29 volumio volumio[912]: info: Starting Shairport Sync Apr 14 08:16:29 volumio volumio[912]: info: Loading plugin "last_100"... Apr 14 08:16:29 volumio volumio[912]: info: Loading plugin "webradio"... Apr 14 08:16:29 volumio volumio-remote-updater[586]: [2026-04-14 08:16:29] [connect] Successful connection Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "i2s_dacs"... Apr 14 08:16:30 volumio volumio[912]: info: I2S DAC not set, start Auto-detection Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "volumiodiscovery"... Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** For more information see Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 08:16:30 volumio node[912]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 08:16:30 volumio volumio[912]: *** WARNING *** For more information see Apr 14 08:16:30 volumio node[912]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 08:16:30 volumio node[912]: *** WARNING *** For more information see Apr 14 08:16:30 volumio node[912]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 08:16:30 volumio node[912]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 08:16:30 volumio node[912]: *** WARNING *** For more information see Apr 14 08:16:30 volumio volumio[912]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 08:16:30 volumio volumio[912]: info: Discovery: Started advertising with name: Volumio Apr 14 08:16:30 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "outputs"... Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "albumart"... Apr 14 08:16:30 volumio volumio[912]: info: Plugin example_plugin is not enabled Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "inputs"... Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "updater_comm"... Apr 14 08:16:30 volumio volumio[912]: info: Plugin mpdemulation is not enabled Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "rest_api"... Apr 14 08:16:30 volumio volumio[912]: info: Loading plugin "websocket"... Apr 14 08:16:30 volumio volumio[912]: info: Starting Socket.io Server version 2.3.0 Apr 14 08:16:30 volumio volumio[912]: info: Loading i18n strings for locale ru Apr 14 08:16:30 volumio volumio[912]: Forking 3 albumart workers Apr 14 08:16:30 volumio volumio[912]: Updating browse sources language Apr 14 08:16:30 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::initPlayerControls Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 08:16:31 volumio volumio[912]: Express server listening on port 3000 Apr 14 08:16:31 volumio volumio[912]: [Metrics] WebUI: 7s 117.33ms Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::resetVolumioState Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::getcurrentVolume Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:31 volumio volumio[912]: info: Volumio Network Manager: Network status updated: 1 Apr 14 08:16:31 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 14 08:16:31 volumio volumio-remote-updater[586]: [2026-04-14 08:16:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1776143789 101 Apr 14 08:16:31 volumio volumio[912]: 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 Apr 14 08:16:31 volumio volumio[912]: info: Reloading queue from file Apr 14 08:16:31 volumio volumio[912]: info: Setting Device type: Raspberry PI Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::setRepeat true single undefined Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:16:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::setRandom true Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:16:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:16:31 volumio volumio[912]: Starting albumart workers Apr 14 08:16:31 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 14 08:16:31 volumio volumio[912]: info: VolumeController:: Volume=33 Mute =false Apr 14 08:16:31 volumio volumio[912]: Starting albumart workers Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:16:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::updateTrackBlock Apr 14 08:16:31 volumio volumio[912]: info: CorePlayQueue::getTrackBlock Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 08:16:31 volumio volumio[912]: Starting albumart workers Apr 14 08:16:31 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 14 08:16:31 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 14 08:16:31 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 14 08:16:31 volumio volumio[912]: info: VolumeController:: Volume=33 Mute =false Apr 14 08:16:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:16:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:16:31 volumio volumio[912]: info: Completed loading Core Plugins Apr 14 08:16:31 volumio volumio[912]: info: Preparing to generate the ALSA configuration file Apr 14 08:16:32 volumio volumio[912]: info: Discovery: adding 01caef8b-6991-4a5c-8c0f-8311123e040b Apr 14 08:16:32 volumio volumio[912]: info: Discovery: Found device Volumio Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:32 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:32 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 08:16:32 volumio volumio[912]: info: Discovery: this is already registered, 01caef8b-6991-4a5c-8c0f-8311123e040b Apr 14 08:16:32 volumio volumio[912]: info: Discovery: Found device Volumio Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:32 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:32 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 14 08:16:32 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 08:16:32 volumio volumio[912]: info: Asound.conf file unchanged, so no further update is needed Apr 14 08:16:32 volumio volumio[912]: info: Output device has changed, restarting MPD Apr 14 08:16:32 volumio volumio[912]: info: Output device has changed, restarting Shairport Sync Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:32 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 08:16:32 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 08:16:32 volumio sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:32 volumio volumio[912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 08:16:32 volumio volumio[912]: info: ___________ START PLUGINS ___________ Apr 14 08:16:32 volumio systemd[1]: Listening on mpd.socket. Apr 14 08:16:32 volumio systemd[1]: Starting Music Player Daemon... Apr 14 08:16:32 volumio volumio[912]: info: ControllerMpd::onStart: Initializing MPD Apr 14 08:16:32 volumio volumio[912]: info: Creating MPD Configuration file Apr 14 08:16:32 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 14 08:16:32 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 08:16:32 volumio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 08:16:32 volumio volumio[912]: info: [1776143792472] CoreMusicLibrary::Adding element Медиа-серверы Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:32 volumio sudo[1073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 08:16:32 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio systemd[1]: Started Volumio5 Onboarding Server. Apr 14 08:16:32 volumio sudo[1073]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 14 08:16:32 volumio volumio[912]: info: UPNP Browser: Client initialized successfully Apr 14 08:16:32 volumio sudo[1073]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:32 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:32 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 08:16:32 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 08:16:32 volumio systemd[1]: mpd.service: Succeeded. Apr 14 08:16:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 14 08:16:32 volumio systemd[1]: Starting Music Player Daemon... Apr 14 08:16:32 volumio dhcpcd[637]: wlan0: using IPv4LL address 169.254.125.234 Apr 14 08:16:32 volumio avahi-daemon[594]: Registering new address record for 169.254.125.234 on wlan0.IPv4. Apr 14 08:16:32 volumio dhcpcd[637]: wlan0: adding route to 169.254.0.0/16 Apr 14 08:16:32 volumio volumio[912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:32 volumio sudo[1101]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 08:16:32 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:32 volumio sudo[1101]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 14 08:16:32 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:32 volumio volumio[912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 08:16:32 volumio volumio[912]: info: [1776143792797] CoreMusicLibrary::Adding element Last_100 Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 08:16:32 volumio volumio[912]: info: [1776143792809] CoreMusicLibrary::Adding element Webradio Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:16:32 volumio volumio[912]: info: Initializing BBC Radios Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:16:32 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:32 volumio volumio[912]: info: Volumio Calling Home Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 14 08:16:33 volumio volumio[912]: info: MPD Permissions set Apr 14 08:16:33 volumio volumio[912]: info: MPD Permissions set Apr 14 08:16:33 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:33.222+03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 14 08:16:33 volumio volumio[912]: info: Volumio called home Apr 14 08:16:33 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 08:16:33 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Apr 14 08:16:33 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:33 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 08:16:33 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:33 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:16:33 volumio volumio[912]: 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: 15 Apr 14 08:16:33 volumio volumio[912]: 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: 15 Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Apr 14 08:16:33 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:33 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:33 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:33.572+03:00 level=INFO msg="system info for 1c84418517d9e94c0327754fbc2b0366" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Apr 14 08:16:33 volumio volumio[912]: info: Starting Shairport Sync Apr 14 08:16:33 volumio volumio[912]: info: Starting Shairport Sync Apr 14 08:16:33 volumio volumio[912]: info: Starting Shairport Sync Apr 14 08:16:33 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 08:16:33 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:33 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 08:16:33 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:33 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:33 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:33 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 08:16:33 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 08:16:33 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 08:16:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 08:16:33 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:33.766+03:00 level=INFO msg="enabling local network discovery" Apr 14 08:16:33 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:33.787+03:00 level=INFO msg="enabling BLE discovery" Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Apr 14 08:16:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 08:16:33 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 08:16:33 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 14 08:16:33 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 08:16:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 08:16:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 08:16:33 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:33 volumio bluetoothd[705]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/B8:27:EB:57:D0:59/settings: (Failed to create file “/usr/var/lib/bluetooth/B8:27:EB:57:D0:59/settings.7G77M3”: No such file or directory) Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Apr 14 08:16:33 volumio volumio[912]: info: Shairport-Sync Started Apr 14 08:16:33 volumio volumio[912]: Error adding Membership: Error: addMembership EINVAL Apr 14 08:16:33 volumio volumio[912]: info: Shairport-Sync Started Apr 14 08:16:33 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:33.913+03:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Apr 14 08:16:33 volumio volumio[912]: info: Shairport-Sync Started Apr 14 08:16:33 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:33 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:33 volumio volumio-remote-updater[586]: No test mode Apr 14 08:16:33 volumio volumio-remote-updater[586]: No alpha test mode Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Apr 14 08:16:33 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.1.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Apr 14 08:16:34 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:34 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 08:16:34 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:34 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio ntpd[824]: Listen normally on 5 wlan0 169.254.125.234:123 Apr 14 08:16:34 volumio ntpd[824]: new interface(s) found: waking up resolver Apr 14 08:16:34 volumio volumio[912]: info: Listing playlists Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Apr 14 08:16:34 volumio volumio[912]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 08:16:34 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:34.263+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" Apr 14 08:16:34 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:34.265+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" Apr 14 08:16:34 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:34.265+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" Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 08:16:34 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:34 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 38 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 39 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 40 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 41 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: verbose: New Socket.io Connection to 192.168.3.94 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 42 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:34 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 08:16:34 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:34 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:34 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:34 volumio volumio[912]: info: Listing playlists Apr 14 08:16:34 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 08:16:35 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:35 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:35 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:35 volumio volumio5-onboarding[1079]: time=2026-04-14T08:16:35.300+03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 14 08:16:35 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:36 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:36 volumio mpd[1139]: Apr 14 08:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 08:16:36 volumio systemd[1]: Started Music Player Daemon. Apr 14 08:16:36 volumio sudo[1064]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:36 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:36 volumio volumio[912]: info: Completed starting Core Plugins Apr 14 08:16:36 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:36 volumio volumio[912]: info: ----- MyVolumio plugins startup ---- Apr 14 08:16:36 volumio volumio[912]: info: ------------------------------------------- Apr 14 08:16:36 volumio volumio[912]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 14 08:16:36 volumio volumio[912]: error: MPD error: The expression evaluated to a falsy value: Apr 14 08:16:36 volumio volumio[912]: assert.ok(self.idling) Apr 14 08:16:36 volumio volumio[912]: error: The expression evaluated to a falsy value: Apr 14 08:16:36 volumio volumio[912]: assert.ok(self.idling) Apr 14 08:16:36 volumio volumio[912]: info: MPD running with PID1139 Apr 14 08:16:36 volumio volumio[912]: ,establishing connection Apr 14 08:16:36 volumio volumio[912]: error: updateQueue error: null Apr 14 08:16:36 volumio volumio[912]: error: updateQueue error: null Apr 14 08:16:36 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:37 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:37 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:38 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:38 volumio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 08:16:38 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:38 volumio sudo[1207]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:38 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 08:16:38 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:38 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:38 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 08:16:38 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:38 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 14 08:16:38 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:38 volumio volumio[912]: info: Upmpdcli Daemon Started Apr 14 08:16:38 volumio volumio[1215]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 14 08:16:39 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:39 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:40 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:40 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:41 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:41 volumio volumio[912]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 14 08:16:41 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:42 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:42 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:43 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:43 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:43 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:44 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:44 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:44 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 14 08:16:44 volumio volumio[912]: info: Adding plugin bluetooth to MyMusic Plugins Apr 14 08:16:44 volumio volumio[912]: info: Adding plugin multiroom to MyMusic Plugins Apr 14 08:16:44 volumio volumio[912]: info: Adding plugin metavolumio to MyMusic Plugins Apr 14 08:16:44 volumio volumio[912]: info: Adding plugin cd_controller to MyMusic Plugins Apr 14 08:16:45 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 14 08:16:45 volumio volumio[912]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 14 08:16:45 volumio volumio[912]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 14 08:16:45 volumio volumio[912]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 14 08:16:45 volumio volumio[912]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 14 08:16:46 volumio volumio[912]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 14 08:16:46 volumio volumio[912]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 14 08:16:46 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:46 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:46 volumio volumio[912]: info: Starting MyVolumio Remote Streaming Endpoints Apr 14 08:16:46 volumio volumio[912]: info: MyVolumio login type: Token Apr 14 08:16:46 volumio volumio[912]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 14 08:16:46 volumio volumio[912]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 14 08:16:48 volumio volumio[912]: info: Starting Streaming Service Transparent Proxy Apr 14 08:16:48 volumio volumio[912]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 14 08:16:48 volumio volumio[912]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 14 08:16:48 volumio volumio[912]: info: Streaming services startup Apr 14 08:16:48 volumio volumio[912]: info: Starting Streaming Daemon Apr 14 08:16:48 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 08:16:48 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:48 volumio volumio[912]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 14 08:16:48 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:48 volumio volumio[912]: info: CoreCommandRouter::volumioGetQueue Apr 14 08:16:48 volumio volumio[912]: info: CoreStateMachine::getQueue Apr 14 08:16:48 volumio volumio[912]: info: CorePlayQueue::getQueue Apr 14 08:16:48 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:16:48 volumio volumio[912]: error: Cannot start Volumio Streaming Daemon Apr 14 08:16:48 volumio volumio[912]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 08:16:48 volumio volumio[912]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 08:16:48 volumio volumio[912]: info: Preload queue cleared Apr 14 08:16:48 volumio volumio[912]: error: MyVolumio Custom Token format not valid, refreshing it Apr 14 08:16:49 volumio volumio[912]: info: MyVolumio login type: Token Apr 14 08:16:49 volumio volumio[912]: STREAMING PROXY: Starting server on port 3245 Apr 14 08:16:49 volumio volumio[912]: Node JS runtime: 14 Apr 14 08:16:49 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:16:49 volumio volumio[912]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 08:16:50 volumio volumio[912]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 08:16:50 volumio volumio[912]: info: Received Get System Version Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:16:50 volumio volumio[912]: info: Received Get System Info Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 08:16:50 volumio volumio[912]: info: Discovery: Getting this device information Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:16:50 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:50 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 08:16:50 volumio volumio[912]: info: Preload queue cleared Apr 14 08:16:50 volumio volumio[912]: info: MyVolumio token set successfully Apr 14 08:16:50 volumio volumio[912]: info: MYVOLUMIO: Adding device Apr 14 08:16:50 volumio volumio[912]: info: MYVOLUMIO: Evaluating Server Apr 14 08:16:51 volumio volumio[912]: info: MyVolumio status changed Apr 14 08:16:51 volumio volumio[912]: info: Streaming services startup Apr 14 08:16:51 volumio volumio[912]: info: Starting Streaming Daemon Apr 14 08:16:51 volumio volumio[912]: info: Removing browser output: myVolumio user plan is not superstar Apr 14 08:16:51 volumio volumio[912]: info: Removing audio output: Apr 14 08:16:51 volumio volumio[912]: info: Stoppping Tunnel 1 Apr 14 08:16:51 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 08:16:51 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:51 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:51 volumio volumio[912]: error: Cannot start Volumio Streaming Daemon Apr 14 08:16:51 volumio volumio[912]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 08:16:51 volumio volumio[912]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 08:16:51 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 14 08:16:51 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 08:16:51 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Apr 14 08:16:51 volumio volumio[912]: info: Remote SSH Stopped Apr 14 08:16:51 volumio volumio[912]: info: Setting Geolocation for MyVolumio to eu4 Apr 14 08:16:51 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:51 volumio volumio[1215]: ..............................................................................................++++ Apr 14 08:16:51 volumio volumio[912]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 14 08:16:52 volumio volumio[1215]: .++++ Apr 14 08:16:52 volumio volumio[1215]: e is 65537 (0x010001) Apr 14 08:16:52 volumio volumio[1215]: writing RSA key Apr 14 08:16:52 volumio volumio[912]: info: Updating MyVolumio device info Apr 14 08:16:52 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:52 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:53 volumio volumio[912]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 14 08:16:53 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:16:53 volumio volumio[912]: info: Preload queue cleared Apr 14 08:16:54 volumio volumio[912]: info: Preload queue cleared Apr 14 08:16:54 volumio volumio[912]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::ClearQueue Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::stop Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::clearPlayQueue Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::saveQueue Apr 14 08:16:54 volumio volumio[912]: info: CoreCommandRouter::volumioPushQueue Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::addQueueItems Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::addQueueItems Apr 14 08:16:54 volumio volumio[912]: info: Preload queue cleared Apr 14 08:16:54 volumio volumio[912]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601974 Apr 14 08:16:54 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 08:16:54 volumio volumio[912]: info: CoreCommandRouter::volumioPushQueue Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::saveQueue Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::updateTrackBlock Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::getTrackBlock Apr 14 08:16:54 volumio volumio[912]: info: CoreCommandRouter::volumioPlay Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::play index 0 Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::stop Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::play index undefined Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:54 volumio volumio[912]: info: CoreStateMachine::startPlaybackTimer Apr 14 08:16:54 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:16:54 volumio volumio[912]: info: [1776143814875] ControllerWebradio::clearAddPlayTrack Apr 14 08:16:54 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 08:16:54 volumio volumio[912]: info: sendMpdCommand stop took 4 milliseconds Apr 14 08:16:54 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 08:16:54 volumio volumio[912]: info: Apr 14 08:16:54 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:54 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:54 volumio volumio[912]: info: sendMpdCommand clear took 5 milliseconds Apr 14 08:16:54 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601974" Apr 14 08:16:54 volumio volumio[912]: info: Apr 14 08:16:54 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:54 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:54 volumio volumio[912]: info: Apr 14 08:16:54 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:54 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:54 volumio volumio[912]: error: updateQueue error: null Apr 14 08:16:54 volumio volumio[912]: info: ------------------------------ 14ms Apr 14 08:16:55 volumio volumio[912]: info: Apr 14 08:16:55 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:55 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:55 volumio volumio[912]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601974" took 614 milliseconds Apr 14 08:16:55 volumio volumio[912]: info: ------------------------------ 612ms Apr 14 08:16:55 volumio volumio[912]: info: ------------------------------ 611ms Apr 14 08:16:55 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 08:16:55 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand play Apr 14 08:16:55 volumio volumio[912]: info: Apr 14 08:16:55 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:55 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:55 volumio volumio[912]: info: Apr 14 08:16:55 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:16:55 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:16:55 volumio volumio[912]: info: ------------------------------ 14ms Apr 14 08:16:55 volumio volumio[912]: info: sendMpdCommand play took 9 milliseconds Apr 14 08:16:55 volumio volumio[912]: info: ------------------------------ 9ms Apr 14 08:16:55 volumio volumio[912]: info: ------------------------------ 6ms Apr 14 08:16:55 volumio volumio[912]: info: MYVOLUMIO: Adding device Apr 14 08:16:55 volumio volumio[912]: info: MYVOLUMIO: Evaluating Server Apr 14 08:16:56 volumio volumio[912]: info: Setting Geolocation for MyVolumio to eu4 Apr 14 08:16:56 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:56 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:56 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:57 volumio volumio[912]: info: Updating MyVolumio device info Apr 14 08:16:57 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:16:57 volumio volumio[912]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 14 08:16:58 volumio volumio[912]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 14 08:16:58 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 14 08:17:01 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 08:17:01 volumio volumio[912]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Apr 14 08:17:01 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:17:02 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:17:02 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:17:02 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 08:17:03 volumio volumio-remote-updater[586]: No test mode Apr 14 08:17:03 volumio volumio-remote-updater[586]: No alpha test mode Apr 14 08:17:03 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 14 08:17:03 volumio volumio[912]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 08:17:03 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 08:17:04 volumio volumio[912]: info: CoreCommandRouter::volumioGetState Apr 14 08:17:04 volumio volumio[912]: info: VolumeController::SetAlsaVolume51 Apr 14 08:17:04 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:04 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:04 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:04 volumio volumio5-onboarding[1079]: time=2026-04-14T08:17:04.486+03:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 08:17:06 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 08:17:08 volumio volumio[912]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 14 08:17:08 volumio volumio[912]: info: Completed starting MyVolumio Plugin Apr 14 08:17:08 volumio volumio[912]: [Metrics] CommandRouter: 42s 910.42ms Apr 14 08:17:08 volumio volumio[912]: info: CoreCommandRouter::volumiosetStartupVolume Apr 14 08:17:08 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 08:17:08 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 08:17:08 volumio volumio[912]: info: CoreCommandRouter::Close All Modals sent Apr 14 08:17:08 volumio volumio[912]: info: CoreCommandRouter::Close All Modals sent Apr 14 08:17:09 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 14 08:17:09 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 08:17:09 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 14 08:17:12 volumio volumio[912]: info: CoreCommandRouter::volumioGetQueue Apr 14 08:17:12 volumio volumio[912]: info: CoreStateMachine::getQueue Apr 14 08:17:12 volumio volumio[912]: info: CorePlayQueue::getQueue Apr 14 08:17:14 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:17:14 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:15 volumio volumio[912]: info: BOOT COMPLETED Apr 14 08:17:16 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:17:16 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:18 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:17:18 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:20 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 08:17:20 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:23 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:23 volumio volumio[912]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::ClearQueue Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::stop Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::clearPlayQueue Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::saveQueue Apr 14 08:17:23 volumio volumio[912]: info: CoreCommandRouter::volumioPushQueue Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::addQueueItems Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::addQueueItems Apr 14 08:17:23 volumio volumio[912]: info: Preload queue cleared Apr 14 08:17:23 volumio volumio[912]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 14 08:17:23 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 08:17:23 volumio volumio[912]: info: CoreCommandRouter::volumioPushQueue Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::saveQueue Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::updateTrackBlock Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::getTrackBlock Apr 14 08:17:23 volumio volumio[912]: info: CoreCommandRouter::volumioPlay Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::play index 0 Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::stop Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::play index undefined Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:23 volumio volumio[912]: info: CoreStateMachine::startPlaybackTimer Apr 14 08:17:23 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:23 volumio volumio[912]: info: [1776143843170] ControllerWebradio::clearAddPlayTrack Apr 14 08:17:23 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 08:17:23 volumio ntpd[824]: Soliciting pool server 193.106.93.116 Apr 14 08:17:26 volumio ntpd[824]: Soliciting pool server 195.90.182.235 Apr 14 08:17:27 volumio ntpd[824]: Soliciting pool server 151.0.2.54 Apr 14 08:17:28 volumio ntpd[824]: Soliciting pool server 92.255.126.18 Apr 14 08:17:29 volumio ntpd[824]: Soliciting pool server 109.173.112.247 Apr 14 08:17:29 volumio ntpd[824]: Soliciting pool server 162.159.200.1 Apr 14 08:17:30 volumio ntpd[824]: Soliciting pool server 185.211.244.47 Apr 14 08:17:30 volumio ntpd[824]: Soliciting pool server 85.21.78.91 Apr 14 08:17:31 volumio ntpd[824]: Soliciting pool server 89.109.251.28 Apr 14 08:17:31 volumio ntpd[824]: Soliciting pool server 91.197.207.24 Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand stop took 8406 milliseconds Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand clear took 10 milliseconds Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand status took 8 milliseconds Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand status took 5 milliseconds Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 55ms Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 53ms Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: Upnp client error: Error: This socket has been ended by the other party Apr 14 08:17:31 volumio volumio[912]: Upnp client error: Error: This socket has been ended by the other party Apr 14 08:17:31 volumio volumio[912]: Upnp client error: Error: This socket has been ended by the other party Apr 14 08:17:31 volumio volumio[912]: error: updateQueue error: null Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand status took 57 milliseconds Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 59ms Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:31 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:31 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:31 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:31 volumio volumio[912]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 78ms Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 131 milliseconds Apr 14 08:17:31 volumio volumio[912]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 08:17:31 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand play Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 90ms Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 89ms Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: info: Apr 14 08:17:31 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:31 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:31 volumio volumio[912]: info: sendMpdCommand play took 8 milliseconds Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 8ms Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 5ms Apr 14 08:17:31 volumio volumio[912]: info: ------------------------------ 3ms Apr 14 08:17:32 volumio ntpd[824]: Soliciting pool server 92.241.18.100 Apr 14 08:17:32 volumio ntpd[824]: Soliciting pool server 92.255.126.4 Apr 14 08:17:33 volumio ntpd[824]: Soliciting pool server 93.191.12.44 Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 8 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 6 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 5 milliseconds Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 14 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 5 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 4 milliseconds Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus stop Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 64ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 96ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 95ms Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:33 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 76 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 178ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 168ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 167ms Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:33 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces system playlist update Apr 14 08:17:33 volumio volumio[912]: info: Ignoring MPD Status Update Apr 14 08:17:33 volumio volumio[912]: info: Apr 14 08:17:33 volumio volumio[912]: ---------------------------- MPD announces state update: player Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::getState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand status Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 111ms Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 109 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 18ms Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 17 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 16ms Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand status took 13 milliseconds Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseState Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 14 08:17:33 volumio volumio[912]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: verbose: ControllerMpd::parseTrackInfo Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ControllerMpd::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::servicePushState Apr 14 08:17:33 volumio volumio[912]: info: CorePlayQueue::getTrack 0 Apr 14 08:17:33 volumio volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 08:17:33 volumio volumio[912]: verbose: CURRENT POSITION 0 Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState stateService play Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::syncState currentStatus play Apr 14 08:17:33 volumio volumio[912]: info: Received an update from plugin. extracting info from payload Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: CoreStateMachine::pushState Apr 14 08:17:33 volumio volumio[912]: info: CoreCommandRouter::volumioPushState Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 196ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 104ms Apr 14 08:17:33 volumio volumio[912]: info: ------------------------------ 102ms Apr 14 08:17:33 volumio volumio[912]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dance%20Wave!/d2f6a59c-966b-4076-af61-e58081627ae7.jpg' Apr 14 08:17:33 volumio volumio[912]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dance%20Wave!/d2f6a59c-966b-4076-af61-e58081627ae7.jpg' Apr 14 08:17:34 volumio ntpd[824]: Soliciting pool server 46.148.143.150 Apr 14 08:17:34 volumio volumio[912]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 08:17:34 volumio volumio[912]: Error: read ECONNRESET Apr 14 08:17:34 volumio volumio[912]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Apr 14 08:17:34 volumio volumio[912]: errno: -104, Apr 14 08:17:34 volumio volumio[912]: code: 'ECONNRESET', Apr 14 08:17:34 volumio volumio[912]: syscall: 'read' Apr 14 08:17:34 volumio volumio[912]: } Apr 14 08:17:34 volumio volumio[912]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 08:17:35 volumio ntpd[824]: Soliciting pool server 89.109.251.22 Apr 14 08:17:35 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 08:16 Apr 14 08:17:35 volumio sudo[1538]: 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"