-- Logs begin at Mon 2025-06-30 14:14:44 CEST, end at Mon 2025-06-30 14:16:53 CEST. -- Jun 30 14:15:00 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 30 14:15:00 volumio mpd[869]: Jun 30 14:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 30 14:15:01 volumio systemd[1]: volumiossh.service: Succeeded. Jun 30 14:15:01 volumio systemd[1]: Started Volumio SSH enabler. Jun 30 14:15:01 volumio systemd[1]: Started Music Player Daemon. Jun 30 14:15:01 volumio mpd_monitor.sh[604]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 30 14:15:01 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:01 volumio wireless.js[623]: Start wireless flow Jun 30 14:15:01 volumio wireless.js[623]: Stopped hotspot (if there).. Jun 30 14:15:01 volumio sudo[1164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 30 14:15:01 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:01 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:01 volumio sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 30 14:15:01 volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:01 volumio sudo[1166]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:02 volumio wireless.js[623]: DHCP IP Jun 30 14:15:02 volumio wireless.js[623]: Start ap Jun 30 14:15:02 volumio volumio-time-update[645]: volumio-time-update-util: Fetching time from Volumio... Jun 30 14:15:02 volumio wpa_supplicant[1168]: Successfully initialized wpa_supplicant Jun 30 14:15:02 volumio sudo[1169]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 30 14:15:02 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:02 volumio volumio-time-update[645]: volumio-time-update-util: Date not found in response Jun 30 14:15:02 volumio volumio-time-update[645]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 14:15:02 volumio dhcpcd[1174]: dev: loaded udev Jun 30 14:15:02 volumio wpa_supplicant[1168]: nl80211: kernel reports: Authentication algorithm number required Jun 30 14:15:02 volumio dhcpcd-run-hooks[1193]: wlan0: starting wpa_supplicant Jun 30 14:15:02 volumio dhcpcd-run-hooks[1196]: wlan0: failed to start wpa_supplicant Jun 30 14:15:02 volumio dhcpcd-run-hooks[1197]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Authentication algorithm number required nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: ke Jun 30 14:15:02 volumio dhcpcd[1174]: wlan0: connected to Access Point `' Jun 30 14:15:02 volumio dhcpcd[1174]: no interfaces have a carrier Jun 30 14:15:02 volumio dhcpcd[1174]: forked to background, child pid 1207 Jun 30 14:15:02 volumio dhcpcd[1207]: eth0: waiting for carrier Jun 30 14:15:02 volumio dhcpcd[1207]: wlan0: waiting for carrier Jun 30 14:15:02 volumio dhcpcd[1207]: wlan0: carrier acquired Jun 30 14:15:02 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:02 volumio dhcpcd[1207]: DUID 00:01:00:01:2e:f2:a2:d7:b8:27:eb:d5:46:ac Jun 30 14:15:02 volumio dhcpcd[1207]: wlan0: IAID cf:55:d9:c0 Jun 30 14:15:02 volumio dhcpcd[1207]: wlan0: adding address fe80::a5d2:a50a:8d36:adf2 Jun 30 14:15:02 volumio dhcpcd[1207]: ipv6_addaddr1: Permission denied Jun 30 14:15:02 volumio dhcpcd[1207]: wlan0: carrier lost Jun 30 14:15:03 volumio wireless.js[623]: trying... Jun 30 14:15:03 volumio dhcpcd[1207]: wlan0: carrier acquired Jun 30 14:15:03 volumio dhcpcd[1207]: wlan0: IAID cf:55:d9:c0 Jun 30 14:15:03 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:03 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:03 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:03 volumio dhcpcd[1207]: wlan0: soliciting an IPv6 router Jun 30 14:15:03 volumio dhcpcd[1207]: wlan0: soliciting a DHCP lease Jun 30 14:15:04 volumio wireless.js[623]: trying... Jun 30 14:15:04 volumio sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:04 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:04 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:04 volumio volumio-remote-updater[616]: [2025-06-30 14:15:04] [info] asio async_connect error: system:111 (Connection refused) Jun 30 14:15:04 volumio volumio-remote-updater[616]: [2025-06-30 14:15:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 30 14:15:04 volumio volumio-remote-updater[616]: [2025-06-30 14:15:04] [error] handle_connect error: Underlying Transport Error Jun 30 14:15:05 volumio wireless.js[623]: trying... Jun 30 14:15:05 volumio sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:05 volumio sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:05 volumio sudo[1227]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:06 volumio wireless.js[623]: trying... Jun 30 14:15:06 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:06 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:06 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: Trying to associate with 3c:7c:3f:68:d2:2c (SSID='YOLO_5G' freq=5500 MHz) Jun 30 14:15:06 volumio dhcpcd[1207]: wlan0: carrier lost Jun 30 14:15:06 volumio wpa_supplicant[1195]: nl80211: kernel reports: Authentication algorithm number required Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: Associated with 3c:7c:3f:68:d2:2c Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: WPA: Key negotiation completed with 3c:7c:3f:68:d2:2c [PTK=CCMP GTK=CCMP] Jun 30 14:15:06 volumio wpa_supplicant[1195]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:7c:3f:68:d2:2c completed [id=0 id_str=] Jun 30 14:15:06 volumio dhcpcd[1207]: wlan0: carrier acquired Jun 30 14:15:06 volumio dhcpcd[1207]: wlan0: connected to Access Point `YOLO_5G' Jun 30 14:15:06 volumio dhcpcd[1207]: wlan0: IAID cf:55:d9:c0 Jun 30 14:15:07 volumio wireless.js[623]: trying... Jun 30 14:15:07 volumio dhcpcd[1207]: wlan0: soliciting a DHCP lease Jun 30 14:15:07 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:07 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:07 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:07 volumio dhcpcd[1207]: wlan0: offered 192.168.33.27 from 192.168.33.1 Jun 30 14:15:07 volumio volumio-time-update[645]: volumio-time-update-util: Fetching time from Volumio... Jun 30 14:15:07 volumio dhcpcd[1207]: wlan0: soliciting an IPv6 router Jun 30 14:15:07 volumio volumio-time-update[645]: volumio-time-update-util: Date not found in response Jun 30 14:15:07 volumio volumio-time-update[645]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 14:15:07 volumio dhcpcd[1207]: wlan0: probing address 192.168.33.27/24 Jun 30 14:15:08 volumio wireless.js[623]: trying... Jun 30 14:15:08 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:08 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:08 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:09 volumio wireless.js[623]: trying... Jun 30 14:15:09 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:09 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:09 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:10 volumio wireless.js[623]: trying... Jun 30 14:15:10 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:10 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:10 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:11 volumio wireless.js[623]: trying... Jun 30 14:15:11 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:11 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:11 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:12 volumio wireless.js[623]: trying... Jun 30 14:15:12 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:12 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:12 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:12 volumio volumio-time-update[645]: volumio-time-update-util: Fetching time from Volumio... Jun 30 14:15:12 volumio volumio-time-update[645]: volumio-time-update-util: Date not found in response Jun 30 14:15:12 volumio volumio-time-update[645]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 14:15:12 volumio dhcpcd[1207]: wlan0: leased 192.168.33.27 for 86400 seconds Jun 30 14:15:12 volumio avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.33.27. Jun 30 14:15:12 volumio avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jun 30 14:15:12 volumio avahi-daemon[650]: Registering new address record for 192.168.33.27 on wlan0.IPv4. Jun 30 14:15:12 volumio dhcpcd[1207]: wlan0: adding route to 192.168.33.0/24 Jun 30 14:15:12 volumio dhcpcd[1207]: wlan0: adding default route via 192.168.33.1 Jun 30 14:15:13 volumio wireless.js[623]: trying... Jun 30 14:15:13 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 14:15:13 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:13 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:13 volumio wireless.js[623]: Connected to: ----YOLO_5G Jun 30 14:15:13 volumio wireless.js[623]: ---- Jun 30 14:15:13 volumio sudo[1306]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:15:13 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:13 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:13 volumio wireless.js[623]: ... joined AP, wlan0 IPv4 is 192.168.33.27, ipV6 is undefined Jun 30 14:15:13 volumio wireless.js[623]: It's done! AP Jun 30 14:15:13 volumio iwconfig[1310]: Error for wireless request "Set Power Management" (8B2C) : Jun 30 14:15:13 volumio iwconfig[1310]: SET failed on device wlan0 ; Operation not permitted. Jun 30 14:15:13 volumio systemd[1]: Started Wireless Services. Jun 30 14:15:13 volumio systemd[1]: Started Volumio Backend Module. Jun 30 14:15:13 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: Setting RT Priority for mpd Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: Setting MPD Affinity Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: pid 869's current affinity mask: f Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: pid 869's new affinity mask: 3 Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 30 14:15:13 volumio volumio-cpu-tweak[1312]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 30 14:15:13 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 30 14:15:13 volumio ntpd[792]: Listen normally on 3 wlan0 192.168.33.27:123 Jun 30 14:15:13 volumio ntpd[792]: new interface(s) found: waking up resolver Jun 30 14:15:14 volumio volumio-remote-updater[616]: [2025-06-30 14:15:14] [info] asio async_connect error: system:111 (Connection refused) Jun 30 14:15:14 volumio volumio-remote-updater[616]: [2025-06-30 14:15:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 30 14:15:14 volumio volumio-remote-updater[616]: [2025-06-30 14:15:14] [error] handle_connect error: Underlying Transport Error Jun 30 14:15:15 volumio nmbd[778]: [2025/06/30 14:15:15.782693, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 14:15:15 volumio systemd[1]: Started Samba NMB Daemon. Jun 30 14:15:15 volumio nmbd[778]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 30 14:15:15 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 30 14:15:15 volumio nmbd[778]: [2025/06/30 14:15:15.818466, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 30 14:15:15 volumio nmbd[778]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.33.27 for name WORKGROUP<1d>. Jun 30 14:15:15 volumio nmbd[778]: This response was from IP 192.168.33.14, reporting an IP address of 192.168.33.14. Jun 30 14:15:16 volumio winbindd[1328]: [2025/06/30 14:15:16.444710, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 30 14:15:16 volumio winbindd[1328]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 30 14:15:16 volumio winbindd[1328]: [2025/06/30 14:15:16.485568, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 14:15:16 volumio systemd[1]: Started Samba Winbind Daemon. Jun 30 14:15:16 volumio winbindd[1328]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 30 14:15:16 volumio systemd[1]: Starting Samba SMB Daemon... Jun 30 14:15:17 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 30 14:15:17 volumio volumio-time-update[645]: volumio-time-update-util: Fetching time from Volumio... Jun 30 14:15:17 volumio volumio-time-update[645]: volumio-time-update-util: Setting system time to: 2025-06-30 14:15:42 Jun 30 14:15:17 volumio sudo[1342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-30 14:15:42 Jun 30 14:15:17 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:17 volumio dbus-daemon[655]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1344 comm="timedatectl set-time 2025-06-30 14:15:42 ") Jun 30 14:15:17 volumio smbd[1333]: [2025/06/30 14:15:17.547232, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 14:15:17 volumio smbd[1333]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 30 14:15:17 volumio systemd[1]: Started Samba SMB Daemon. Jun 30 14:15:17 volumio systemd[1]: Starting Time & Date Service... Jun 30 14:15:17 volumio dbus-daemon[655]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 30 14:15:17 volumio systemd[1]: Started Time & Date Service. Jun 30 14:15:42 volumio systemd-timedated[1347]: Changed local time to Mon Jun 30 14:15:42 2025 Jun 30 14:15:42 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:42 volumio volumio-time-update[645]: volumio-time-update-util: System time updated successfully. Jun 30 14:15:42 volumio systemd[1]: Started Volumio Time Update Utility. Jun 30 14:15:42 volumio systemd[1]: Reached target Multi-User System. Jun 30 14:15:42 volumio systemd[1]: Reached target Graphical Interface. Jun 30 14:15:42 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 30 14:15:42 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 30 14:15:42 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 30 14:15:42 volumio systemd[1]: Startup finished in 16.114s (kernel) + 36.377s (userspace) = 52.491s. Jun 30 14:15:42 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:15:42 volumio volumio[1311]: info: ----- Volumio3 ---- Jun 30 14:15:42 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:15:42 volumio volumio[1311]: info: ----- System startup ---- Jun 30 14:15:42 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:15:43 volumio sh[519]: timed out Jun 30 14:15:43 volumio dhcpcd[547]: timed out Jun 30 14:15:43 volumio sh[519]: dhcpcd exited Jun 30 14:15:43 volumio dhcpcd[547]: dhcpcd exited Jun 30 14:15:43 volumio sh[519]: ifup: failed to bring up eth0 Jun 30 14:15:43 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 30 14:15:43 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 30 14:15:43 volumio wpa_supplicant[1195]: wlan0: WPA: Group rekeying completed with 3c:7c:3f:68:d2:2c [GTK=CCMP] Jun 30 14:15:44 volumio volumio[1311]: info: MYVOLUMIO Environment detected Jun 30 14:15:45 volumio volumio[1311]: info: Plugin folders cleanup Jun 30 14:15:45 volumio volumio[1311]: info: Scanning into folder /volumio/app/plugins/ Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category audio_interface Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category miscellanea Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category music_service Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category plugins.json Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category system_controller Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category user_interface Jun 30 14:15:45 volumio volumio[1311]: info: Scanning into folder /data/plugins/ Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category audio_interface Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category music_service Jun 30 14:15:45 volumio volumio[1311]: info: Scanning category user_interface Jun 30 14:15:45 volumio volumio[1311]: info: Plugin folders cleanup completed Jun 30 14:15:45 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:15:45 volumio volumio[1311]: info: ----- Core plugins startup ---- Jun 30 14:15:45 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:15:45 volumio volumio[1311]: info: Loading plugins from folder /volumio/app/plugins/ Jun 30 14:15:45 volumio volumio[1311]: info: Adding plugin upnp to MyMusic Plugins Jun 30 14:15:45 volumio volumio[1311]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 30 14:15:45 volumio volumio[1311]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 30 14:15:45 volumio volumio[1311]: info: Loading plugins from folder /data/plugins/ Jun 30 14:15:45 volumio volumio[1311]: info: Loading plugin "system"... Jun 30 14:15:45 volumio volumio[1311]: info: Plugin bluetooth_controller is not enabled Jun 30 14:15:45 volumio volumio[1311]: info: Plugin bluetooth_controller is not enabled Jun 30 14:15:45 volumio volumio[1311]: info: Loading plugin "appearance"... Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "network"... Jun 30 14:15:48 volumio volumio[1311]: info: Refreshing Cached IP Addresses Jun 30 14:15:48 volumio sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 14:15:48 volumio sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:48 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:48 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:15:48 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:48 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "services"... Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "alsa_controller"... Jun 30 14:15:48 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 30 14:15:48 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:48 volumio volumio-remote-updater[616]: [2025-06-30 14:15:48] [connect] Successful connection Jun 30 14:15:48 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "wizard"... Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "networkfs"... Jun 30 14:15:48 volumio volumio[1311]: info: Starting Udev Watcher for removable devices Jun 30 14:15:48 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jurek,password=jktmjrtkjlts,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.33.14/Muza /mnt/NAS/Muza Jun 30 14:15:48 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:48 volumio volumio[1311]: info: Ignoring mount for partition: boot Jun 30 14:15:48 volumio volumio[1311]: info: Ignoring mount for partition: volumio Jun 30 14:15:48 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 30 14:15:48 volumio volumio[1311]: info: Ignoring mount for partition: volumio_data Jun 30 14:15:48 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "volumio_command_line_client"... Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "upnp"... Jun 30 14:15:48 volumio volumio[1311]: info: [1751285748770] Starting Upmpd Daemon Jun 30 14:15:48 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "my_music"... Jun 30 14:15:48 volumio volumio[1311]: info: Loading plugin "mpd"... Jun 30 14:15:49 volumio kernel: Key type cifs.spnego registered Jun 30 14:15:49 volumio kernel: Key type cifs.idmap registered Jun 30 14:15:49 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 30 14:15:49 volumio kernel: CIFS: Attempting to mount //192.168.33.14/Muza Jun 30 14:15:49 volumio volumio[1311]: info: Loading plugin "upnp_browser"... Jun 30 14:15:50 volumio volumio[1311]: info: Loading plugin "alarm-clock"... Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "airplay_emulation"... Jun 30 14:15:51 volumio volumio[1311]: info: Starting Shairport Sync Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "last_100"... Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "webradio"... Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "i2s_dacs"... Jun 30 14:15:51 volumio volumio[1311]: info: I2S DAC not set, start Auto-detection Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "volumiodiscovery"... Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** For more information see Jun 30 14:15:51 volumio node[1311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 14:15:51 volumio volumio[1311]: *** WARNING *** For more information see Jun 30 14:15:51 volumio node[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 14:15:51 volumio node[1311]: *** WARNING *** For more information see Jun 30 14:15:51 volumio node[1311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 14:15:51 volumio node[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 14:15:51 volumio node[1311]: *** WARNING *** For more information see Jun 30 14:15:51 volumio volumio[1311]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 30 14:15:51 volumio volumio[1311]: info: Discovery: Started advertising with name: Volumio Jun 30 14:15:51 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 14:15:51 volumio volumio[1311]: info: Loading plugin "spop"... Jun 30 14:15:52 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:52 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 30 14:15:52 volumio sudo[1396]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:54 volumio volumio[1311]: info: Loading plugin "outputs"... Jun 30 14:15:54 volumio volumio[1311]: info: Loading plugin "albumart"... Jun 30 14:15:54 volumio volumio[1311]: info: Plugin example_plugin is not enabled Jun 30 14:15:54 volumio volumio[1311]: info: Loading plugin "inputs"... Jun 30 14:15:54 volumio volumio[1311]: info: Loading plugin "updater_comm"... Jun 30 14:15:55 volumio volumio[1311]: info: Plugin mpdemulation is not enabled Jun 30 14:15:55 volumio volumio[1311]: info: Loading plugin "rest_api"... Jun 30 14:15:55 volumio volumio[1311]: info: Loading plugin "websocket"... Jun 30 14:15:55 volumio volumio[1311]: info: Starting Socket.io Server version 2.3.0 Jun 30 14:15:55 volumio volumio[1311]: info: Loading plugin "touch_display"... Jun 30 14:15:55 volumio volumio[1311]: Forking 3 albumart workers Jun 30 14:15:56 volumio volumio[1311]: info: Applying required configuration parameters for plugin touch_display Jun 30 14:15:56 volumio volumio[1311]: info: Loading i18n strings for locale pl Jun 30 14:15:56 volumio volumio[1311]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 30 14:15:56 volumio volumio[1311]: Updating browse sources language Jun 30 14:15:56 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::initPlayerControls Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 14:15:57 volumio volumio[1311]: Express server listening on port 3000 Jun 30 14:15:57 volumio volumio[1311]: [Metrics] WebUI: 16s 581.58ms Jun 30 14:15:57 volumio volumio[1311]: info: CoreStateMachine::resetVolumioState Jun 30 14:15:57 volumio volumio[1311]: info: CoreStateMachine::getcurrentVolume Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 14:15:57 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:15:57 volumio volumio[1311]: Starting albumart workers Jun 30 14:15:57 volumio volumio[1311]: Starting albumart workers Jun 30 14:15:57 volumio volumio[1311]: Starting albumart workers Jun 30 14:15:57 volumio volumio[1311]: info: Volumio Network Manager: Network status updated: 2 Jun 30 14:15:57 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 30 14:15:57 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 30 14:15:57 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 30 14:15:58 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 30 14:15:58 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 30 14:15:58 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 30 14:15:58 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 30 14:15:58 volumio volumio[1311]: info: Reloading queue from file Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::setRepeat null single undefined Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::pushState Jun 30 14:15:58 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::volumioPushState Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::setRandom false Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::pushState Jun 30 14:15:58 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::volumioPushState Jun 30 14:15:58 volumio volumio[1311]: info: Setting Device type: Raspberry PI Jun 30 14:15:58 volumio volumio[1311]: info: VolumeController:: Volume=100 Mute =false Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::pushState Jun 30 14:15:58 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::volumioPushState Jun 30 14:15:58 volumio volumio[1311]: info: CoreStateMachine::updateTrackBlock Jun 30 14:15:58 volumio volumio[1311]: info: CorePlayQueue::getTrackBlock Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 14:15:58 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 14:15:58 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:58 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:15:58 volumio volumio[1311]: info: Discovery: adding 7d20ce93-8ee4-4b98-84fd-2ab2820353d7 Jun 30 14:15:58 volumio volumio[1311]: info: Discovery: Found device Volumio Jun 30 14:15:58 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:58 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:15:58 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:15:58 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:58 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:59 volumio volumio[1311]: info: Completed loading Core Plugins Jun 30 14:15:59 volumio volumio[1311]: info: Preparing to generate the ALSA configuration file Jun 30 14:15:59 volumio volumio[1311]: info: VolumeController:: Volume=100 Mute =false Jun 30 14:15:59 volumio volumio[1311]: info: CoreStateMachine::pushState Jun 30 14:15:59 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::volumioPushState Jun 30 14:15:59 volumio volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Jun 30 14:15:59 volumio volumio[1311]: info: Output device has changed, restarting MPD Jun 30 14:15:59 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 30 14:15:59 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:59 volumio volumio[1311]: info: Output device has changed, restarting Shairport Sync Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:15:59 volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 14:15:59 volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:59 volumio sudo[1525]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:59 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 14:15:59 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:59 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 30 14:15:59 volumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 14:15:59 volumio volumio[1311]: info: ___________ START PLUGINS ___________ Jun 30 14:15:59 volumio volumio[1311]: info: ControllerMpd::onStart: Initializing MPD Jun 30 14:15:59 volumio volumio[1311]: info: Creating MPD Configuration file Jun 30 14:15:59 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:59 volumio systemd[1]: Stopping Music Player Daemon... Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 14:15:59 volumio volumio[1311]: info: [1751285759493] CoreMusicLibrary::Adding element Serwery Mediów Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:15:59 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 14:15:59 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:59 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Jun 30 14:15:59 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 14:15:59 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:15:59 volumio systemd[1]: mpd.service: Succeeded. Jun 30 14:15:59 volumio systemd[1]: Stopped Music Player Daemon. Jun 30 14:15:59 volumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 14:15:59 volumio volumio[1311]: info: [1751285759744] CoreMusicLibrary::Adding element Last_100 Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 14:15:59 volumio volumio[1311]: info: [1751285759751] CoreMusicLibrary::Adding element Webradio Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:15:59 volumio volumio[1311]: info: Initializing BBC Radios Jun 30 14:15:59 volumio systemd[1]: Starting Music Player Daemon... Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:15:59 volumio volumio[1311]: info: Creating Spotify config file Jun 30 14:15:59 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio sudo[1547]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 30 14:16:00 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:00 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:00 volumio volumio[1311]: info: Loading i18n strings for locale pl Jun 30 14:16:00 volumio volumio[1311]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:16:00 volumio volumio[1311]: info: Volumio Calling Home Jun 30 14:16:00 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 30 14:16:00 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:00 volumio systemd[1]: Reloading. Jun 30 14:16:00 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 30 14:16:00 volumio volumio[1311]: info: MPD Permissions set Jun 30 14:16:00 volumio volumio[1311]: info: MPD Permissions set Jun 30 14:16:00 volumio volumio[1311]: info: Upmpdcli Daemon Started Jun 30 14:16:00 volumio volumio[1311]: info: Spotify config file written Jun 30 14:16:00 volumio sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 30 14:16:00 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:00 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:00 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:01 volumio volumio[1311]: info: No need to fix Spotify hosts Jun 30 14:16:01 volumio volumio[1311]: info: Volumio called home Jun 30 14:16:01 volumio volumio[1311]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 30 14:16:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 30 14:16:01 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 30 14:16:01 volumio volumio[1311]: SPOTIFY: BQDgkFKQEaWmPsTmxniLKkz2-BhW25x8-Jqovg2wxQymfxWzNRhS_jxLo4NkrYonNycGSCF5g0gaCG9l_I7pYWwkI85FJhN2l8E2amhdErCmHjhbcC-458zi63bLuFDZ5AOjp7GlyhSF3cAzlafuRFiqBf6cPopP73nJTd3s4xvKPFpXA6tSMYxWDpI7RfNL4CAnu_Bydvx59RUrF139wdWJ7YMjPCr8G59flmVUUuiW1BxSeNWisoPQXYFOSqGjSnG-LP3N3hetER6OCW5vY-_3aXLThukh4sJq9YmCQ3OP4Lo Jun 30 14:16:01 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 30 14:16:01 volumio volumio[1311]: info: New Spotify access token = BQDgkFKQEaWmPsTmxniLKkz2-BhW25x8-Jqovg2wxQymfxWzNRhS_jxLo4NkrYonNycGSCF5g0gaCG9l_I7pYWwkI85FJhN2l8E2amhdErCmHjhbcC-458zi63bLuFDZ5AOjp7GlyhSF3cAzlafuRFiqBf6cPopP73nJTd3s4xvKPFpXA6tSMYxWDpI7RfNL4CAnu_Bydvx59RUrF139wdWJ7YMjPCr8G59flmVUUuiW1BxSeNWisoPQXYFOSqGjSnG-LP3N3hetER6OCW5vY-_3aXLThukh4sJq9YmCQ3OP4Lo Jun 30 14:16:01 volumio volumio[1311]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 30 14:16:01 volumio volumio[1311]: info: Starting Shairport Sync Jun 30 14:16:01 volumio volumio[1311]: info: Starting Shairport Sync Jun 30 14:16:01 volumio volumio[1311]: info: Starting Shairport Sync Jun 30 14:16:01 volumio sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 14:16:01 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:01 volumio sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 14:16:01 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:01 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:01 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:01 volumio sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 14:16:01 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:01 volumio volumio[1311]: SPOTIFY: User informations: {"country":"PL","display_name":"jurek","email":"jerzydubin@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ogaol2dwuwoi7ndw7qbstszqq"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/ogaol2dwuwoi7ndw7qbstszqq","id":"ogaol2dwuwoi7ndw7qbstszqq","images":[],"product":"premium","type":"user","uri":"spotify:user:ogaol2dwuwoi7ndw7qbstszqq"} Jun 30 14:16:01 volumio volumio[1311]: info: Spotify Successfully logged in Jun 30 14:16:01 volumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 14:16:01 volumio volumio[1311]: info: [1751285761803] CoreMusicLibrary::Adding element Spotify Jun 30 14:16:01 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:16:01 volumio volumio[1311]: Cannot find translation for source Spotify Jun 30 14:16:02 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio volumio[1311]: info: touch_display: systemctl daemon-reload succeeded. Jun 30 14:16:02 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 30 14:16:02 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:02 volumio systemd[1]: Started Volumio Kiosk. Jun 30 14:16:02 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 30 14:16:02 volumio volumio[1311]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 30 14:16:02 volumio volumio[1311]: info: touch_display: Volumio Kiosk started Jun 30 14:16:02 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:02 volumio systemd[1]: Started go-librespot Daemon. Jun 30 14:16:02 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:02 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio go-librespot[1606]: go-librespot daemon starting... Jun 30 14:16:02 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 30 14:16:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 30 14:16:02 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:02 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 14:16:02 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio volumio[1311]: info: Shairport-Sync Started Jun 30 14:16:02 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:02 volumio volumio[1311]: Error adding Membership: Error: addMembership EINVAL Jun 30 14:16:02 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 30 14:16:02 volumio volumio[1311]: info: Shairport-Sync Started Jun 30 14:16:02 volumio volumio[1311]: info: Shairport-Sync Started Jun 30 14:16:02 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:02 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:03 volumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 30 14:16:03 volumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jun 30 14:16:03 volumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 30 14:16:03 volumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 30 14:16:03 volumio startx[1604]: X.Org X Server 1.20.4 Jun 30 14:16:03 volumio startx[1604]: X Protocol Version 11, Revision 0 Jun 30 14:16:03 volumio startx[1604]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 30 14:16:03 volumio startx[1604]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jun 30 14:16:03 volumio startx[1604]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jun 30 14:16:03 volumio startx[1604]: Build Date: 04 April 2023 07:50:56AM Jun 30 14:16:03 volumio startx[1604]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jun 30 14:16:03 volumio startx[1604]: Current version of pixman: 0.36.0 Jun 30 14:16:03 volumio startx[1604]: Before reporting problems, check http://wiki.x.org Jun 30 14:16:03 volumio startx[1604]: to make sure that you have the latest version. Jun 30 14:16:03 volumio startx[1604]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 30 14:16:03 volumio startx[1604]: (++) from command line, (!!) notice, (II) informational, Jun 30 14:16:03 volumio startx[1604]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 30 14:16:03 volumio startx[1604]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jun 30 14:16:03 2025 Jun 30 14:16:03 volumio volumio-remote-updater[616]: [2025-06-30 14:16:03] [connect] Successful connection Jun 30 14:16:03 volumio volumio-remote-updater[616]: [2025-06-30 14:16:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751285763 101 Jun 30 14:16:03 volumio startx[1604]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 30 14:16:03 volumio startx[1604]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 30 14:16:03 volumio volumio[1311]: 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: 11 Jun 30 14:16:03 volumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 30 14:16:03 volumio go-librespot[1606]: time="2025-06-30T14:16:03+02:00" level=info msg="running go-librespot 0.2.0" Jun 30 14:16:03 volumio go-librespot[1606]: time="2025-06-30T14:16:03+02:00" level=debug msg="app state loaded" Jun 30 14:16:03 volumio go-librespot[1606]: time="2025-06-30T14:16:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 14:16:03 volumio mpd[1559]: Jun 30 14:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 30 14:16:04 volumio systemd[1]: Started Music Player Daemon. Jun 30 14:16:04 volumio volumio[1311]: Upnp client error: Error: This socket has been ended by the other party Jun 30 14:16:04 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:04 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 14:16:04 volumio volumio[1311]: info: Completed starting Core Plugins Jun 30 14:16:04 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:16:04 volumio volumio[1311]: info: ----- MyVolumio plugins startup ---- Jun 30 14:16:04 volumio volumio[1311]: info: ------------------------------------------- Jun 30 14:16:04 volumio volumio[1311]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=info msg="zeroconf server listening on port 36299" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="obtained new client token: AACY7PyNN3QNw62zXWUy/4q9nzT97KDlZVJKg48xnUNKS4lxvNVBiT4h4cos5T1uyC09u/BPh8WlRW6xG3+FL4PG1xEkYAMnWHyUqMZKbnti7635awXwCohmKxP1oZii4Qr6Sa8I6pmJCITjEB7C6TJZmDcILlyi6WnPynuyTZEAF9Ea/mLocdoT1nWtyKg7LN21GsGPcyUrRqQi5yGvzSqx/ZnmfdRUvqAvAhKUbqYTt65jeo6a34SUF6kH" Jun 30 14:16:04 volumio volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Jun 30 14:16:04 volumio volumio[1311]: assert.ok(self.idling) Jun 30 14:16:04 volumio volumio[1311]: error: The expression evaluated to a falsy value: Jun 30 14:16:04 volumio volumio[1311]: assert.ok(self.idling) Jun 30 14:16:04 volumio volumio[1311]: info: MPD running with PID1559 Jun 30 14:16:04 volumio volumio[1311]: ,establishing connection Jun 30 14:16:04 volumio volumio[1311]: error: updateQueue error: null Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 30 14:16:04 volumio volumio[1311]: error: updateQueue error: null Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="completed keyexchange" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="completed challenge" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=info msg="authenticated AP as ogaol2dwuwoi7ndw7qbstszqq" Jun 30 14:16:04 volumio volumio[1533]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=info msg="authenticated Login5 as ogaol2dwuwoi7ndw7qbstszqq" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="initializing zeroconf session, username: ogaol2dwuwoi7ndw7qbstszqq" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="dealer connection opened" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=trace msg="starting accesspoint recv loop" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=trace msg="starting dealer recv loop" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=trace msg="received accesspoint ping" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 30 14:16:04 volumio go-librespot[1606]: time="2025-06-30T14:16:04+02:00" level=debug msg="received connection id: MjFmYjVjZGEtNjE3Yi00MjlmLWIyZDEtYjM4ZGFlMWEwNGJkK2RlYWxlcit0Y3A6Ly8wYWIxNTE4MS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTNFODU0OEZBRjMyNEM3Q0ZBQTA1NDkzRTBERUZBQjVGRTlBOEUzOUZEOTMyRjBDRDBFMTJEM0UxRUNDQjBCOA==" Jun 30 14:16:05 volumio go-librespot[1606]: time="2025-06-30T14:16:05+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 30 14:16:05 volumio go-librespot[1606]: time="2025-06-30T14:16:05+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 30 14:16:05 volumio go-librespot[1606]: time="2025-06-30T14:16:05+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 30 14:16:05 volumio go-librespot[1606]: time="2025-06-30T14:16:05+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 30 14:16:05 volumio go-librespot[1606]: time="2025-06-30T14:16:05+02:00" level=trace msg="received accesspoint pong ack" Jun 30 14:16:05 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 30 14:16:05 volumio volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 14:16:05 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 14:16:05 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:05 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:05 volumio volumio[1311]: info: CoreCommandRouter::volumioGetQueue Jun 30 14:16:05 volumio volumio[1311]: info: CoreStateMachine::getQueue Jun 30 14:16:05 volumio volumio[1311]: info: CorePlayQueue::getQueue Jun 30 14:16:05 volumio volumio[1311]: info: Listing playlists Jun 30 14:16:05 volumio volumio[1311]: info: go-librespot daemon successfully initialized Jun 30 14:16:05 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 14:16:05 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:05 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:05 volumio sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:16:05 volumio sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:05 volumio sudo[1684]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:05 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 12 Jun 30 14:16:05 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:05 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:06 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 14:16:06 volumio volumio[1311]: info: Listing playlists Jun 30 14:16:06 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:16:06 volumio volumio[1311]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 30 14:16:08 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Jun 30 14:16:08 volumio go-librespot[1606]: time="2025-06-30T14:16:08+02:00" level=debug msg="new websocket client" Jun 30 14:16:08 volumio volumio[1311]: info: Connection to go-librespot Websocket established Jun 30 14:16:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 14:16:10 volumio volumio[1311]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 30 14:16:11 volumio volumio[1311]: info: CoreCommandRouter::Close All Modals sent Jun 30 14:16:12 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 30 14:16:12 volumio volumio[1311]: info: Getting Spotify volume Jun 30 14:16:12 volumio volumio[1311]: info: Spotify volume: 100 Jun 30 14:16:13 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 30 14:16:14 volumio volumio[1311]: info: Enabling plugin bluetooth_controller Jun 30 14:16:14 volumio volumio[1311]: info: Loading plugin "bluetooth_controller"... Jun 30 14:16:16 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:16 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin bluetooth to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin multiroom to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin metavolumio to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin cd_controller to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 30 14:16:16 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 30 14:16:20 volumio volumio[1311]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 30 14:16:20 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 30 14:16:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:20 volumio volumio[1311]: info: Starting MyVolumio Remote Streaming Endpoints Jun 30 14:16:20 volumio volumio[1311]: info: MyVolumio login type: Token Jun 30 14:16:20 volumio volumio[1311]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 30 14:16:20 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 30 14:16:23 volumio volumio[1311]: info: Starting Streaming Service Transparent Proxy Jun 30 14:16:23 volumio volumio[1311]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 30 14:16:23 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 30 14:16:23 volumio volumio[1311]: info: Streaming services startup Jun 30 14:16:23 volumio volumio[1311]: info: Starting Streaming Daemon Jun 30 14:16:23 volumio volumio[1311]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 30 14:16:23 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 30 14:16:23 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:24 volumio volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 30 14:16:24 volumio volumio[1311]: bluetooth controller exists Jun 30 14:16:24 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:25 volumio ntpd[792]: Soliciting pool server 193.25.222.136 Jun 30 14:16:25 volumio ntpd[792]: Soliciting pool server 193.70.94.182 Jun 30 14:16:26 volumio ntpd[792]: Soliciting pool server 178.215.228.24 Jun 30 14:16:26 volumio ntpd[792]: Soliciting pool server 162.159.200.123 Jun 30 14:16:26 volumio ntpd[792]: Soliciting pool server 193.59.134.156 Jun 30 14:16:27 volumio ntpd[792]: Soliciting pool server 212.127.95.218 Jun 30 14:16:27 volumio ntpd[792]: Soliciting pool server 91.210.128.220 Jun 30 14:16:27 volumio ntpd[792]: Soliciting pool server 162.159.200.1 Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:27 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:28 volumio ntpd[792]: Soliciting pool server 51.68.141.5 Jun 30 14:16:28 volumio ntpd[792]: Soliciting pool server 91.212.242.20 Jun 30 14:16:28 volumio ntpd[792]: Soliciting pool server 89.161.47.136 Jun 30 14:16:28 volumio ntpd[792]: Soliciting pool server 194.146.251.101 Jun 30 14:16:28 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:28 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:28 volumio volumio[1311]: Cannot compose Albumart path Jun 30 14:16:28 volumio volumio[1311]: STREAMING PROXY: Starting server on port 3245 Jun 30 14:16:28 volumio volumio[1311]: Node JS runtime: 14 Jun 30 14:16:28 volumio volumio[1311]: error: Cannot start Volumio Streaming Daemon Jun 30 14:16:28 volumio volumio[1311]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 30 14:16:28 volumio volumio[1311]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 30 14:16:29 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 14:16:29 volumio volumio[1311]: info: Discoverable: false Jun 30 14:16:29 volumio ntpd[792]: Soliciting pool server 213.222.217.10 Jun 30 14:16:29 volumio ntpd[792]: Soliciting pool server 89.25.168.148 Jun 30 14:16:29 volumio ntpd[792]: Soliciting pool server 188.122.1.224 Jun 30 14:16:29 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Jun 30 14:16:29 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Jun 30 14:16:29 volumio volumio[1311]: info: Starting bluetooth device scan Jun 30 14:16:29 volumio volumio[1311]: info: Found bluetooth devices: { Jun 30 14:16:29 volumio volumio[1311]: "message": "pushBluetoothDevices", Jun 30 14:16:29 volumio volumio[1311]: "payload": { Jun 30 14:16:29 volumio volumio[1311]: "hasBluetooth": true, Jun 30 14:16:29 volumio volumio[1311]: "devices": [] Jun 30 14:16:29 volumio volumio[1311]: } Jun 30 14:16:29 volumio volumio[1311]: } Jun 30 14:16:29 volumio volumio[1311]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 30 14:16:30 volumio ntpd[792]: Soliciting pool server 46.174.178.150 Jun 30 14:16:30 volumio ntpd[792]: Soliciting pool server 2001:6a0:0:31::2 Jun 30 14:16:30 volumio ntpd[792]: Soliciting pool server 94.154.96.7 Jun 30 14:16:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:16:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 14:16:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 30 14:16:30 volumio volumio-remote-updater[616]: No test mode Jun 30 14:16:30 volumio volumio-remote-updater[616]: No alpha test mode Jun 30 14:16:30 volumio volumio[1311]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Various Fixes for QOBUZ Connect
    • Various Fixes for Tidal Connect



 

","title":"Update v3.816","updateavailable":true} Jun 30 14:16:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 30 14:16:31 volumio volumio[1311]: info: MyVolumio token set successfully Jun 30 14:16:31 volumio volumio[1311]: info: MYVOLUMIO: Adding device Jun 30 14:16:31 volumio volumio[1311]: info: MYVOLUMIO: Evaluating Server Jun 30 14:16:33 volumio ntpd[792]: receive: Unexpected origin timestamp 0xec0cfea0.10d00a63 does not match aorg 0000000000.00000000 from server@193.59.134.156 xmt 0xec0cfea1.7fe811d4 Jun 30 14:16:33 volumio ntpd[792]: receive: Unexpected origin timestamp 0xec0cfea0.10c365a1 does not match aorg 0000000000.00000000 from server@91.210.128.220 xmt 0xec0cfea1.7fdbb408 Jun 30 14:16:33 volumio ntpd[792]: receive: Unexpected origin timestamp 0xec0cfea0.10bead9c does not match aorg 0000000000.00000000 from server@91.212.242.20 xmt 0xec0cfea1.802eb925 Jun 30 14:16:33 volumio ntpd[792]: receive: Unexpected origin timestamp 0xec0cfea0.10d6631f does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xec0cfea1.822e02fc Jun 30 14:16:33 volumio volumio[1311]: info: MyVolumio status changed Jun 30 14:16:33 volumio volumio[1311]: info: Streaming services startup Jun 30 14:16:33 volumio volumio[1311]: info: Starting Streaming Daemon Jun 30 14:16:33 volumio volumio[1311]: info: Removing browser output: myVolumio user plan is not superstar Jun 30 14:16:33 volumio volumio[1311]: info: Removing audio output: Jun 30 14:16:33 volumio volumio[1311]: info: Stoppping Tunnel 1 Jun 30 14:16:34 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 30 14:16:34 volumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 30 14:16:34 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:34 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:16:34 volumio volumio[1311]: info: Setting Geolocation for MyVolumio to eu4 Jun 30 14:16:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:34 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:34 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Jun 30 14:16:34 volumio volumio[1311]: error: Cannot start Volumio Streaming Daemon Jun 30 14:16:34 volumio volumio[1311]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 30 14:16:34 volumio volumio[1311]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 30 14:16:34 volumio volumio[1311]: info: Remote SSH Stopped Jun 30 14:16:34 volumio volumio[1311]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 30 14:16:35 volumio volumio[1311]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 30 14:16:35 volumio volumio[1311]: info: Updating MyVolumio device info Jun 30 14:16:35 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:36 volumio volumio[1311]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 30 14:16:36 volumio go-librespot[1606]: time="2025-06-30T14:16:36+02:00" level=trace msg="sent dealer ping" Jun 30 14:16:36 volumio go-librespot[1606]: time="2025-06-30T14:16:36+02:00" level=trace msg="received dealer pong" Jun 30 14:16:36 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 14:16:36 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:36 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:36 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 30 14:16:36 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:36 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:36 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 14:16:36 volumio volumio[1311]: info: Listing playlists Jun 30 14:16:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:16:38 volumio volumio[1311]: info: MYVOLUMIO: Adding device Jun 30 14:16:38 volumio volumio[1311]: info: MYVOLUMIO: Evaluating Server Jun 30 14:16:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 14:16:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 30 14:16:39 volumio volumio[1311]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:40 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 14:16:40 volumio volumio[1311]: info: Listing playlists Jun 30 14:16:40 volumio volumio[1311]: info: Setting Geolocation for MyVolumio to eu6 Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:16:40 volumio volumio[1311]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 30 14:16:40 volumio volumio[1311]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:41 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 14:16:41 volumio volumio[1311]: info: Listing playlists Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:16:41 volumio volumio[1311]: info: Updating MyVolumio device info Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::volumioGetBrowseSources Jun 30 14:16:41 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 14:16:41 volumio volumio[1311]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 30 14:16:43 volumio volumio[1311]: info: Enabling plugin bluetooth_controller Jun 30 14:16:43 volumio volumio[1311]: info: Loading plugin "bluetooth_controller"... Jun 30 14:16:45 volumio volumio[1311]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 30 14:16:45 volumio volumio[1311]: info: Completed starting MyVolumio Plugin Jun 30 14:16:45 volumio volumio[1311]: [Metrics] CommandRouter: 61s 239.12ms Jun 30 14:16:45 volumio volumio[1311]: info: CoreCommandRouter::volumiosetStartupVolume Jun 30 14:16:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 14:16:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 14:16:45 volumio volumio[1311]: info: CoreCommandRouter::Close All Modals sent Jun 30 14:16:45 volumio volumio[1311]: info: CoreCommandRouter::Close All Modals sent Jun 30 14:16:45 volumio volumio[1311]: info: Enabling plugin bluetooth_controller Jun 30 14:16:45 volumio volumio[1311]: info: Loading plugin "bluetooth_controller"... Jun 30 14:16:46 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 30 14:16:46 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 30 14:16:46 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 30 14:16:46 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 30 14:16:46 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:16:46 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Jun 30 14:16:46 volumio volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 30 14:16:50 volumio volumio[1311]: info: Stopping bluetooth device scan Jun 30 14:16:51 volumio volumio[1311]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Jun 30 14:16:51 volumio volumio[1311]: info: Uninstalling plugin bluetooth_controller Jun 30 14:16:51 volumio volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 14:16:51 volumio volumio[1311]: TypeError: Cannot read property 'close' of undefined Jun 30 14:16:51 volumio volumio[1311]: at BluetoothController.onStop (/data/plugins/audio_interface/bluetooth_controller/index.js:137:21) Jun 30 14:16:51 volumio volumio[1311]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 30 14:16:51 volumio volumio[1311]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jun 30 14:16:51 volumio volumio[1311]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jun 30 14:16:51 volumio volumio[1311]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Jun 30 14:16:51 volumio volumio[1311]: at Socket.emit (events.js:315:20) Jun 30 14:16:51 volumio volumio[1311]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 30 14:16:51 volumio volumio[1311]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 30 14:16:51 volumio volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 14:16:52 volumio volumio[1533]: .......................................................................................................................................................................++++ Jun 30 14:16:53 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 14:15 Jun 30 14:16:53 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"