Feb 25 14:19:00 pi5 dhcpcd[917]: eth0: carrier acquired Feb 25 14:19:00 pi5 dhcpcd[1044]: eth0: carrier acquired Feb 25 14:19:00 pi5 sh[898]: eth0: carrier acquired Feb 25 14:19:00 pi5 dhcpcd[917]: eth0: IAID 67:c9:bc:2c Feb 25 14:19:00 pi5 sh[898]: eth0: IAID 67:c9:bc:2c Feb 25 14:19:00 pi5 dhcpcd[1044]: eth0: IAID 67:c9:bc:2c Feb 25 14:19:00 pi5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Feb 25 14:19:00 pi5 dhcpcd[1044]: eth0: adding address fe80::58ef:e62f:9ffe:4ee7 Feb 25 14:19:00 pi5 dhcpcd[917]: eth0: adding address fe80::58ef:e62f:9ffe:4ee7 Feb 25 14:19:00 pi5 dhcpcd[917]: ipv6_addaddr1: Permission denied Feb 25 14:19:00 pi5 sh[898]: eth0: adding address fe80::58ef:e62f:9ffe:4ee7 Feb 25 14:19:00 pi5 sh[898]: ipv6_addaddr1: Permission denied Feb 25 14:19:00 pi5 dhcpcd[1044]: ipv6_addaddr1: Permission denied Feb 25 14:19:00 pi5 ifplugd(eth0)[1179]: Link beat detected. Feb 25 14:19:00 pi5 ntpd[1175]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:00 pi5 ntpd[1175]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 25 14:19:00 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:00 pi5 ntpd[1175]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Feb 25 14:19:00 pi5 ifplugd(eth0)[1179]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 25 14:19:00 pi5 ifplugd(eth0)[1179]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 25 14:19:00 pi5 systemd[1]: systemd-rfkill.service: Deactivated successfully. Feb 25 14:19:00 pi5 dhcpcd[1044]: eth0: soliciting a DHCP lease Feb 25 14:19:00 pi5 dhcpcd[917]: eth0: soliciting an IPv6 router Feb 25 14:19:00 pi5 sh[898]: eth0: soliciting an IPv6 router Feb 25 14:19:00 pi5 dhcpcd[1044]: eth0: soliciting an IPv6 router Feb 25 14:19:01 pi5 ntpd[1175]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:01 pi5 ntpd[1175]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 25 14:19:01 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:01 pi5 ntpd[1175]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Feb 25 14:19:01 pi5 sudo[1291]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:01 pi5 wireless.js[1013]: SETTING APPROPRIATE REG DOMAIN: GB Feb 25 14:19:01 pi5 sudo[1324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 25 14:19:01 pi5 sudo[1324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:01 pi5 sudo[1324]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:01 pi5 dhcpcd[917]: eth0: soliciting a DHCP lease Feb 25 14:19:01 pi5 sh[898]: eth0: soliciting a DHCP lease Feb 25 14:19:01 pi5 sudo[1326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Feb 25 14:19:01 pi5 sudo[1326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:01 pi5 sudo[1326]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:01 pi5 wireless.js[1013]: SUCCESSFULLY SET NEW REGDOMAIN: GB Feb 25 14:19:01 pi5 sudo[1335]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 25 14:19:01 pi5 sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 14:19:01 pi5 sudo[1335]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:01 pi5 volumio-remote-updater[1009]: [2025-02-25 14:19:01] [info] asio async_connect error: system:111 (Connection refused) Feb 25 14:19:01 pi5 volumio-remote-updater[1009]: [2025-02-25 14:19:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 25 14:19:01 pi5 volumio-remote-updater[1009]: [2025-02-25 14:19:01] [error] handle_connect error: Underlying Transport Error Feb 25 14:19:01 pi5 sudo[1337]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 25 14:19:01 pi5 sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 14:19:02 pi5 sudo[1337]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:02 pi5 avahi-daemon[995]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 25 14:19:02 pi5 avahi-daemon[995]: New relevant interface wlan0.IPv4 for mDNS. Feb 25 14:19:02 pi5 avahi-daemon[995]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Feb 25 14:19:02 pi5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 25 14:19:02 pi5 systemd[1]: welcome.service: Deactivated successfully. Feb 25 14:19:02 pi5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 14:19:02 pi5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 14:19:02 pi5 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 14:19:02 pi5 welcome[1343]: Resolved ip:[1] 192.168.211.1 Feb 25 14:19:02 pi5 systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Feb 25 14:19:02 pi5 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 14:19:02 pi5 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 25 14:19:02 pi5 dhcpcd[1044]: wlan0: carrier acquired Feb 25 14:19:02 pi5 hostapd[1347]: wlan0: interface state UNINITIALIZED->ENABLED Feb 25 14:19:02 pi5 hostapd[1347]: wlan0: AP-ENABLED Feb 25 14:19:02 pi5 systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 25 14:19:02 pi5 dhcpcd[1044]: wlan0: IAID 67:c9:bc:2d Feb 25 14:19:02 pi5 dhcpcd[1044]: wlan0: adding address fe80::3686:37d:f318:215b Feb 25 14:19:02 pi5 dhcpcd[1044]: ipv6_addaddr1: Permission denied Feb 25 14:19:02 pi5 systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Feb 25 14:19:02 pi5 iwconfig[1357]: Error for wireless request "Set Power Management" (8B2C) : Feb 25 14:19:02 pi5 iwconfig[1357]: SET failed on device wlan0 ; Invalid argument. Feb 25 14:19:02 pi5 systemd[1]: wireless.service: Deactivated successfully. Feb 25 14:19:02 pi5 systemd[1]: Started wireless.service - Wireless Services. Feb 25 14:19:02 pi5 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 14:19:02 pi5 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 25 14:19:02 pi5 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 25 14:19:02 pi5 systemd[1]: Received SIGRTMIN+21 from PID 301 (plymouthd). Feb 25 14:19:02 pi5 volumio-cpu-tweak[1360]: Setting RT Priority for mpd Feb 25 14:19:02 pi5 volumio-cpu-tweak[1375]: pid 35's current scheduling policy: SCHED_OTHER Feb 25 14:19:02 pi5 volumio-cpu-tweak[1375]: pid 35's current scheduling priority: 0 Feb 25 14:19:02 pi5 volumio-cpu-tweak[1360]: Setting MPD Affinity Feb 25 14:19:02 pi5 volumio-cpu-tweak[1377]: pid 3's current affinity mask: f Feb 25 14:19:02 pi5 volumio-cpu-tweak[1360]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 25 14:19:02 pi5 volumio-cpu-tweak[1360]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 25 14:19:02 pi5 dnsmasq[1380]: started, version 2.90 cachesize 150 Feb 25 14:19:02 pi5 dnsmasq[1380]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Feb 25 14:19:02 pi5 dnsmasq-dhcp[1380]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Feb 25 14:19:02 pi5 dnsmasq[1380]: reading /etc/resolv.conf Feb 25 14:19:02 pi5 dnsmasq[1380]: using nameserver 208.67.222.222#53 Feb 25 14:19:02 pi5 dnsmasq[1380]: using nameserver 208.67.220.220#53 Feb 25 14:19:02 pi5 dnsmasq[1380]: read /etc/hosts - 4 names Feb 25 14:19:02 pi5 systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Feb 25 14:19:02 pi5 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 25 14:19:02 pi5 systemd[1]: Started nmbd.service - Samba NMB Daemon. Feb 25 14:19:02 pi5 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Feb 25 14:19:02 pi5 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 25 14:19:02 pi5 systemd[1]: Received SIGRTMIN+21 from PID 301 (plymouthd). Feb 25 14:19:02 pi5 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 25 14:19:02 pi5 systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 25 14:19:02 pi5 systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Feb 25 14:19:02 pi5 systemd[1]: Reached target getty.target - Login Prompts. Feb 25 14:19:02 pi5 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 25 14:19:02 pi5 winbindd[1389]: [2025/02/25 14:19:02.890156, 0] ../../source3/winbindd/winbindd.c:1440(main) Feb 25 14:19:02 pi5 winbindd[1389]: winbindd version 4.17.8-Raspbian started. Feb 25 14:19:02 pi5 winbindd[1389]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 25 14:19:02 pi5 winbindd[1389]: [2025/02/25 14:19:02.896640, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Feb 25 14:19:02 pi5 winbindd[1389]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 25 14:19:02 pi5 dhcpcd[1044]: wlan0: soliciting an IPv6 router Feb 25 14:19:02 pi5 systemd[1]: Started winbind.service - Samba Winbind Daemon. Feb 25 14:19:02 pi5 systemd[1]: Starting smbd.service - Samba SMB Daemon... Feb 25 14:19:03 pi5 smbd[1398]: [2025/02/25 14:19:03.067356, 0] ../../source3/smbd/server.c:1741(main) Feb 25 14:19:03 pi5 smbd[1398]: smbd version 4.17.8-Raspbian started. Feb 25 14:19:03 pi5 smbd[1398]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 25 14:19:03 pi5 dhcpcd[1044]: wlan0: soliciting a DHCP lease Feb 25 14:19:03 pi5 systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 25 14:19:03 pi5 systemd[1]: Reached target multi-user.target - Multi-User System. Feb 25 14:19:03 pi5 systemd[1]: Reached target graphical.target - Graphical Interface. Feb 25 14:19:03 pi5 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 25 14:19:03 pi5 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 25 14:19:03 pi5 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 25 14:19:03 pi5 systemd[1]: Startup finished in 13.057s (kernel) + 8.989s (userspace) = 22.047s. Feb 25 14:19:03 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:03 pi5 volumio[1358]: info: ----- Volumio3 ---- Feb 25 14:19:03 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:03 pi5 volumio[1358]: info: ----- System startup ---- Feb 25 14:19:03 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:03 pi5 volumio[1358]: info: MYVOLUMIO Environment detected Feb 25 14:19:03 pi5 volumio[1358]: info: Plugin folders cleanup Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning into folder /volumio/app/plugins/ Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category audio_interface Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category miscellanea Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category music_service Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category plugins.json Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category system_controller Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning category user_interface Feb 25 14:19:03 pi5 volumio[1358]: info: Scanning into folder /data/plugins/ Feb 25 14:19:03 pi5 volumio[1358]: info: Plugin folders cleanup completed Feb 25 14:19:03 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:03 pi5 volumio[1358]: info: ----- Core plugins startup ---- Feb 25 14:19:03 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:03 pi5 volumio[1358]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 14:19:03 pi5 volumio[1358]: info: Adding plugin upnp to MyMusic Plugins Feb 25 14:19:03 pi5 volumio[1358]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 14:19:03 pi5 volumio[1358]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 14:19:03 pi5 volumio[1358]: info: Loading plugins from folder /data/plugins/ Feb 25 14:19:03 pi5 volumio[1358]: info: Loading plugin "system"... Feb 25 14:19:03 pi5 volumio[1358]: info: Loading plugin "appearance"... Feb 25 14:19:04 pi5 dhcpcd[1044]: eth0: offered 192.168.30.36 from 192.168.30.254 Feb 25 14:19:04 pi5 dhcpcd[1044]: eth0: probing address 192.168.30.36/24 Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "network"... Feb 25 14:19:04 pi5 volumio[1358]: info: Refreshing Cached IP Addresses Feb 25 14:19:04 pi5 sudo[1431]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 14:19:04 pi5 sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:04 pi5 sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 14:19:04 pi5 sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:04 pi5 sudo[1431]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:04 pi5 sudo[1433]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "services"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "alsa_controller"... Feb 25 14:19:04 pi5 sudo[1442]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 14:19:04 pi5 sudo[1442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:04 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "wizard"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "networkfs"... Feb 25 14:19:04 pi5 volumio[1358]: info: Starting Udev Watcher for removable devices Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: boot Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: volumio Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: volumio_data Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: boot Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: volumio Feb 25 14:19:04 pi5 volumio[1358]: info: Ignoring mount for partition: volumio_data Feb 25 14:19:04 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "volumio_command_line_client"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "upnp"... Feb 25 14:19:04 pi5 volumio[1358]: info: [1740493144338] Starting Upmpd Daemon Feb 25 14:19:04 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "my_music"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "mpd"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "upnp_browser"... Feb 25 14:19:04 pi5 ntpd[1175]: IO: Listen normally on 3 wlan0 192.168.211.1:123 Feb 25 14:19:04 pi5 ntpd[1175]: IO: new interface(s) found: waking up resolver Feb 25 14:19:04 pi5 ntpd[1175]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:04 pi5 ntpd[1175]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 25 14:19:04 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:04 pi5 ntpd[1175]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "alarm-clock"... Feb 25 14:19:04 pi5 volumio[1358]: info: Plugin airplay_emulation is not enabled Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "last_100"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "webradio"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "i2s_dacs"... Feb 25 14:19:04 pi5 volumio[1358]: info: I2S DAC not set, start Auto-detection Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "volumiodiscovery"... Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** For more information see Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 14:19:04 pi5 volumio[1358]: *** WARNING *** For more information see Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** For more information see Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 14:19:04 pi5 node[1358]: *** WARNING *** For more information see Feb 25 14:19:04 pi5 volumio[1358]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 14:19:04 pi5 volumio[1358]: info: Discovery: Started advertising with name: pi5 Feb 25 14:19:04 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "outputs"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "albumart"... Feb 25 14:19:04 pi5 volumio[1358]: info: Plugin example_plugin is not enabled Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "inputs"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "updater_comm"... Feb 25 14:19:04 pi5 volumio[1358]: info: Plugin mpdemulation is not enabled Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "rest_api"... Feb 25 14:19:04 pi5 volumio[1358]: info: Loading plugin "websocket"... Feb 25 14:19:04 pi5 volumio[1358]: info: Starting Socket.io Server version 1.7.4 Feb 25 14:19:04 pi5 volumio[1358]: info: Loading i18n strings for locale en Feb 25 14:19:04 pi5 volumio[1358]: Updating browse sources language Feb 25 14:19:04 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 14:19:05 pi5 volumio[1464]: Forking 3 albumart workers Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::initPlayerControls Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: Express server listening on port 3000 Feb 25 14:19:05 pi5 volumio[1358]: [Metrics] WebUI: 1s 660.10ms Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::resetVolumioState Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::getcurrentVolume Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: Volumio Network Manager: Network status updated: 0 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::pushState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioPushState Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::updateTrackBlock Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrackBlock Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 14:19:05 pi5 volumio[1358]: info: Reloading queue from file Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::setRepeat null single undefined Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::pushState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioPushState Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::setRandom null Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::pushState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioPushState Feb 25 14:19:05 pi5 volumio[1358]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 25 14:19:05 pi5 volumio[1358]: info: USB Boot Capable - System SBC Revision found in cpuinfo: e04171 Feb 25 14:19:05 pi5 volumio[1358]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 25 14:19:05 pi5 volumio[1358]: info: Setting Device type: Raspberry PI Feb 25 14:19:05 pi5 volumio[1358]: info: Completed loading Core Plugins Feb 25 14:19:05 pi5 volumio[1358]: info: Preparing to generate the ALSA configuration file Feb 25 14:19:05 pi5 volumio[1358]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 25 14:19:05 pi5 volumio[1358]: info: Reading ALSA contributions from plugins. Feb 25 14:19:05 pi5 volumio[1358]: info: CoreStateMachine::pushState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioPushState Feb 25 14:19:05 pi5 volumio[1358]: info: Asound.conf file unchanged, so no further update is needed Feb 25 14:19:05 pi5 volumio[1358]: info: Output device has changed, restarting MPD Feb 25 14:19:05 pi5 volumio[1358]: info: ___________ START PLUGINS ___________ Feb 25 14:19:05 pi5 volumio[1358]: info: ControllerMpd::onStart: Initializing MPD Feb 25 14:19:05 pi5 volumio[1358]: info: Creating MPD Configuration file Feb 25 14:19:05 pi5 sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 14:19:05 pi5 volumio[1358]: info: [1740493145130] CoreMusicLibrary::Adding element Media Servers Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 14:19:05 pi5 sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:05 pi5 sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 14:19:05 pi5 sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:05 pi5 sudo[1530]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:05 pi5 sudo[1534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 14:19:05 pi5 sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 14:19:05 pi5 sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:05 pi5 sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 14:19:05 pi5 volumio[1358]: info: [1740493145142] CoreMusicLibrary::Adding element Last_100 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 14:19:05 pi5 volumio[1358]: info: [1740493145143] CoreMusicLibrary::Adding element Webradio Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 14:19:05 pi5 sudo[1534]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 14:19:05 pi5 volumio[1358]: info: Initializing BBC Radios Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: Volumio Calling Home Feb 25 14:19:05 pi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 14:19:05 pi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 14:19:05 pi5 sudo[1557]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 14:19:05 pi5 sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 14:19:05 pi5 sudo[1559]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 25 14:19:05 pi5 sudo[1557]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:05 pi5 volumio[1358]: info: MPD Permissions set Feb 25 14:19:05 pi5 volumio[1358]: info: MPD Permissions set Feb 25 14:19:05 pi5 volumio[1358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:05 pi5 volumio[1476]: Starting albumart workers Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1475]: Starting albumart workers Feb 25 14:19:05 pi5 volumio[1474]: Starting albumart workers Feb 25 14:19:05 pi5 ntpd[1175]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:05 pi5 ntpd[1175]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 25 14:19:05 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:05 pi5 ntpd[1175]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Feb 25 14:19:05 pi5 volumio[1358]: info: Discovery: adding 6fb60ab9-1adc-4f2a-932d-f83e557ef0f6 Feb 25 14:19:05 pi5 volumio[1358]: info: Discovery: Found device pi5 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:05 pi5 volumio[1358]: info: Discovery: this is already registered, 6fb60ab9-1adc-4f2a-932d-f83e557ef0f6 Feb 25 14:19:05 pi5 volumio[1358]: info: Discovery: Found device pi5 Feb 25 14:19:05 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:05 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:06 pi5 dhcpcd[917]: eth0: offered 192.168.30.36 from 192.168.30.254 Feb 25 14:19:06 pi5 sh[898]: eth0: offered 192.168.30.36 from 192.168.30.254 Feb 25 14:19:06 pi5 dhcpcd[917]: eth0: probing address 192.168.30.36/24 Feb 25 14:19:06 pi5 sh[898]: eth0: probing address 192.168.30.36/24 Feb 25 14:19:06 pi5 ntpd[1175]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:06 pi5 ntpd[1175]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 25 14:19:06 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:06 pi5 ntpd[1175]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Feb 25 14:19:06 pi5 mpd[1560]: Feb 25 14:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 14:19:06 pi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 14:19:06 pi5 sudo[1536]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:06 pi5 sudo[1532]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:06 pi5 volumio[1358]: info: Completed starting Core Plugins Feb 25 14:19:06 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:06 pi5 volumio[1358]: info: ----- MyVolumio plugins startup ---- Feb 25 14:19:06 pi5 volumio[1358]: info: ------------------------------------------- Feb 25 14:19:06 pi5 volumio[1358]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 14:19:06 pi5 volumio[1358]: error: MPD error: The expression evaluated to a falsy value: Feb 25 14:19:06 pi5 volumio[1358]: assert.ok(self.idling) Feb 25 14:19:06 pi5 volumio[1358]: error: The expression evaluated to a falsy value: Feb 25 14:19:06 pi5 volumio[1358]: assert.ok(self.idling) Feb 25 14:19:06 pi5 volumio[1358]: info: MPD running with PID1560 Feb 25 14:19:06 pi5 volumio[1358]: ,establishing connection Feb 25 14:19:06 pi5 volumio[1358]: error: updateQueue error: null Feb 25 14:19:06 pi5 volumio[1358]: error: updateQueue error: null Feb 25 14:19:07 pi5 ntpd[1175]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:07 pi5 ntpd[1175]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 25 14:19:07 pi5 ntpd[1175]: DNS: dns_check: DNS error: -11, System error Feb 25 14:19:07 pi5 ntpd[1175]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Feb 25 14:19:07 pi5 sudo[1442]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:08 pi5 dhcpcd[1044]: wlan0: probing for an IPv4LL address Feb 25 14:19:08 pi5 dhcpcd[1044]: eth0: leased 192.168.30.36 for 86400 seconds Feb 25 14:19:08 pi5 avahi-daemon[995]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.30.36. Feb 25 14:19:08 pi5 avahi-daemon[995]: New relevant interface eth0.IPv4 for mDNS. Feb 25 14:19:08 pi5 avahi-daemon[995]: Registering new address record for 192.168.30.36 on eth0.IPv4. Feb 25 14:19:08 pi5 dhcpcd[1044]: eth0: adding route to 192.168.30.0/24 Feb 25 14:19:08 pi5 dhcpcd[1044]: eth0: adding default route via 192.168.30.254 Feb 25 14:19:08 pi5 dnsmasq[1380]: reading /etc/resolv.conf Feb 25 14:19:08 pi5 dnsmasq[1380]: using nameserver 192.168.30.254#53 Feb 25 14:19:08 pi5 dnsmasq[1380]: using nameserver 208.67.222.222#53 Feb 25 14:19:08 pi5 dnsmasq[1380]: using nameserver 208.67.220.220#53 Feb 25 14:19:08 pi5 systemd[1]: welcome.service: Deactivated successfully. Feb 25 14:19:08 pi5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 14:19:08 pi5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 14:19:08 pi5 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 14:19:08 pi5 welcome[1621]: Resolved ip:[2] 192.168.30.36 192.168.211.1 Feb 25 14:19:08 pi5 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 14:19:08 pi5 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 14:19:09 pi5 volumio-remote-updater[1009]: [2025-02-25 14:19:09] [connect] Successful connection Feb 25 14:19:09 pi5 volumio-remote-updater[1009]: [2025-02-25 14:19:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740493149 101 Feb 25 14:19:09 pi5 volumio[1358]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Feb 25 14:19:09 pi5 volumio[1358]: info: Discovery: this is already registered, 6fb60ab9-1adc-4f2a-932d-f83e557ef0f6 Feb 25 14:19:09 pi5 volumio[1358]: info: Discovery: Found device pi5 Feb 25 14:19:09 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:09 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:10 pi5 ntpd[1175]: IO: Listen normally on 4 eth0 192.168.30.36:123 Feb 25 14:19:10 pi5 ntpd[1175]: IO: new interface(s) found: waking up resolver Feb 25 14:19:10 pi5 ntpd[1175]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 213.5.132.231 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 82.219.4.30 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 162.159.200.123 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 85.199.214.101 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::d505:84e7 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::c139:9032 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::a29f:c87b Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::b23e:444f Feb 25 14:19:11 pi5 ntpd[1175]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:11 pi5 volumio[1358]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 14:19:11 pi5 ntpd[1175]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 178.62.250.107 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 85.199.214.100 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 131.111.8.61 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 178.79.143.32 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 2a00:da00:1800:837c::1 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 2001:678:8::123 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 2606:4700:f1::123 Feb 25 14:19:11 pi5 ntpd[1175]: DNS: Pool taking: 2a01:7e00::f03c:94ff:fe24:f68b Feb 25 14:19:11 pi5 ntpd[1175]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 25 14:19:11 pi5 dhcpcd[917]: eth0: leased 192.168.30.36 for 86400 seconds Feb 25 14:19:11 pi5 sh[898]: eth0: leased 192.168.30.36 for 86400 seconds Feb 25 14:19:11 pi5 sh[898]: eth0: adding route to 192.168.30.0/24 Feb 25 14:19:11 pi5 sh[898]: eth0: adding default route via 192.168.30.254 Feb 25 14:19:11 pi5 dhcpcd[917]: eth0: adding route to 192.168.30.0/24 Feb 25 14:19:11 pi5 dhcpcd[917]: eth0: adding default route via 192.168.30.254 Feb 25 14:19:11 pi5 sh[898]: forked to background, child pid 916 Feb 25 14:19:11 pi5 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 14:19:11 pi5 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 25 14:19:11 pi5 systemd[1]: welcome.service: Deactivated successfully. Feb 25 14:19:11 pi5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 14:19:11 pi5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 14:19:12 pi5 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 14:19:12 pi5 welcome[1653]: Resolved ip:[2] 192.168.30.36 192.168.211.1 Feb 25 14:19:12 pi5 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 14:19:12 pi5 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 14:19:12 pi5 ifplugd(eth0)[1179]: client: ifup: interface eth0 already configured Feb 25 14:19:12 pi5 sh[1681]: eth0=eth0 Feb 25 14:19:12 pi5 ifplugd(eth0)[1179]: Program executed successfully. Feb 25 14:19:12 pi5 ntpd[1175]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 217.114.59.3 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 129.250.35.251 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 91.109.118.94 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 212.71.248.69 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::5b6d:765e Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::d447:f845 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::81fa:23fb Feb 25 14:19:12 pi5 ntpd[1175]: DNS: Pool taking: 64:ff9b::d972:3b03 Feb 25 14:19:12 pi5 ntpd[1175]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 25 14:19:12 pi5 dhcpcd[1044]: wlan0: using IPv4LL address 169.254.88.187 Feb 25 14:19:12 pi5 avahi-daemon[995]: Registering new address record for 169.254.88.187 on wlan0.IPv4. Feb 25 14:19:12 pi5 dhcpcd[1044]: wlan0: adding route to 169.254.0.0/16 Feb 25 14:19:12 pi5 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 25 14:19:12 pi5 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 25 14:19:12 pi5 systemd[1]: welcome.service: Deactivated successfully. Feb 25 14:19:12 pi5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 14:19:12 pi5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 14:19:12 pi5 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 14:19:12 pi5 welcome[1693]: Resolved ip:[3] 192.168.30.36 192.168.211.1 169.254.88.187 Feb 25 14:19:12 pi5 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 14:19:12 pi5 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 25 14:19:14 pi5 volumio[1358]: info: Volumio Network Manager: Network status updated: 1 Feb 25 14:19:14 pi5 sudo[1720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 14:19:14 pi5 sudo[1720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:14 pi5 sudo[1720]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:14 pi5 sudo[1722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 14:19:14 pi5 sudo[1722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:14 pi5 sudo[1722]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:14 pi5 sudo[1725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 14:19:14 pi5 sudo[1725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:14 pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 25 14:19:14 pi5 sudo[1725]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:14 pi5 volumio[1358]: info: Upmpdcli Daemon Started Feb 25 14:19:14 pi5 ntpd[1175]: IO: Listen normally on 5 wlan0 169.254.88.187:123 Feb 25 14:19:14 pi5 ntpd[1175]: IO: new interface(s) found: waking up resolver Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 14:19:15 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:15 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:15 pi5 volumio[1358]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 14:19:15 pi5 volumio[1358]: info: MyVolumio login type: Token Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 14:19:15 pi5 volumio[1358]: info: Streaming services startup Feb 25 14:19:15 pi5 volumio[1358]: info: Starting Streaming Daemon Feb 25 14:19:15 pi5 sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 14:19:15 pi5 sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:15 pi5 volumio[1358]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 14:19:15 pi5 sudo[1746]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:15 pi5 volumio[1358]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=0.036&uuid=96e25e8b036fdbb047a6171fc7e87d28" http://updates.volumio.org/downloader-v1/track-device Feb 25 14:19:15 pi5 volumio[1358]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 25 14:19:15 pi5 volumio[1358]: Dload Upload Total Spent Left Speed Feb 25 14:19:15 pi5 volumio[1358]: [132B blob data] Feb 25 14:19:15 pi5 volumio[1358]: retrying in 5 seconds, trial 0 Feb 25 14:19:15 pi5 volumio[1358]: info: Volumio Calling Home Feb 25 14:19:15 pi5 volumio[1358]: error: Cannot start Volumio Streaming Daemon Feb 25 14:19:15 pi5 volumio[1358]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 14:19:15 pi5 volumio[1358]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 14:19:15 pi5 volumio[1358]: info: Volumio called home Feb 25 14:19:16 pi5 volumio[1358]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 14:19:16 pi5 volumio[1358]: info: MyVolumio token set successfully Feb 25 14:19:16 pi5 volumio[1358]: info: MYVOLUMIO: Adding device Feb 25 14:19:16 pi5 volumio[1358]: info: MYVOLUMIO: Evaluating Server Feb 25 14:19:16 pi5 ntpd[1175]: CLOCK: time stepped by -0.386874 Feb 25 14:19:16 pi5 systemd-journald[432]: Time jumped backwards, rotating. Feb 25 14:19:16 pi5 ntpd[1175]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 25 14:19:16 pi5 volumio[1358]: info: MyVolumio Plan changed: premium Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 25 14:19:16 pi5 volumio[1358]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 14:19:16 pi5 volumio[1358]: info: Removing audio output: Feb 25 14:19:16 pi5 volumio[1358]: info: MYVOLUMIO: Adding device Feb 25 14:19:16 pi5 volumio[1358]: info: MYVOLUMIO: Evaluating Server Feb 25 14:19:16 pi5 volumio[1358]: info: Remote config written successfully Feb 25 14:19:16 pi5 volumio[1358]: info: Starting Tunnel 1 Feb 25 14:19:16 pi5 volumio[1358]: info: Starting Tunnel Connection Checker Feb 25 14:19:16 pi5 volumio[1358]: info: Completed starting MyVolumio Plugin Feb 25 14:19:16 pi5 volumio[1358]: info: MYVolumio Device enabled Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 25 14:19:16 pi5 volumio[1358]: info: MyVolumio status changed Feb 25 14:19:16 pi5 volumio[1358]: info: Streaming services startup Feb 25 14:19:16 pi5 volumio[1358]: info: Starting Streaming Daemon Feb 25 14:19:16 pi5 sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 14:19:16 pi5 sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 sudo[1789]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 25 14:19:16 pi5 volumio[1358]: info: [1740493156468] Starting BluetoothController Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 25 14:19:16 pi5 sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 25 14:19:16 pi5 sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 sudo[1794]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 volumio[1358]: info: MRS: MultiRoom plugin initialized Feb 25 14:19:16 pi5 volumio[1358]: info: MRS: STOPPING SNAPCLIENT Feb 25 14:19:16 pi5 volumio[1358]: info: MRS: Snap server stop Feb 25 14:19:16 pi5 volumio[1358]: info: MRS: STOPPING volumioStreaming Feb 25 14:19:16 pi5 sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 25 14:19:16 pi5 sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 sudo[1813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 25 14:19:16 pi5 sudo[1813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 25 14:19:16 pi5 sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 25 14:19:16 pi5 sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 25 14:19:16 pi5 sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:16 pi5 sudo[1819]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 25 14:19:16 pi5 sudo[1811]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 sudo[1815]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 sudo[1813]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 25 14:19:16 pi5 volumio[1358]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 25 14:19:16 pi5 volumio[1358]: info: Preparing to generate the ALSA configuration file Feb 25 14:19:17 pi5 volumio[1358]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 25 14:19:17 pi5 volumio[1358]: info: Reading ALSA contributions from plugins. Feb 25 14:19:17 pi5 volumio[1358]: info: MRS: Removed streaming files Feb 25 14:19:17 pi5 volumio[1358]: info: MRS: volumioStreaming STOPPED Feb 25 14:19:17 pi5 volumio[1358]: info: MRS: SNAPSERVER STOPPED Feb 25 14:19:17 pi5 volumio[1358]: info: MRS: SNAPCLIENT STOPPED Feb 25 14:19:17 pi5 volumio[1358]: error: Cannot start Volumio Streaming Daemon Feb 25 14:19:17 pi5 volumio[1358]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 14:19:17 pi5 volumio[1358]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 14:19:17 pi5 volumio[1358]: info: Asound.conf file unchanged, so no further update is needed Feb 25 14:19:17 pi5 volumio[1358]: info: Output device has changed, restarting MPD Feb 25 14:19:17 pi5 volumio[1358]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 14:19:17 pi5 sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:17 pi5 sudo[1824]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:17 pi5 sudo[1826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 14:19:17 pi5 sudo[1826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 25 14:19:17 pi5 volumio[1358]: info: Adding METAVOLUMIO REST API Endpoints Feb 25 14:19:17 pi5 volumio[1358]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 25 14:19:17 pi5 volumio[1358]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 25 14:19:17 pi5 volumio[1358]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 25 14:19:17 pi5 volumio[1358]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 25 14:19:17 pi5 volumio[1358]: info: Adding inputs REST Endpoints Feb 25 14:19:17 pi5 volumio[1358]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 25 14:19:17 pi5 volumio[1358]: info: Scanning Audio Inputs Feb 25 14:19:17 pi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 14:19:17 pi5 volumio[1358]: info: Checking against Known Cards name Feb 25 14:19:17 pi5 volumio[1358]: info: Adding Server instance for streaming Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 25 14:19:17 pi5 volumio[1358]: error: Hi Res Audio Failed Login: Missing Login Data Feb 25 14:19:17 pi5 volumio[1358]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 25 14:19:17 pi5 volumio[1358]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Feb 25 14:19:17 pi5 volumio[1358]: info: Stopping AccessToken refresher cron Feb 25 14:19:17 pi5 volumio[1358]: info: AccessToken refresher cron started Feb 25 14:19:17 pi5 volumio[1358]: info: Stopping AccessToken refresher cron for QOBUZ Feb 25 14:19:17 pi5 volumio[1358]: info: AccessToken refresher cron started for QOBUZ Feb 25 14:19:17 pi5 volumio[1358]: info: Adding TIDAL REST API Endpoints Feb 25 14:19:17 pi5 volumio[1358]: info: Adding QOBUZ REST API Endpoints Feb 25 14:19:17 pi5 volumio[1358]: [Metrics] CommandRouter: 13s 911.14ms Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: VolumeController:: Setting startup Volume 35 Feb 25 14:19:17 pi5 volumio[1358]: info: VolumeController::SetAlsaVolume35 Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::Close All Modals sent Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::Close All Modals sent Feb 25 14:19:17 pi5 volumio[1358]: info: CoreStateMachine::pushState Feb 25 14:19:17 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::volumioPushState Feb 25 14:19:17 pi5 volumio[1358]: info: Setting Geolocation for MyVolumio to eu3 Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: MPD Permissions set Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:17 pi5 systemd[1]: mpd.service: Deactivated successfully. Feb 25 14:19:17 pi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 14:19:17 pi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 25 14:19:17 pi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 14:19:17 pi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 14:19:17 pi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 14:19:17 pi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 14:19:17 pi5 sudo[1843]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 14:19:17 pi5 sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 14:19:17 pi5 sudo[1843]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:17 pi5 mpd[1845]: Feb 25 14:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 14:19:17 pi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 14:19:17 pi5 sudo[1826]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:17 pi5 volumio[1358]: error: updateQueue error: null Feb 25 14:19:17 pi5 volumio[1358]: info: Successfully Added MyVolumio device Feb 25 14:19:17 pi5 volumio[1358]: info: Setting Geolocation for MyVolumio to eu3 Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:17 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 14:19:18 pi5 volumio[1358]: info: Updating MyVolumio device info Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: Updating MyVolumio device info Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:18 pi5 volumio[1358]: info: Successfully Updated MyVolumio device Feb 25 14:19:18 pi5 volumio[1358]: info: Successfully Added MyVolumio device Feb 25 14:19:19 pi5 volumio[1358]: info: Successfully Updated MyVolumio device Feb 25 14:19:19 pi5 upmpdcli[1851]: writing RSA key Feb 25 14:19:21 pi5 sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 25 14:19:21 pi5 sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:19:21 pi5 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 25 14:19:21 pi5 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 25 14:19:21 pi5 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 25 14:19:21 pi5 sudo[1861]: pam_unix(sudo:session): session closed for user root Feb 25 14:19:21 pi5 volumio[1358]: info: Remote SSH Started Feb 25 14:19:21 pi5 autossh[1864]: port set to 0, monitoring disabled Feb 25 14:19:21 pi5 autossh[1864]: starting ssh (count 1) Feb 25 14:19:21 pi5 autossh[1864]: ssh child pid is 1867 Feb 25 14:19:21 pi5 volumio[1358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 25 14:19:21 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:21 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:21 pi5 volumiossh-tunnel[1867]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 25 14:19:24 pi5 volumio[1358]: info: BOOT COMPLETED Feb 25 14:19:24 pi5 systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 25 14:19:26 pi5 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 25 14:19:27 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:27 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 9 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 10 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 11 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 12 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 13 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 14 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 15 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 16 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 17 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 18 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 19 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 20 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 21 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 22 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 23 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 24 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 25 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 26 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 27 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 28 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 29 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 30 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 31 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 32 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 33 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 34 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 35 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 36 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 37 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 38 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 39 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 40 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 41 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 42 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 43 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 44 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 45 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 46 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 47 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 48 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 49 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 50 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 51 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 52 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 53 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 54 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 55 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 56 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 57 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 58 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 59 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 60 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 61 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 62 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 63 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 64 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 65 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 66 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 67 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 68 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 69 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 70 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 71 Feb 25 14:19:28 pi5 volumio[1358]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 72 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: Received Get System Info Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 14:19:28 pi5 volumio[1358]: info: Discovery: Getting this device information Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 14:19:28 pi5 volumio[1358]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 25 14:19:28 pi5 volumio[1358]: info: Received Get System Info Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 14:19:28 pi5 volumio[1358]: info: Discovery: Getting this device information Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:28 pi5 volumio[1358]: info: Listing playlists Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetQueue Feb 25 14:19:28 pi5 volumio[1358]: info: CoreStateMachine::getQueue Feb 25 14:19:28 pi5 volumio[1358]: info: CorePlayQueue::getQueue Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 14:19:28 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:29 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:29 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:30 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:30 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 14:19:30 pi5 volumio[1358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 73 Feb 25 14:19:30 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetState Feb 25 14:19:30 pi5 volumio[1358]: info: CorePlayQueue::getTrack 0 Feb 25 14:19:30 pi5 volumio[1358]: info: Executing endpoint metavolumio Feb 25 14:19:30 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 25 14:19:30 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:31 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:31 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:31 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:31 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:32 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:32 pi5 volumio[1358]: error: Failed request for metavolumio API Feb 25 14:19:32 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:33 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:33 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:34 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:34 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 14:19:34 pi5 volumio-remote-updater[1009]: No test mode Feb 25 14:19:34 pi5 volumio-remote-updater[1009]: No alpha test mode Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 25 14:19:34 pi5 volumio[1358]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix for Allo Piano DAC on RPi with kernel 6.6.x

 

NEW ADDITIONS

    • Add support for Raspberry Pi Display V2
    • Bump kernel to 6.6.62

 

","title":"Update v3.785","updateavailable":true} Feb 25 14:19:34 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 14:19:35 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:36 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:36 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:37 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:37 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:38 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 14:19:38 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:38 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:42 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:42 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:42 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:42 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:44 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:44 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:19:46 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:19:46 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:20:09 pi5 sshd[1948]: Accepted password for volumio from 192.168.30.10 port 51346 ssh2 Feb 25 14:20:09 pi5 sshd[1948]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 25 14:20:09 pi5 systemd-logind[1006]: New session 1 of user volumio. Feb 25 14:20:09 pi5 systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Feb 25 14:20:09 pi5 systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 25 14:20:09 pi5 systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 25 14:20:09 pi5 systemd[1]: Starting user@1000.service - User Manager for UID 1000... Feb 25 14:20:09 pi5 (systemd)[1951]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Feb 25 14:20:10 pi5 systemd[1951]: Queued start job for default target default.target. Feb 25 14:20:10 pi5 systemd[1951]: Created slice app.slice - User Application Slice. Feb 25 14:20:10 pi5 systemd[1951]: Created slice session.slice - User Core Session Slice. Feb 25 14:20:10 pi5 systemd[1951]: Reached target paths.target - Paths. Feb 25 14:20:10 pi5 systemd[1951]: Reached target timers.target - Timers. Feb 25 14:20:10 pi5 systemd[1951]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Feb 25 14:20:10 pi5 systemd[1951]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 25 14:20:10 pi5 systemd[1951]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 25 14:20:10 pi5 systemd[1951]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 25 14:20:10 pi5 systemd[1951]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 25 14:20:10 pi5 systemd[1951]: Listening on pulseaudio.socket - Sound System. Feb 25 14:20:10 pi5 systemd[1951]: Reached target sockets.target - Sockets. Feb 25 14:20:10 pi5 systemd[1951]: Reached target basic.target - Basic System. Feb 25 14:20:10 pi5 systemd[1]: Started user@1000.service - User Manager for UID 1000. Feb 25 14:20:10 pi5 systemd[1951]: Starting pulseaudio.service - Sound Service... Feb 25 14:20:10 pi5 systemd[1]: Started session-1.scope - Session 1 of User volumio. Feb 25 14:20:10 pi5 sshd[1948]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 25 14:20:10 pi5 pulseaudio[1966]: module-rescue-stream is obsolete and should no longer be loaded. Please remove it from your configuration. Feb 25 14:20:10 pi5 pulseaudio[1966]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 25 14:20:10 pi5 pulseaudio[1966]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 25 14:20:10 pi5 pulseaudio[1966]: Could not find org.bluez.BatteryProviderManager1.RegisterBatteryProvider(), is bluetoothd started with experimental features enabled (-E flag)? Feb 25 14:20:10 pi5 systemd[1951]: Started pulseaudio.service - Sound Service. Feb 25 14:20:10 pi5 systemd[1951]: Reached target default.target - Main User Target. Feb 25 14:20:10 pi5 systemd[1951]: Startup finished in 409ms. Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink/sbc Feb 25 14:20:10 pi5 kernel: Bluetooth: RFCOMM TTY layer initialized Feb 25 14:20:10 pi5 kernel: Bluetooth: RFCOMM socket layer initialized Feb 25 14:20:10 pi5 kernel: Bluetooth: RFCOMM ver 1.11 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource/sbc Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink/sbc_xq_453 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource/sbc_xq_453 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink/sbc_xq_512 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource/sbc_xq_512 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink/sbc_xq_552 Feb 25 14:20:10 pi5 bluetoothd[997]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource/sbc_xq_552 Feb 25 14:20:22 pi5 sudo[1999]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Feb 25 14:20:22 pi5 sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Feb 25 14:20:28 pi5 systemd[1951]: Reached target bluetooth.target - Bluetooth. Feb 25 14:20:28 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:20:28 pi5 bluetoothd[997]: src/profile.c:ext_auth() Headset unit rejected 5C:17:CF:0D:C0:70: org.bluez.Error.Rejected Feb 25 14:20:28 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:20:28 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:20:30 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:20:30 pi5 bluetoothd[997]: src/profile.c:ext_auth() Headset unit rejected 5C:17:CF:0D:C0:70: org.bluez.Error.Rejected Feb 25 14:20:33 pi5 bluetoothd[997]: Authentication attempt without agent Feb 25 14:20:33 pi5 bluetoothd[997]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: Retrieving Cloud Streaming UI Feb 25 14:20:47 pi5 volumio[1358]: info: Getting Tidal Cloud Configuration Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: Getting Qobuz Cloud Configuration Feb 25 14:20:47 pi5 volumio[1358]: info: Asking plugin for UI Config Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: Getting Spotify Cloud Configuration Feb 25 14:20:47 pi5 volumio[1358]: info: Asking plugin for UI Config Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: Saving Spotify Acccount Feb 25 14:20:47 pi5 volumio[1358]: info: Got it Feb 25 14:20:47 pi5 volumio[1358]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 25 14:20:47 pi5 volumio[1358]: info: Got it Feb 25 14:20:47 pi5 volumio[1358]: info: Got Tidal Cloud Configuration Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 14:20:47 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 25 14:20:49 pi5 volumio[1358]: info: Enabling MyMusic plugin bluetooth Feb 25 14:20:49 pi5 volumio[1358]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Feb 25 14:20:49 pi5 volumio[1358]: info: Enabling plugin bluetooth Feb 25 14:20:49 pi5 volumio[1358]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 25 14:20:49 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 14:20:49 pi5 volumio[1358]: info: Done. Feb 25 14:20:49 pi5 sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/hciconfig hci0 name pi5 Feb 25 14:20:49 pi5 sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:20:49 pi5 sudo[2050]: pam_unix(sudo:session): session closed for user root Feb 25 14:20:49 pi5 volumio[1358]: info: Bluetooth name changed to pi5 Feb 25 14:20:51 pi5 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 14:20:52 pi5 sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 25 14:20:52 pi5 sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:20:52 pi5 volumio[1358]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 14:20:52 pi5 volumio[1358]: Error: Missing or invalid serial Feb 25 14:20:52 pi5 volumio[1358]: at Object.marshallMessage [as marshall] (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/message.js:80:30) Feb 25 14:20:52 pi5 volumio[1358]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:122:28) Feb 25 14:20:52 pi5 volumio[1358]: at Object.onceWrapper (node:events:628:28) Feb 25 14:20:52 pi5 volumio[1358]: at EventEmitter.emit (node:events:514:28) Feb 25 14:20:52 pi5 volumio[1358]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:102:10 Feb 25 14:20:52 pi5 volumio[1358]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:83:16 Feb 25 14:20:52 pi5 volumio[1358]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Feb 25 14:20:52 pi5 volumio[1358]: at Socket.emit (node:events:514:28) Feb 25 14:20:52 pi5 volumio[1358]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 25 14:20:52 pi5 volumio[1358]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Feb 25 14:20:52 pi5 volumio[1358]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 14:20:52 pi5 systemd[1]: /lib/systemd/system/volumiobt.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 14:20:52 pi5 systemd[1]: /lib/systemd/system/volumiobt.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 14:20:52 pi5 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 25 14:20:52 pi5 sudo[2053]: pam_unix(sudo:session): session closed for user root Feb 25 14:20:52 pi5 sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 25 14:20:52 pi5 sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 14:20:52 pi5 volumiobt[2069]: /usr/bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 25 14:20:52 pi5 sudo[2068]: pam_unix(sudo:session): session closed for user root Feb 25 14:20:53 pi5 sudo[2073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-02-25 14:19' Feb 25 14:20:53 pi5 sudo[2073]: 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="c4e184d4f6f6bde0af8ddd9d7eadaa6e3b46e745" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="ab4932f837be7eb2e1fa43b9edda84cd8da03286" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Feb 19 10:35:36 UTC 2025" VOLUMIO_VERSION="0.036" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="73c906345bbbd0b0850e54596a39bc63"