Feb 10 16:44:00 volumio dhcpcd[877]: wlan0: leased 192.168.1.116 for 43200 seconds Feb 10 16:44:00 volumio avahi-daemon[710]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.116. Feb 10 16:44:00 volumio avahi-daemon[710]: New relevant interface wlan0.IPv4 for mDNS. Feb 10 16:44:00 volumio avahi-daemon[710]: Registering new address record for 192.168.1.116 on wlan0.IPv4. Feb 10 16:44:00 volumio dhcpcd[877]: wlan0: adding route to 192.168.1.0/24 Feb 10 16:44:00 volumio dhcpcd[877]: wlan0: adding default route via 192.168.1.1 Feb 10 16:44:00 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 10 16:44:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 10 16:44:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 10 16:44:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 10 16:44:00 volumio welcome[1273]: Resolved ip:[1] 192.168.1.116 Feb 10 16:44:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 10 16:44:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 10 16:44:00 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Feb 10 16:44:00 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Feb 10 16:44:00 volumio wireless.js[729]: WIRELESS.JS - INFO: trying... Feb 10 16:44:01 volumio sudo[1308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 10 16:44:01 volumio sudo[1308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:01 volumio sudo[1308]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:01 volumio winbindd[1300]: [2026/02/10 16:44:01.108732, 0] ../../source3/winbindd/winbindd.c:1440(main) Feb 10 16:44:01 volumio winbindd[1300]: winbindd version 4.17.8-Raspbian started. Feb 10 16:44:01 volumio winbindd[1300]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 10 16:44:01 volumio winbindd[1300]: [2026/02/10 16:44:01.122713, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Feb 10 16:44:01 volumio winbindd[1300]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 10 16:44:01 volumio sudo[1317]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:01 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 16:44:01 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:01 volumio wireless.js[729]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.116, ipV6 is undefined Feb 10 16:44:01 volumio wireless.js[729]: WIRELESS.JS - INFO: Connected to SSID: Splly_5G Feb 10 16:44:01 volumio wireless.js[729]: WIRELESS.JS - INFO: It's done! AP Feb 10 16:44:01 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Feb 10 16:44:01 volumio wireless.js[729]: WIRELESS.JS - INFO: Restarting avahi-daemon... Feb 10 16:44:01 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Feb 10 16:44:01 volumio sudo[1324]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Feb 10 16:44:01 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 16:44:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 16:44:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 16:44:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:01 volumio systemd[1]: shairport-sync.service: Consumed 1.561s CPU time. Feb 10 16:44:01 volumio avahi-daemon[710]: Got SIGTERM, quitting. Feb 10 16:44:01 volumio avahi-daemon[710]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.116. Feb 10 16:44:01 volumio avahi-daemon[710]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 10 16:44:01 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 10 16:44:01 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:01 volumio avahi-daemon[710]: avahi-daemon 0.8 exiting. Feb 10 16:44:01 volumio volumio[1212]: info: ----- Volumio3 ---- Feb 10 16:44:01 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:01 volumio volumio[1212]: info: ----- System startup ---- Feb 10 16:44:01 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:01 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Feb 10 16:44:01 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 10 16:44:01 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 10 16:44:01 volumio avahi-daemon[1329]: Process 710 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Feb 10 16:44:01 volumio avahi-daemon[1329]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 10 16:44:01 volumio avahi-daemon[1329]: Successfully dropped root privileges. Feb 10 16:44:01 volumio avahi-daemon[1329]: avahi-daemon 0.8 starting up. Feb 10 16:44:01 volumio avahi-daemon[1329]: Successfully called chroot(). Feb 10 16:44:01 volumio avahi-daemon[1329]: Successfully dropped remaining capabilities. Feb 10 16:44:01 volumio avahi-daemon[1329]: No service file found in /etc/avahi/services. Feb 10 16:44:01 volumio avahi-daemon[1329]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.116. Feb 10 16:44:01 volumio avahi-daemon[1329]: New relevant interface wlan0.IPv4 for mDNS. Feb 10 16:44:01 volumio avahi-daemon[1329]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 10 16:44:01 volumio avahi-daemon[1329]: New relevant interface lo.IPv4 for mDNS. Feb 10 16:44:01 volumio avahi-daemon[1329]: Network interface enumeration completed. Feb 10 16:44:01 volumio avahi-daemon[1329]: Registering new address record for 192.168.1.116 on wlan0.IPv4. Feb 10 16:44:01 volumio avahi-daemon[1329]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 10 16:44:01 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 10 16:44:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:01 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:01 volumio wireless.js[729]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 10 16:44:01 volumio smbd[1334]: [2026/02/10 16:44:01.698545, 0] ../../source3/smbd/server.c:1741(main) Feb 10 16:44:01 volumio smbd[1334]: smbd version 4.17.8-Raspbian started. Feb 10 16:44:01 volumio smbd[1334]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 10 16:44:01 volumio ntpd[997]: IO: Listen normally on 3 wlan0 192.168.1.116:123 Feb 10 16:44:01 volumio ntpd[997]: IO: new interface(s) found: waking up resolver Feb 10 16:44:01 volumio ntpd[997]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 10 16:44:01 volumio ntpd[997]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 10 16:44:01 volumio ntpd[997]: DNS: Pool taking: 92.241.18.100 Feb 10 16:44:01 volumio ntpd[997]: DNS: Pool taking: 162.159.200.1 Feb 10 16:44:01 volumio ntpd[997]: DNS: Pool taking: 91.188.214.68 Feb 10 16:44:01 volumio ntpd[997]: DNS: Pool taking: 90.188.6.85 Feb 10 16:44:01 volumio ntpd[997]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 10 16:44:02 volumio avahi-daemon[1329]: Server startup complete. Host name is volumio.local. Local service cookie is 1658120480. Feb 10 16:44:02 volumio ntpd[997]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 10 16:44:02 volumio ntpd[997]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 92.241.18.6 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 51.250.35.68 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 89.109.251.28 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool skipping: 162.159.200.1 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 2001:678:68c::3 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 2a00:b700:3::16a Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 2a02:6bf:f000:1:3::17 Feb 10 16:44:02 volumio ntpd[997]: DNS: Pool taking: 2606:4700:f1::123 Feb 10 16:44:02 volumio ntpd[997]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 10 16:44:03 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 10 16:44:03 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 10 16:44:03 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 10 16:44:03 volumio volumio[1212]: info: MYVOLUMIO Environment detected Feb 10 16:44:03 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 10 16:44:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 10 16:44:03 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 10 16:44:03 volumio systemd[1]: Startup finished in 13.081s (kernel) + 23.338s (userspace) = 36.419s. Feb 10 16:44:03 volumio volumio[1212]: info: Plugin folders cleanup Feb 10 16:44:03 volumio volumio[1212]: info: Scanning into folder /volumio/app/plugins/ Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category audio_interface Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category miscellanea Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category music_service Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category plugins.json Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category system_controller Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category user_interface Feb 10 16:44:03 volumio volumio[1212]: info: Scanning into folder /data/plugins/ Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category music_service Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category system_controller Feb 10 16:44:03 volumio volumio[1212]: info: Scanning category system_hardware Feb 10 16:44:03 volumio volumio[1212]: info: Plugin folders cleanup completed Feb 10 16:44:03 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:03 volumio volumio[1212]: info: ----- Core plugins startup ---- Feb 10 16:44:03 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:03 volumio volumio[1212]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 16:44:03 volumio volumio[1212]: info: Adding plugin upnp to MyMusic Plugins Feb 10 16:44:03 volumio volumio[1212]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 16:44:03 volumio volumio[1212]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 16:44:03 volumio volumio[1212]: info: Loading plugins from folder /data/plugins/ Feb 10 16:44:03 volumio volumio[1212]: info: Loading plugin "system"... Feb 10 16:44:03 volumio volumio[1212]: info: Loading plugin "appearance"... Feb 10 16:44:03 volumio ntpd[997]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 10 16:44:03 volumio ntpd[997]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 10 16:44:03 volumio ntpd[997]: DNS: Pool taking: 83.237.235.30 Feb 10 16:44:03 volumio ntpd[997]: DNS: Pool taking: 62.113.114.46 Feb 10 16:44:03 volumio ntpd[997]: DNS: Pool taking: 79.111.119.96 Feb 10 16:44:03 volumio ntpd[997]: DNS: Pool taking: 217.69.139.133 Feb 10 16:44:03 volumio ntpd[997]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 10 16:44:04 volumio ntpd[997]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 10 16:44:04 volumio ntpd[997]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 10 16:44:04 volumio ntpd[997]: DNS: Pool taking: 188.225.9.167 Feb 10 16:44:04 volumio ntpd[997]: DNS: Pool taking: 92.255.126.22 Feb 10 16:44:04 volumio ntpd[997]: DNS: Pool taking: 77.50.202.25 Feb 10 16:44:04 volumio ntpd[997]: DNS: Pool taking: 93.90.103.9 Feb 10 16:44:04 volumio ntpd[997]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 10 16:44:04 volumio volumio[1212]: info: Loading plugin "network"... Feb 10 16:44:04 volumio volumio[1212]: info: Refreshing Cached IP Addresses Feb 10 16:44:04 volumio sudo[1383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 16:44:04 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:04 volumio volumio[1212]: info: Loading plugin "services"... Feb 10 16:44:04 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:04 volumio volumio[1212]: info: Loading plugin "alsa_controller"... Feb 10 16:44:05 volumio sudo[1384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:05 volumio sudo[1384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:05 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:05 volumio sudo[1395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 16:44:05 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:05 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "wizard"... Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "networkfs"... Feb 10 16:44:05 volumio volumio[1212]: info: Starting Udev Watcher for removable devices Feb 10 16:44:05 volumio volumio[1212]: info: Ignoring mount for partition: boot Feb 10 16:44:05 volumio volumio[1212]: info: Ignoring mount for partition: volumio Feb 10 16:44:05 volumio volumio[1212]: info: Ignoring mount for partition: volumio_data Feb 10 16:44:05 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "volumio_command_line_client"... Feb 10 16:44:05 volumio sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Splly,password=splly459063,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.130/@music /mnt/NAS/Music Feb 10 16:44:05 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "upnp"... Feb 10 16:44:05 volumio volumio[1212]: info: [1770731045280] Starting Upmpd Daemon Feb 10 16:44:05 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "my_music"... Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "mpd"... Feb 10 16:44:05 volumio kernel: netfs: FS-Cache loaded Feb 10 16:44:05 volumio kernel: Key type cifs.spnego registered Feb 10 16:44:05 volumio kernel: Key type cifs.idmap registered Feb 10 16:44:05 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 10 16:44:05 volumio kernel: CIFS: Attempting to mount //192.168.1.130/@music Feb 10 16:44:05 volumio volumio[1212]: info: Loading plugin "upnp_browser"... Feb 10 16:44:06 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:06 volumio volumio-remote-updater[725]: [2026-02-10 16:44:06] [connect] Successful connection Feb 10 16:44:06 volumio volumio[1212]: info: Starting UPNP Browser Feb 10 16:44:06 volumio volumio[1212]: info: Loading plugin "alarm-clock"... Feb 10 16:44:06 volumio volumio[1212]: info: Loading plugin "airplay_emulation"... Feb 10 16:44:07 volumio volumio[1212]: info: Starting Shairport Sync Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "last_100"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "webradio"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "i2s_dacs"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "volumiodiscovery"... Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 16:44:07 volumio node[1212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 16:44:07 volumio node[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** For more information see Feb 10 16:44:07 volumio node[1212]: *** WARNING *** For more information see Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 16:44:07 volumio node[1212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 16:44:07 volumio node[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 16:44:07 volumio volumio[1212]: *** WARNING *** For more information see Feb 10 16:44:07 volumio node[1212]: *** WARNING *** For more information see Feb 10 16:44:07 volumio volumio[1212]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 16:44:07 volumio volumio[1212]: info: Discovery: Started advertising with name: Volumio Feb 10 16:44:07 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "autostart"... Feb 10 16:44:07 volumio volumio[1212]: info: Applying required configuration parameters for plugin autostart Feb 10 16:44:07 volumio volumio[1212]: info: AutoStart - onVolumioStart - read config.json Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "outputs"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "albumart"... Feb 10 16:44:07 volumio volumio[1212]: info: Plugin example_plugin is not enabled Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "inputs"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "updater_comm"... Feb 10 16:44:07 volumio volumio[1212]: info: Plugin mpdemulation is not enabled Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "rest_api"... Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "websocket"... Feb 10 16:44:07 volumio volumio[1212]: info: Starting Socket.io Server version 1.7.4 Feb 10 16:44:07 volumio volumio[1212]: info: Loading plugin "yandex_music"... Feb 10 16:44:08 volumio volumio[1456]: Forking 3 albumart workers Feb 10 16:44:39 volumio ntpd[997]: CLOCK: time stepped by 30.926975 Feb 10 16:44:39 volumio ntpd[997]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 10 16:44:39 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:42 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 10 16:44:43 volumio volumio[1212]: info: Applying required configuration parameters for plugin yandex_music Feb 10 16:44:43 volumio volumio[1212]: info: Loading plugin "backup_restore"... Feb 10 16:44:43 volumio volumio[1466]: Starting albumart workers Feb 10 16:44:44 volumio dhcpcd[689]: timed out Feb 10 16:44:44 volumio sh[675]: timed out Feb 10 16:44:44 volumio sh[640]: ifup: failed to bring up eth0 Feb 10 16:44:44 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 10 16:44:44 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 10 16:44:44 volumio volumio[1467]: Starting albumart workers Feb 10 16:44:45 volumio volumio[1475]: Starting albumart workers Feb 10 16:44:45 volumio volumio[1212]: info: Applying required configuration parameters for plugin backup_restore Feb 10 16:44:45 volumio volumio[1212]: info: Loading plugin "Bluetoothremote"... Feb 10 16:44:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 10 16:44:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 16:44:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 16:44:45 volumio upmpdcli[1497]: Could not open config: /tmp/upmpdcli.conf Feb 10 16:44:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 10 16:44:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 10 16:44:45 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 10 16:44:46 volumio volumio[1212]: info: Applying required configuration parameters for plugin Bluetoothremote Feb 10 16:44:46 volumio volumio[1212]: info: Loading i18n strings for locale en Feb 10 16:44:46 volumio volumio[1212]: Updating browse sources language Feb 10 16:44:46 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::initPlayerControls Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 16:44:47 volumio volumio[1212]: Express server listening on port 3000 Feb 10 16:44:47 volumio volumio[1212]: [Metrics] WebUI: 16s 525.11ms Feb 10 16:44:47 volumio volumio[1212]: info: CoreStateMachine::resetVolumioState Feb 10 16:44:47 volumio volumio[1212]: info: CoreStateMachine::getcurrentVolume Feb 10 16:44:47 volumio volumio[1212]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 16:44:47 volumio sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:47 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:47 volumio sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 16:44:47 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:47 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:47 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:47 volumio volumio[1212]: info: Volumio Network Manager: Network status updated: 2 Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:48 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:48 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Feb 10 16:44:48 volumio volumio[1212]: info: Reloading queue from file Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:48 volumio volumio[1212]: info: Executing start script for DAC IQaudIO DAC Plus Feb 10 16:44:48 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 10 16:44:48 volumio volumio[1212]: info: VolumeController:: Volume=20 Mute =false Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:44:48 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::updateTrackBlock Feb 10 16:44:48 volumio volumio[1212]: info: CorePlayQueue::getTrackBlock Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::setRepeat false single undefined Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:44:48 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::setRandom null Feb 10 16:44:48 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:44:48 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:48 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:44:48 volumio volumio[1212]: info: Setting Device type: Raspberry PI Feb 10 16:44:48 volumio volumio[1212]: info: Listing playlists Feb 10 16:44:48 volumio volumio[1212]: info: Listing playlists Feb 10 16:44:48 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Feb 10 16:44:48 volumio volumio[1212]: info: DAC script executed Feb 10 16:44:48 volumio volumio[1212]: info: Completed loading Core Plugins Feb 10 16:44:48 volumio volumio[1212]: info: Preparing to generate the ALSA configuration file Feb 10 16:44:48 volumio volumio[1212]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 10 16:44:48 volumio volumio[1212]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 10 16:44:48 volumio volumio[1212]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 10 16:44:49 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 10 16:44:49 volumio volumio[1212]: info: Discovery: adding 66aceec2-d850-4a76-9577-f1d7e689c787 Feb 10 16:44:49 volumio volumio[1212]: info: Discovery: Found device Volumio Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:49 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:49 volumio volumio[1212]: info: Discovery: this is already registered, 66aceec2-d850-4a76-9577-f1d7e689c787 Feb 10 16:44:49 volumio volumio[1212]: info: Discovery: Found device Volumio Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:49 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:49 volumio sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 16:44:49 volumio sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:49 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:49 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:49 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:49 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 10 16:44:49 volumio volumio[1212]: info: Asound.conf file unchanged, so no further update is needed Feb 10 16:44:49 volumio volumio[1212]: info: Output device has changed, restarting MPD Feb 10 16:44:49 volumio sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 16:44:49 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:49 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:49 volumio volumio[1212]: info: Output device has changed, restarting Shairport Sync Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:49 volumio sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 16:44:49 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:49 volumio volumio[1212]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 16:44:49 volumio volumio[1212]: info: ___________ START PLUGINS ___________ Feb 10 16:44:49 volumio volumio[1212]: info: ControllerMpd::onStart: Initializing MPD Feb 10 16:44:49 volumio volumio[1212]: info: Creating MPD Configuration file Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 16:44:49 volumio volumio[1212]: info: [1770731089451] CoreMusicLibrary::Adding element Media Servers Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 16:44:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 16:44:49 volumio volumio[1212]: info: UPNP Browser: Client initialized successfully Feb 10 16:44:49 volumio sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 16:44:49 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:49 volumio sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 16:44:49 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:49 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:49 volumio sudo[1579]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 16:44:49 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:49 volumio sudo[1582]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 16:44:49 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:49 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 16:44:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 16:44:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 16:44:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 16:44:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 16:44:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 16:44:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 16:44:49 volumio volumio[1212]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:49 volumio sudo[1589]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 16:44:49 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 16:44:49 volumio sudo[1594]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 16:44:49 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:49 volumio volumio[1212]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 16:44:49 volumio volumio[1212]: info: [1770731089896] CoreMusicLibrary::Adding element Last_100 Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 16:44:49 volumio volumio[1212]: info: [1770731089898] CoreMusicLibrary::Adding element Webradio Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:49 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:44:49 volumio volumio[1212]: info: Initializing BBC Radios Feb 10 16:44:50 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:44:50 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:50 volumio volumio[1212]: info: AutoStart - onStart Feb 10 16:44:50 volumio volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 16:44:50 volumio volumio[1212]: info: [1770731090358] CoreMusicLibrary::Adding element Yandex Music Feb 10 16:44:50 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:50 volumio volumio[1212]: Cannot find translation for source Yandex Music Feb 10 16:44:50 volumio volumio[1212]: error: Plugin yandex_music failed to start! TypeError: Cannot read properties of undefined (reading 'get') Feb 10 16:44:50 volumio volumio[1212]: info: Loading i18n strings for locale en Feb 10 16:44:50 volumio volumio[1212]: info: Volumio Calling Home Feb 10 16:44:52 volumio volumio-remote-updater[725]: [2026-02-10 16:44:52] [connect] Successful connection Feb 10 16:44:52 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 10 16:44:53 volumio volumio[1212]: info: VolumeController:: Volume=20 Mute =false Feb 10 16:44:53 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:44:53 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:53 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:53 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 10 16:44:53 volumio volumio[1212]: info: MPD Permissions set Feb 10 16:44:53 volumio volumio[1212]: info: MPD Permissions set Feb 10 16:44:53 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 10 16:44:53 volumio volumio[1212]: info: Volumio called home Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:53 volumio mpd[1595]: 2026-02-10T16:44:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 16:44:53 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 16:44:54 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:54 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:44:54 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:54 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:54 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:54 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Feb 10 16:44:54 volumio volumio[1212]: info: Completed starting Core Plugins Feb 10 16:44:54 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:54 volumio volumio[1212]: info: ----- MyVolumio plugins startup ---- Feb 10 16:44:54 volumio volumio[1212]: info: ------------------------------------------- Feb 10 16:44:54 volumio volumio[1212]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 16:44:54 volumio volumio[1212]: info: Starting Shairport Sync Feb 10 16:44:54 volumio volumio[1212]: info: Starting Shairport Sync Feb 10 16:44:54 volumio volumio[1212]: info: Starting Shairport Sync Feb 10 16:44:54 volumio sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 16:44:54 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:54 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:54 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:54 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:54 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 16:44:54 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:54 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 16:44:54 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 16:44:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 16:44:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:54 volumio systemd[1]: shairport-sync.service: Consumed 1.369s CPU time. Feb 10 16:44:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:54 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 16:44:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 16:44:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 16:44:55 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:55 volumio volumio[1212]: error: MPD error: The expression evaluated to a falsy value: Feb 10 16:44:55 volumio volumio[1212]: assert.ok(self.idling) Feb 10 16:44:55 volumio volumio[1212]: error: The expression evaluated to a falsy value: Feb 10 16:44:55 volumio volumio[1212]: assert.ok(self.idling) Feb 10 16:44:55 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:55 volumio volumio-remote-updater[725]: [2026-02-10 16:44:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770731092 101 Feb 10 16:44:55 volumio volumio[1212]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 12 Feb 10 16:44:55 volumio volumio[1212]: info: MPD running with PID1595 Feb 10 16:44:55 volumio volumio[1212]: ,establishing connection Feb 10 16:44:55 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:44:55 volumio volumio[1212]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 10 16:44:55 volumio volumio[1212]: info: Shairport-Sync Started Feb 10 16:44:55 volumio volumio[1212]: Error adding Membership: Error: addMembership EINVAL Feb 10 16:44:55 volumio volumio[1212]: info: Shairport-Sync Started Feb 10 16:44:55 volumio volumio[1212]: info: Shairport-Sync Started Feb 10 16:44:55 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:55 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:55 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:55 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Feb 10 16:44:55 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:55 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 10 16:44:55 volumio volumio[1212]: info: Listing playlists Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::volumioGetVisibleSources Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:44:55 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:44:55 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:44:55 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:44:56 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:56 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:56 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:56 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:56 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:56 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:56 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:56 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 10 16:44:57 volumio sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 10 16:44:57 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 10 16:44:57 volumio sudo[1687]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 10 16:44:57 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 16:44:57 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 10 16:44:57 volumio sudo[1691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:57 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio sudo[1691]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:57 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:57 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:57 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:44:57 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:57 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:57 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:57 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.146 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 16:44:57 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 16:44:58 volumio sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 16:44:58 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:58 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:58 volumio sudo[1712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:58 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:58 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:58 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Feb 10 16:44:59 volumio sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 16:44:59 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:59 volumio sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 16:44:59 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:59 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:44:59 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Feb 10 16:44:59 volumio volumio[1212]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; V2324A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:59 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:44:59 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 10 16:44:59 volumio volumio[1212]: info: Listing playlists Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 16:44:59 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 16:44:59 volumio volumio[1212]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 16:45:00 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 10 16:45:00 volumio volumio[1212]: info: CoreCommandRouter::volumioPlay Feb 10 16:45:00 volumio volumio[1212]: info: CoreStateMachine::play index undefined Feb 10 16:45:00 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:00 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:00 volumio volumio[1212]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:45:00 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:00 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:45:00 volumio volumio[1212]: info: sendMpdCommand stop took 6 milliseconds Feb 10 16:45:00 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:00 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:00 volumio volumio[1212]: info: sendMpdCommand clear took 17 milliseconds Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:00 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:00 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:00 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:00 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:00 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:00 volumio volumio[1212]: info: ------------------------------ 144ms Feb 10 16:45:00 volumio volumio[1212]: info: ------------------------------ 39ms Feb 10 16:45:00 volumio volumio[1212]: info: ------------------------------ 33ms Feb 10 16:45:00 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 10 16:45:00 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 16:45:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 16:45:00 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac" Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:00 volumio sudo[1562]: pam_unix(sudo:session): session closed for user root Feb 10 16:45:00 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:00 volumio volumio[1212]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac" took 19 milliseconds Feb 10 16:45:00 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:00 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:00 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:00 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:00 volumio volumio[1212]: info: Feb 10 16:45:00 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:01 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:01 volumio volumio[1212]: info: Feb 10 16:45:01 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:01 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 74ms Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 38ms Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 32ms Feb 10 16:45:01 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 16:45:01 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:45:01 volumio volumio[1212]: info: Feb 10 16:45:01 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:01 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:01 volumio volumio[1212]: info: Feb 10 16:45:01 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:01 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:01 volumio volumio[1212]: info: Upmpdcli Daemon Started Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 55ms Feb 10 16:45:01 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 10 16:45:01 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 10 16:45:01 volumio volumio[1212]: info: sendMpdCommand play took 45 milliseconds Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 45ms Feb 10 16:45:01 volumio volumio[1212]: info: ------------------------------ 35ms Feb 10 16:45:02 volumio volumio[1212]: info: Feb 10 16:45:02 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:02 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:02 volumio volumio[1212]: info: Feb 10 16:45:02 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:02 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:02 volumio volumio[1212]: info: Feb 10 16:45:02 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:02 volumio volumio[1212]: info: Feb 10 16:45:02 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:02 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:02 volumio volumio[1212]: info: sendMpdCommand status took 21 milliseconds Feb 10 16:45:02 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:02 volumio volumio[1212]: info: sendMpdCommand status took 18 milliseconds Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:02 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:03 volumio volumio[1212]: info: Feb 10 16:45:03 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:03 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:03 volumio volumio[1212]: info: Feb 10 16:45:03 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:03 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand status took 610 milliseconds Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand status took 609 milliseconds Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 586 milliseconds Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 580 milliseconds Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand status took 8 milliseconds Feb 10 16:45:03 volumio volumio[1212]: info: sendMpdCommand status took 4 milliseconds Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:03 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:03 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:03 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:03 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2155,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:03 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:45:03 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:03 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:03 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2269,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:03 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:03 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:03 volumio volumio[1212]: info: ------------------------------ 761ms Feb 10 16:45:03 volumio volumio[1212]: info: ------------------------------ 833ms Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::volumioSeek Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::seek Feb 10 16:45:03 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:03 volumio volumio[1212]: info: TRACKBLOCK {"id":"141196287:37501525@user:onyourwave","service":"yandex_music","type":"song","name":"Inner Journey","title":"Inner Journey","album":"Echoes Of Silence","artist":"Inward Universe","duration":213,"albumart":"https://avatars.yandex.net/get-music-content/14854709/cf4fc98a.a.37501525-1/200x200","uri":"yandex_music/track/141196287:37501525@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","yandexTrackId":"141196287:37501525@user:onyourwave","yandexPlaylistId":"user:onyourwave","codec":"flac-mp4","bitrate":0} Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:45:03 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:03 volumio volumio[1212]: info: ControllerMpd::seek Feb 10 16:45:03 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:03 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: Feb 10 16:45:04 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:04 volumio volumio[1212]: info: Feb 10 16:45:04 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:04 volumio volumio[1212]: error: null Feb 10 16:45:04 volumio volumio[1212]: info: Feb 10 16:45:04 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1309 milliseconds Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1309 milliseconds Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1308 milliseconds Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1308 milliseconds Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand status took 30 milliseconds Feb 10 16:45:04 volumio volumio[1212]: info: sendMpdCommand status took 29 milliseconds Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:04 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:04 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:04 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2269,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:04 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:04 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:04 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:04 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2269,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:04 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:04 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:04 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:04 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2865,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:04 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:04 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:04 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:05 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:05 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2865,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:05 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:05 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:05 volumio volumio[1212]: info: ------------------------------ 2496ms Feb 10 16:45:05 volumio volumio[1212]: info: ------------------------------ 2496ms Feb 10 16:45:05 volumio volumio[1212]: info: ------------------------------ 1897ms Feb 10 16:45:05 volumio volumio[1212]: info: ------------------------------ 1891ms Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:05 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:05 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:06 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:06 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:07 volumio volumio[1212]: info: sendMpdCommand status took 2363 milliseconds Feb 10 16:45:07 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 2346 milliseconds Feb 10 16:45:07 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 2350 milliseconds Feb 10 16:45:07 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:07 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:07 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:07 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:07 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:07 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:07 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59857,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:07 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:07 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:07 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:07 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:07 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59857,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:07 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:07 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:07 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:07 volumio volumio[1212]: info: ------------------------------ 2586ms Feb 10 16:45:07 volumio volumio[1212]: info: ------------------------------ 2617ms Feb 10 16:45:08 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 816 milliseconds Feb 10 16:45:08 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:08 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:08 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59857,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da5f62b6f8fc3b063d30ef90a627738896e5f0a02bb0d23ae9881c10cd6a839fd%2Clid%3D305%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:08 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:08 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:08 volumio volumio[1212]: info: ------------------------------ 3362ms Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:08 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:08 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:10 volumio volumio[1212]: info: AutoStart - Plugin is starting Feb 10 16:45:10 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:10 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:10 volumio volumio[1212]: info: AutoStart - start playing Feb 10 16:45:10 volumio volumio[1212]: info: AutoStart - start playing with no specific position Feb 10 16:45:10 volumio volumio[1212]: info: CoreCommandRouter::volumioPlay Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::play index 0 Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::stop Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::updateTrackBlock Feb 10 16:45:10 volumio volumio[1212]: info: CorePlayQueue::getTrackBlock Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:10 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:10 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:10 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::serviceStop Feb 10 16:45:10 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:10 volumio volumio[1212]: info: CoreCommandRouter::serviceStop Feb 10 16:45:10 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 16:45:10 volumio volumio[1212]: info: ControllerMpd::stop Feb 10 16:45:10 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:45:11 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand stop took 748 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:11 volumio volumio[1212]: info: CoreStateMachine::play index undefined Feb 10 16:45:11 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:11 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:11 volumio volumio[1212]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:45:11 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:11 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:11 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand status took 75 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand stop took 73 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand status took 72 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand status took 72 milliseconds Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:11 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand clear took 36 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 10 16:45:11 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:11 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:11 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:11 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:11 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:11 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:11 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:11 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:11 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:11 volumio volumio[1212]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 10 16:45:11 volumio volumio[1212]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 265ms Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 253ms Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 252ms Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:11 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:11 volumio volumio[1212]: info: Feb 10 16:45:11 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:11 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:11 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:11 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:11 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 241ms Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 3ms Feb 10 16:45:11 volumio volumio[1212]: info: ------------------------------ 2ms Feb 10 16:45:11 volumio volumio[1212]: info: Listing playlists Feb 10 16:45:11 volumio volumio[1212]: info: Listing playlists Feb 10 16:45:12 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D51bd2f28bd300b7844615f232c2a2ee9a5a978aa4273e08e54c794e278fceaeb%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac" Feb 10 16:45:12 volumio volumio[1212]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D51bd2f28bd300b7844615f232c2a2ee9a5a978aa4273e08e54c794e278fceaeb%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac" took 1 milliseconds Feb 10 16:45:12 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:12 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:12 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 34ms Feb 10 16:45:12 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 16:45:12 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 4ms Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 39ms Feb 10 16:45:12 volumio volumio[1212]: info: sendMpdCommand play took 38 milliseconds Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 37ms Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 36ms Feb 10 16:45:12 volumio volumio[1212]: info: Feb 10 16:45:12 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:12 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:12 volumio volumio[1212]: info: ------------------------------ 58ms Feb 10 16:45:13 volumio volumio[1212]: info: Feb 10 16:45:13 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:13 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:13 volumio volumio[1212]: info: Feb 10 16:45:13 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:13 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:13 volumio volumio[1212]: info: Feb 10 16:45:13 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:13 volumio volumio[1212]: info: Feb 10 16:45:13 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:13 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:13 volumio volumio[1212]: info: sendMpdCommand status took 6 milliseconds Feb 10 16:45:13 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:13 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 16:45:13 volumio volumio[1212]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 16:45:17 volumio upmpdcli[1796]: writing RSA key Feb 10 16:45:19 volumio volumio[1212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 16:45:19 volumio volumio[1212]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 16:45:19 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:19 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:19 volumio volumio[1212]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 16:45:19 volumio volumio[1212]: info: MyVolumio login type: Token Feb 10 16:45:19 volumio volumio[1212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 16:45:19 volumio volumio[1212]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 16:45:20 volumio volumio[1212]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 16:45:20 volumio volumio[1212]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 16:45:20 volumio volumio[1212]: info: Streaming services startup Feb 10 16:45:20 volumio volumio[1212]: info: Starting Streaming Daemon Feb 10 16:45:20 volumio volumio[1212]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 16:45:20 volumio volumio[1212]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Feb 10 16:45:21 volumio sudo[1804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 16:45:21 volumio volumio[1212]: info: Bluetoothremote--- Device list cleared and placeholder written. Feb 10 16:45:21 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:45:21 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:45:21 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:21 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 10 16:45:21 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root Feb 10 16:45:21 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.19 Feb 10 16:45:21 volumio bluetoothd[785]: Adv Monitor app :1.19 disconnected from D-Bus Feb 10 16:45:21 volumio volumio[1212]: info: Feb 10 16:45:21 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:22 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:22 volumio volumio[1212]: info: Feb 10 16:45:22 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:22 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand status took 8790 milliseconds Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand status took 8790 milliseconds Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand status took 8790 milliseconds Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 8789 milliseconds Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand status took 18 milliseconds Feb 10 16:45:22 volumio volumio[1212]: info: sendMpdCommand status took 18 milliseconds Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:22 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:22 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:22 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:22 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1673,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Inner Journey","artist":"Inward Universe","album":"Echoes Of Silence","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-144.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D51bd2f28bd300b7844615f232c2a2ee9a5a978aa4273e08e54c794e278fceaeb%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69991e8f%2F0%2F54474%2Fcfda0ff8.206107208.6.141196287%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:22 volumio volumio[1212]: verbose: CURRENT POSITION 0 Feb 10 16:45:22 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:22 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:45:22 volumio volumio[1212]: info: ------------------------------ 8801ms Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 16:45:22 volumio volumio[1212]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 10 16:45:22 volumio volumio[1212]: info: Received Get System Version Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:45:22 volumio volumio[1212]: info: Received Get System Info Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:45:22 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::volumioPlay Feb 10 16:45:22 volumio volumio[1212]: info: CoreStateMachine::play index undefined Feb 10 16:45:22 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:22 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:22 volumio volumio[1212]: error: Cannot start Volumio Streaming Daemon Feb 10 16:45:22 volumio volumio[1212]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 16:45:22 volumio volumio[1212]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 16:45:22 volumio volumio[1212]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 16:45:23 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1287 milliseconds Feb 10 16:45:23 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1290 milliseconds Feb 10 16:45:23 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1292 milliseconds Feb 10 16:45:23 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1299 milliseconds Feb 10 16:45:23 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1299 milliseconds Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:23 volumio volumio[1212]: info: ------------------------------ 10254ms Feb 10 16:45:23 volumio volumio[1212]: info: ------------------------------ 10249ms Feb 10 16:45:23 volumio volumio[1212]: info: ------------------------------ 10249ms Feb 10 16:45:23 volumio volumio[1212]: info: ------------------------------ 1539ms Feb 10 16:45:23 volumio volumio[1212]: info: ------------------------------ 1486ms Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::volumioNext Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::next Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::stop Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::updateTrackBlock Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrackBlock Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::serviceStop Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::serviceStop Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::stop Feb 10 16:45:23 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::volumioSeek Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::seek Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: TRACKBLOCK {"id":"141196287:37501525@user:onyourwave","service":"yandex_music","type":"song","name":"Inner Journey","title":"Inner Journey","album":"Echoes Of Silence","artist":"Inward Universe","duration":213,"albumart":"https://avatars.yandex.net/get-music-content/14854709/cf4fc98a.a.37501525-1/200x200","uri":"yandex_music/track/141196287:37501525@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","yandexTrackId":"141196287:37501525@user:onyourwave","yandexPlaylistId":"user:onyourwave","codec":"flac-mp4","bitrate":0} Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Feb 10 16:45:23 volumio volumio[1212]: info: ControllerMpd::seek Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:23 volumio volumio[1212]: Upnp client error: Error: This socket has been ended by the other party Feb 10 16:45:23 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:23 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:23 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:24 volumio volumio[1212]: error: null Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand stop took 963 milliseconds Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio volumio[1212]: info: CoreStateMachine::play index undefined Feb 10 16:45:24 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:45:24 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:24 volumio volumio[1212]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:45:24 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:45:24 volumio volumio[1212]: info: CoreStateMachine::updateTrackBlock Feb 10 16:45:24 volumio volumio[1212]: info: CorePlayQueue::getTrackBlock Feb 10 16:45:24 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.20 Feb 10 16:45:24 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.21 Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio bluetoothd[785]: Adv Monitor app :1.20 disconnected from D-Bus Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.22 Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand status took 325 milliseconds Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand status took 325 milliseconds Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand stop took 329 milliseconds Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand status took 133 milliseconds Feb 10 16:45:24 volumio volumio[1212]: info: sendMpdCommand status took 101 milliseconds Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:24 volumio bluetoothd[785]: Adv Monitor app :1.21 disconnected from D-Bus Feb 10 16:45:24 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:24 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio volumio[1212]: info: Feb 10 16:45:24 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:24 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:24 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:24 volumio volumio[1212]: info: Bluetoothremote--- Reconnecting trusted device: Yandex Remote 2 (54:A9:C8:84:2D:24) Feb 10 16:45:24 volumio bluetoothd[785]: Adv Monitor app :1.22 disconnected from D-Bus Feb 10 16:45:25 volumio volumio[1212]: info: Feb 10 16:45:25 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:25 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 184 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 184 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand clear took 184 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 185 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 185 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand status took 117 milliseconds Feb 10 16:45:25 volumio volumio[1212]: info: sendMpdCommand status took 116 milliseconds Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:25 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:25 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:25 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:25 volumio volumio[1212]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 10 16:45:25 volumio volumio[1212]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 10 16:45:25 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:25 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:25 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:25 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:25 volumio volumio[1212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 10 16:45:25 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.23 Feb 10 16:45:25 volumio bluetoothd[785]: Adv Monitor app :1.23 disconnected from D-Bus Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 942ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 842ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 644ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 643ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 426ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 422ms Feb 10 16:45:25 volumio volumio[1212]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Feb 10 16:45:25 volumio volumio[1212]: info: Feb 10 16:45:25 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:25 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:25 volumio volumio[1212]: info: Feb 10 16:45:25 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:25 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:25 volumio volumio[1212]: info: Bluetoothremote--- Reconnecting trusted device: T33 (85:64:0D:CC:42:B2) Feb 10 16:45:25 volumio volumio[1212]: info: Bluetoothremote--- Reconnecting trusted device: splly (88:54:8E:24:52:C4) Feb 10 16:45:25 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:25 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:25 volumio volumio[1212]: error: updateQueue error: null Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 826ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 330ms Feb 10 16:45:25 volumio volumio[1212]: info: ------------------------------ 323ms Feb 10 16:45:26 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.25 Feb 10 16:45:26 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.26 Feb 10 16:45:26 volumio volumio[1212]: info: Bluetoothremote--- ✅ Reconnected Yandex Remote 2 (54:A9:C8:84:2D:24) Feb 10 16:45:26 volumio bluetoothd[785]: Path / reserved for Adv Monitor app :1.24 Feb 10 16:45:26 volumio bluetoothd[785]: Adv Monitor app :1.24 disconnected from D-Bus Feb 10 16:45:26 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac" Feb 10 16:45:26 volumio volumio-remote-updater[725]: Test mode disabled Feb 10 16:45:26 volumio volumio-remote-updater[725]: Alpha mode disabled Feb 10 16:45:26 volumio volumio-remote-updater[725]: Alpha legacy test mode disabled Feb 10 16:45:26 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac" took 284 milliseconds Feb 10 16:45:27 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:27 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:27 volumio volumio[1212]: verbose: MPD COMMAND [object Object] Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 363ms Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 332ms Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 331ms Feb 10 16:45:27 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 16:45:27 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: Feb 10 16:45:27 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Feb 10 16:45:27 volumio volumio[1212]: info: Ignoring MPD Status Update Feb 10 16:45:27 volumio volumio[1212]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for CPU utilization spike
    • \n
  • Fix for triggerhappy input permission
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add BLE onboarding plugin
    • \n
  • Enable vc4-kms-v3d for all compatible RPi models
    • \n
  • Update Tidal SDK
    • \n
\n","title":"Update v4.096","updateavailable":true} Feb 10 16:45:27 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 425ms Feb 10 16:45:27 volumio volumio[1212]: info: sendMpdCommand play took 424 milliseconds Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 404ms Feb 10 16:45:27 volumio volumio[1212]: info: ------------------------------ 404ms Feb 10 16:45:28 volumio volumio[1212]: info: Feb 10 16:45:28 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:28 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:28 volumio volumio[1212]: info: Feb 10 16:45:28 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:28 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:28 volumio volumio[1212]: info: Feb 10 16:45:28 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:28 volumio volumio[1212]: info: Feb 10 16:45:28 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:28 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:28 volumio volumio[1212]: info: sendMpdCommand status took 44 milliseconds Feb 10 16:45:28 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:28 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:29 volumio volumio[1212]: info: Feb 10 16:45:29 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:29 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:29 volumio volumio[1212]: info: Feb 10 16:45:29 volumio volumio[1212]: ---------------------------- MPD announces state update: player Feb 10 16:45:29 volumio volumio[1212]: info: ControllerMpd::getState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:45:29 volumio volumio[1212]: info: sendMpdCommand status took 475 milliseconds Feb 10 16:45:29 volumio volumio[1212]: info: sendMpdCommand status took 458 milliseconds Feb 10 16:45:29 volumio volumio[1212]: info: sendMpdCommand status took 458 milliseconds Feb 10 16:45:29 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 457 milliseconds Feb 10 16:45:29 volumio volumio[1212]: info: sendMpdCommand status took 27 milliseconds Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:29 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:29 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:29 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:29 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:29 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:29 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:29 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:29 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:45:29 volumio volumio[1212]: info: ------------------------------ 545ms Feb 10 16:45:30 volumio volumio[1212]: info: sendMpdCommand status took 793 milliseconds Feb 10 16:45:30 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 773 milliseconds Feb 10 16:45:30 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 774 milliseconds Feb 10 16:45:30 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 779 milliseconds Feb 10 16:45:30 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 768 milliseconds Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::parseState Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:30 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:30 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:30 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:30 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:30 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:30 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:30 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:30 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:30 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:30 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:30 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:30 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:30 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:30 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:30 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:30 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1347,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:30 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:30 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:30 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:30 volumio volumio[1212]: info: ------------------------------ 1756ms Feb 10 16:45:30 volumio volumio[1212]: info: ------------------------------ 1774ms Feb 10 16:45:30 volumio volumio[1212]: info: ------------------------------ 1773ms Feb 10 16:45:30 volumio volumio[1212]: info: ------------------------------ 1330ms Feb 10 16:45:31 volumio volumio[1212]: info: sendMpdCommand playlistinfo took 1249 milliseconds Feb 10 16:45:31 volumio volumio[1212]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:45:31 volumio volumio[1212]: info: ControllerMpd::pushState Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Feb 10 16:45:31 volumio volumio[1212]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1347,"duration":107,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1075 Kbps","isStreaming":false,"title":"COURSE","artist":"ALTUNIN","album":"COURSE","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D59c9f64852e316a60bc24fcb71179e06beed625729e097a7268e7d8db43cae0d%2Clid%3D2%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69994007%2F0%2F1433751%2F83b44f82.203092124.6.138819422%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 10 16:45:31 volumio volumio[1212]: verbose: CURRENT POSITION 1 Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::syncState stateService play Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:45:31 volumio volumio[1212]: info: Received an update from plugin. extracting info from payload Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::pushState Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Feb 10 16:45:31 volumio volumio[1212]: info: ------------------------------ 2124ms Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:31 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:31 volumio volumio[1212]: info: MyVolumio token set successfully Feb 10 16:45:31 volumio volumio[1212]: info: MYVOLUMIO: Adding device Feb 10 16:45:31 volumio volumio[1212]: info: MYVOLUMIO: Evaluating Server Feb 10 16:45:32 volumio volumio[1212]: info: Listing playlists Feb 10 16:45:32 volumio volumio[1212]: info: Listing playlists Feb 10 16:45:33 volumio volumio[1212]: info: MyVolumio status changed Feb 10 16:45:33 volumio volumio[1212]: info: Streaming services startup Feb 10 16:45:33 volumio volumio[1212]: info: Starting Streaming Daemon Feb 10 16:45:33 volumio volumio[1212]: info: Removing browser output: myVolumio user plan is not superstar Feb 10 16:45:33 volumio volumio[1212]: info: Removing audio output: Feb 10 16:45:33 volumio volumio[1212]: info: Stoppping Tunnel 1 Feb 10 16:45:33 volumio sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 16:45:33 volumio sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:45:33 volumio sudo[1877]: pam_unix(sudo:session): session closed for user root Feb 10 16:45:33 volumio sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 10 16:45:33 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 10 16:45:33 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root Feb 10 16:45:34 volumio volumio[1212]: info: Setting Geolocation for MyVolumio to eu10 Feb 10 16:45:34 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:34 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:34 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:34 volumio volumio[1212]: info: CoreCommandRouter::volumioGetQueue Feb 10 16:45:34 volumio volumio[1212]: info: CoreStateMachine::getQueue Feb 10 16:45:34 volumio volumio[1212]: info: CorePlayQueue::getQueue Feb 10 16:45:34 volumio volumio[1212]: error: Cannot start Volumio Streaming Daemon Feb 10 16:45:34 volumio volumio[1212]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 16:45:34 volumio volumio[1212]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 16:45:34 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 16:45:35 volumio volumio[1212]: info: Remote SSH Stopped Feb 10 16:45:35 volumio volumio[1212]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 10 16:45:35 volumio volumio[1212]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 10 16:45:35 volumio volumio[1212]: info: Updating MyVolumio device info Feb 10 16:45:35 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:35 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:35 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:37 volumio volumio[1212]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 10 16:45:37 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 16:45:37 volumio volumio[1212]: info: Received Get System Info Feb 10 16:45:37 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 16:45:37 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 16:45:37 volumio volumio[1212]: info: Discovery: Getting this device information Feb 10 16:45:37 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:37 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 16:45:38 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 16:45:38 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 10 16:45:38 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:39 volumio volumio[1212]: info: MYVOLUMIO: Adding device Feb 10 16:45:39 volumio volumio[1212]: info: MYVOLUMIO: Evaluating Server Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::volumioGetState Feb 10 16:45:39 volumio volumio[1212]: info: Setting Geolocation for MyVolumio to eu10 Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:39 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 10 16:45:40 volumio volumio[1212]: info: Updating MyVolumio device info Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 10 16:45:40 volumio volumio[1212]: info: Completed starting MyVolumio Plugin Feb 10 16:45:40 volumio volumio[1212]: [Metrics] CommandRouter: 68s 704.61ms Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::volumiosetStartupVolume Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 16:45:40 volumio volumio[1212]: info: CoreCommandRouter::Close All Modals sent Feb 10 16:45:41 volumio volumio[1212]: info: CoreCommandRouter::Close All Modals sent Feb 10 16:45:41 volumio volumio[1212]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 10 16:45:41 volumio volumio[1212]: aplay: main:831: audio open error: Device or resource busy Feb 10 16:45:41 volumio volumio[1212]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 10 16:45:42 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 10 16:45:42 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 16:45:42 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 10 16:45:42 volumio volumio[1212]: info: Enabling plugin yandex_music Feb 10 16:45:42 volumio volumio[1212]: info: Loading plugin "yandex_music"... Feb 10 16:45:42 volumio volumio[1212]: info: Applying required configuration parameters for plugin yandex_music Feb 10 16:45:42 volumio volumio[1212]: info: PLUGIN START: yandex_music Feb 10 16:45:42 volumio volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 16:45:42 volumio volumio[1212]: info: [1770731142288] CoreMusicLibrary::Adding element Yandex Music Feb 10 16:45:42 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 16:45:42 volumio volumio[1212]: Cannot find translation for source Yandex Music Feb 10 16:45:42 volumio volumio[1212]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 16:45:42 volumio volumio[1212]: Error: listen EADDRINUSE: address already in use :::6601 Feb 10 16:45:42 volumio volumio[1212]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Feb 10 16:45:42 volumio volumio[1212]: at listenInCluster (node:net:1920:12) Feb 10 16:45:42 volumio volumio[1212]: at Server.listen (node:net:2008:7) Feb 10 16:45:42 volumio volumio[1212]: at Proxy.start (/data/plugins/music_service/yandex_music/proxy.js:109:17) Feb 10 16:45:42 volumio volumio[1212]: at yandexMusic.onStart (/data/plugins/music_service/yandex_music/index.js:64:20) Feb 10 16:45:42 volumio volumio[1212]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Feb 10 16:45:42 volumio volumio[1212]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 10 16:45:42 volumio volumio[1212]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 10 16:45:42 volumio volumio[1212]: code: 'EADDRINUSE', Feb 10 16:45:42 volumio volumio[1212]: errno: -98, Feb 10 16:45:42 volumio volumio[1212]: syscall: 'listen', Feb 10 16:45:42 volumio volumio[1212]: address: '::', Feb 10 16:45:42 volumio volumio[1212]: port: 6601 Feb 10 16:45:42 volumio volumio[1212]: } Feb 10 16:45:42 volumio volumio[1212]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 16:45:45 volumio sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 16:44' Feb 10 16:45:45 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"