Jan 26 09:03:01 musicgenie2 systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 26 09:03:01 musicgenie2 systemd[1]: setdatetime-helper.service: Consumed 1.151s CPU time. Jan 26 09:03:01 musicgenie2 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jan 26 09:03:01 musicgenie2 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jan 26 09:03:01 musicgenie2 systemd[1]: Starting volumio-restart.service - Restart Volumio service daily... Jan 26 09:03:01 musicgenie2 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 26 09:03:01 musicgenie2 systemd[1]: Stopping volumio.service - Volumio Backend Module... Jan 26 09:03:01 musicgenie2 systemd[1]: dynamicswap.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: volumio.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 26 09:03:01 musicgenie2 systemd[1]: volumio.service: Consumed 7.914s CPU time. Jan 26 09:03:01 musicgenie2 systemd[1]: Started volumio.service - Volumio Backend Module. Jan 26 09:03:01 musicgenie2 systemd[1]: volumio-restart.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: Finished volumio-restart.service - Restart Volumio service daily. Jan 26 09:03:01 musicgenie2 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jan 26 09:03:01 musicgenie2 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jan 26 09:03:02 musicgenie2 winbindd[1382]: [2026/01/26 09:03:02.003993, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Jan 26 09:03:02 musicgenie2 winbindd[1382]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'MUSICGENIE2' failed 'NT_STATUS_IO_TIMEOUT' Jan 26 09:03:02 musicgenie2 systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 26 09:03:02 musicgenie2 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 26 09:03:02 musicgenie2 systemd[1]: Reached target graphical.target - Graphical Interface. Jan 26 09:03:02 musicgenie2 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 26 09:03:02 musicgenie2 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 26 09:03:02 musicgenie2 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 26 09:03:02 musicgenie2 systemd[1]: Startup finished in 22.347s (kernel) + 47.444s (userspace) = 1min 9.792s. Jan 26 09:03:05 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:05] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 26 09:03:05 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:05] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 26 09:03:05 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:05] [error] handle_connect error: Connection refused Jan 26 09:03:05 musicgenie2 systemd[1]: office-radio.service: Scheduled restart job, restart counter is at 1. Jan 26 09:03:05 musicgenie2 systemd[1]: Stopped office-radio.service - Office Radio Service. Jan 26 09:03:05 musicgenie2 systemd[1]: office-radio.service: Consumed 34.979s CPU time. Jan 26 09:03:05 musicgenie2 systemd[1]: Started office-radio.service - Office Radio Service. Jan 26 09:03:08 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:03:08 musicgenie2 volumio[1462]: info: ----- Volumio3 ---- Jan 26 09:03:08 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:03:08 musicgenie2 volumio[1462]: info: ----- System startup ---- Jan 26 09:03:08 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:03:09 musicgenie2 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 26 09:03:09 musicgenie2 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jan 26 09:03:09 musicgenie2 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:09 musicgenie2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:09 musicgenie2 systemd[1]: e2scrub_all.service: Deactivated successfully. Jan 26 09:03:09 musicgenie2 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jan 26 09:03:09 musicgenie2 upmpdcli[1500]: Could not open config: /tmp/upmpdcli.conf Jan 26 09:03:09 musicgenie2 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 09:03:09 musicgenie2 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 09:03:12 musicgenie2 volumio[1462]: info: MYVOLUMIO Environment detected Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Plugin folders cleanup Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning into folder /volumio/app/plugins/ Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category audio_interface Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category miscellanea Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category music_service Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category plugins.json Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category system_controller Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category user_interface Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning into folder /data/plugins/ Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Scanning category music_service Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Plugin folders cleanup completed Jan 26 09:03:12 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:03:12 musicgenie2 volumio[1462]: info: ----- Core plugins startup ---- Jan 26 09:03:12 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Adding plugin upnp to MyMusic Plugins Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Loading plugins from folder /data/plugins/ Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Loading plugin "system"... Jan 26 09:03:12 musicgenie2 volumio[1462]: info: Loading plugin "appearance"... Jan 26 09:03:15 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:15] [connect] Successful connection Jan 26 09:03:17 musicgenie2 volumio[1462]: info: Loading plugin "network"... Jan 26 09:03:17 musicgenie2 volumio[1462]: info: Refreshing Cached IP Addresses Jan 26 09:03:17 musicgenie2 sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 09:03:17 musicgenie2 volumio[1462]: info: Loading plugin "services"... Jan 26 09:03:17 musicgenie2 sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:17 musicgenie2 volumio[1462]: info: Loading plugin "alsa_controller"... Jan 26 09:03:17 musicgenie2 sudo[1510]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:17 musicgenie2 sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 09:03:17 musicgenie2 sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:17 musicgenie2 sudo[1511]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:17 musicgenie2 sudo[1519]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 26 09:03:17 musicgenie2 sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:18 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "wizard"... Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "networkfs"... Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Starting Udev Watcher for removable devices Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Ignoring mount for partition: boot Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Ignoring mount for partition: volumio Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Ignoring mount for partition: volumio_data Jan 26 09:03:18 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "volumio_command_line_client"... Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "upnp"... Jan 26 09:03:18 musicgenie2 volumio[1462]: info: [1769418198526] Starting Upmpd Daemon Jan 26 09:03:18 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "my_music"... Jan 26 09:03:18 musicgenie2 volumio[1462]: info: Loading plugin "mpd"... Jan 26 09:03:18 musicgenie2 sudo[1519]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:19 musicgenie2 volumio[1462]: info: Loading plugin "upnp_browser"... Jan 26 09:03:24 musicgenie2 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Jan 26 09:03:24 musicgenie2 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:24 musicgenie2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:24 musicgenie2 upmpdcli[1546]: Could not open config: /tmp/upmpdcli.conf Jan 26 09:03:24 musicgenie2 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 09:03:24 musicgenie2 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Starting UPNP Browser Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Loading plugin "alarm-clock"... Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Loading plugin "airplay_emulation"... Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Starting Shairport Sync Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Loading plugin "last_100"... Jan 26 09:03:25 musicgenie2 volumio[1462]: info: Loading plugin "webradio"... Jan 26 09:03:26 musicgenie2 volumio[1462]: info: Loading plugin "i2s_dacs"... Jan 26 09:03:26 musicgenie2 volumio[1462]: info: Loading plugin "volumiodiscovery"... Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** For more information see Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 09:03:26 musicgenie2 volumio[1462]: *** WARNING *** For more information see Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** For more information see Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 09:03:26 musicgenie2 node[1462]: *** WARNING *** For more information see Jan 26 09:03:26 musicgenie2 volumio[1462]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 09:03:26 musicgenie2 volumio[1462]: info: Discovery: Started advertising with name: MusicGenie2 Jan 26 09:03:26 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 09:03:26 musicgenie2 volumio[1462]: info: Loading plugin "spop"... Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-DISCONNECTED bssid=28:70:4e:76:84:5e reason=34 Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: BSSID 28:70:4e:76:84:5e ignore list count incremented to 2, ignoring for 10 seconds Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: carrier lost Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB Jan 26 09:03:27 musicgenie2 avahi-daemon[1389]: Withdrawing address record for 10.0.2.170 on wlan0. Jan 26 09:03:27 musicgenie2 avahi-daemon[1389]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.170. Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: deleting route to 10.0.2.0/24 Jan 26 09:03:27 musicgenie2 avahi-daemon[1389]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: deleting default route via 10.0.2.254 Jan 26 09:03:27 musicgenie2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:03:27 musicgenie2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 26 09:03:27 musicgenie2 systemd[1]: welcome.service: Deactivated successfully. Jan 26 09:03:27 musicgenie2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 26 09:03:27 musicgenie2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: Trying to associate with 28:70:4e:76:83:9a (SSID='AER-Studios-WiFi' freq=2472 MHz) Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: FT: Invalid key management type (2) Jan 26 09:03:27 musicgenie2 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 26 09:03:27 musicgenie2 welcome[1574]: Resolved ip:[0] Jan 26 09:03:27 musicgenie2 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 26 09:03:27 musicgenie2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: Associated with 28:70:4e:76:83:9a Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: WPA: Key negotiation completed with 28:70:4e:76:83:9a [PTK=CCMP GTK=CCMP] Jan 26 09:03:27 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-CONNECTED - Connection to 28:70:4e:76:83:9a completed [id=2 id_str=] Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: carrier acquired Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: IAID eb:64:59:c3 Jan 26 09:03:27 musicgenie2 dhcpcd[745]: wlan0: soliciting an IPv6 router Jan 26 09:03:28 musicgenie2 ntpd[947]: IO: Deleting interface #3 wlan0, 10.0.2.170#123, interface stats: received=96, sent=96, dropped=0, active_time=31 secs Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 178.79.150.226 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 85.199.214.99 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 93.93.131.118 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 149.22.188.7 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 194.213.3.203 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 82.16.107.176 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 178.79.138.215 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 109.74.197.50 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 109.74.192.36 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 176.58.124.166 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 185.177.149.33 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 213.219.38.86 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 95.215.175.2 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 212.71.233.40 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 91.109.118.94 unlink local addr 10.0.2.170 -> Jan 26 09:03:28 musicgenie2 ntpd[947]: PROTO: 83.151.207.133 unlink local addr 10.0.2.170 -> Jan 26 09:03:29 musicgenie2 dhcpcd[745]: wlan0: soliciting a DHCP lease Jan 26 09:03:30 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:30] [connect] Successful connection Jan 26 09:03:30 musicgenie2 dhcpcd[745]: wlan0: offered 10.0.2.170 from 10.0.2.254 Jan 26 09:03:30 musicgenie2 dhcpcd[745]: wlan0: probing address 10.0.2.170/24 Jan 26 09:03:33 musicgenie2 volumio[1462]: info: Loading plugin "outputs"... Jan 26 09:03:33 musicgenie2 volumio[1462]: info: Loading plugin "albumart"... Jan 26 09:03:33 musicgenie2 volumio[1462]: info: Plugin example_plugin is not enabled Jan 26 09:03:33 musicgenie2 volumio[1462]: info: Loading plugin "inputs"... Jan 26 09:03:33 musicgenie2 volumio[1462]: info: Loading plugin "updater_comm"... Jan 26 09:03:34 musicgenie2 volumio[1462]: info: Plugin mpdemulation is not enabled Jan 26 09:03:34 musicgenie2 volumio[1462]: info: Loading plugin "rest_api"... Jan 26 09:03:35 musicgenie2 volumio[1462]: info: Loading plugin "websocket"... Jan 26 09:03:35 musicgenie2 volumio[1462]: info: Starting Socket.io Server version 1.7.4 Jan 26 09:03:35 musicgenie2 volumio[1462]: info: Loading plugin "music-genie-dj"... Jan 26 09:03:35 musicgenie2 dhcpcd[745]: wlan0: leased 10.0.2.170 for 86400 seconds Jan 26 09:03:35 musicgenie2 avahi-daemon[1389]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.170. Jan 26 09:03:35 musicgenie2 avahi-daemon[1389]: New relevant interface wlan0.IPv4 for mDNS. Jan 26 09:03:35 musicgenie2 avahi-daemon[1389]: Registering new address record for 10.0.2.170 on wlan0.IPv4. Jan 26 09:03:35 musicgenie2 dhcpcd[745]: wlan0: adding route to 10.0.2.0/24 Jan 26 09:03:35 musicgenie2 dhcpcd[745]: wlan0: adding default route via 10.0.2.254 Jan 26 09:03:35 musicgenie2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:03:35 musicgenie2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 26 09:03:35 musicgenie2 systemd[1]: welcome.service: Deactivated successfully. Jan 26 09:03:35 musicgenie2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 26 09:03:35 musicgenie2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 26 09:03:35 musicgenie2 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 26 09:03:35 musicgenie2 welcome[1619]: Resolved ip:[1] 10.0.2.170 Jan 26 09:03:35 musicgenie2 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 26 09:03:35 musicgenie2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:03:36 musicgenie2 ntpd[947]: IO: Listen normally on 4 wlan0 10.0.2.170:123 Jan 26 09:03:36 musicgenie2 ntpd[947]: IO: new interface(s) found: waking up resolver Jan 26 09:03:37 musicgenie2 volumio[1596]: Forking 3 albumart workers Jan 26 09:03:39 musicgenie2 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Jan 26 09:03:39 musicgenie2 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:39 musicgenie2 volumio[1462]: info: Applying required configuration parameters for plugin music-genie-dj Jan 26 09:03:39 musicgenie2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:39 musicgenie2 volumio[1462]: info: Music Genie DJ: Config file loaded from: /data/configuration/music_service/music-genie-dj/config.json Jan 26 09:03:39 musicgenie2 volumio[1462]: info: Music Genie DJ: API Host: http://localhost:3002 Jan 26 09:03:39 musicgenie2 upmpdcli[1674]: Could not open config: /tmp/upmpdcli.conf Jan 26 09:03:39 musicgenie2 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 09:03:39 musicgenie2 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 09:03:40 musicgenie2 volumio[1462]: info: Loading i18n strings for locale en Jan 26 09:03:40 musicgenie2 volumio[1462]: Updating browse sources language Jan 26 09:03:40 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::initPlayerControls Jan 26 09:03:45 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:03:45] [connect] Successful connection Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 09:03:45 musicgenie2 volumio[1462]: Express server listening on port 3000 Jan 26 09:03:45 musicgenie2 volumio[1462]: [Metrics] WebUI: 40s 34.93ms Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreStateMachine::resetVolumioState Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreStateMachine::getcurrentVolume Jan 26 09:03:45 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 09:03:46 musicgenie2 sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 09:03:46 musicgenie2 sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:46 musicgenie2 sudo[1681]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:46 musicgenie2 sudo[1683]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 09:03:46 musicgenie2 sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:46 musicgenie2 systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Jan 26 09:03:46 musicgenie2 sudo[1683]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:47 musicgenie2 volumio[1462]: info: Volumio Network Manager: Network status updated: 2 Jan 26 09:03:47 musicgenie2 fstrim[1687]: /boot: 268.5 MiB (281587712 bytes) trimmed on /dev/mmcblk0p1 Jan 26 09:03:47 musicgenie2 systemd[1]: fstrim.service: Deactivated successfully. Jan 26 09:03:47 musicgenie2 systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Jan 26 09:03:48 musicgenie2 volumio[1462]: info: VolumeController:: Volume=100 Mute =false Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CoreStateMachine::pushState Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrack 0 Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioPushState Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CoreStateMachine::updateTrackBlock Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrackBlock Jan 26 09:03:48 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 09:03:49 musicgenie2 volumio[1462]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 26 09:03:49 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreStateMachine::setRepeat false single undefined Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreStateMachine::pushState Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrack 0 Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioPushState Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreStateMachine::setRandom false Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreStateMachine::pushState Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrack 0 Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 09:03:50 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioPushState Jan 26 09:03:50 musicgenie2 volumio[1462]: info: Setting Device type: Raspberry PI Jan 26 09:03:51 musicgenie2 volumio[1462]: info: VolumeController:: Volume=100 Mute =false Jan 26 09:03:51 musicgenie2 volumio[1462]: info: CoreStateMachine::pushState Jan 26 09:03:51 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrack 0 Jan 26 09:03:51 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioPushState Jan 26 09:03:51 musicgenie2 volumio[1462]: info: Discovery: A device disappeared from network Jan 26 09:03:51 musicgenie2 volumio[1462]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 26 09:03:52 musicgenie2 sudo[1716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 09:03:52 musicgenie2 sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:52 musicgenie2 volumio[1462]: info: Completed loading Core Plugins Jan 26 09:03:52 musicgenie2 volumio[1462]: info: Preparing to generate the ALSA configuration file Jan 26 09:03:53 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioGetState Jan 26 09:03:53 musicgenie2 volumio[1462]: info: CorePlayQueue::getTrack 0 Jan 26 09:03:53 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioGetQueue Jan 26 09:03:53 musicgenie2 volumio[1462]: info: CoreStateMachine::getQueue Jan 26 09:03:53 musicgenie2 volumio[1462]: info: CorePlayQueue::getQueue Jan 26 09:03:53 musicgenie2 volumio[1462]: info: Asound.conf file unchanged, so no further update is needed Jan 26 09:03:53 musicgenie2 volumio[1462]: info: Output device has changed, restarting MPD Jan 26 09:03:54 musicgenie2 volumio[1462]: info: Output device has changed, restarting Shairport Sync Jan 26 09:03:54 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:54 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 09:03:54 musicgenie2 sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 09:03:54 musicgenie2 sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:54 musicgenie2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 09:03:54 musicgenie2 systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 09:03:54 musicgenie2 volumio[1462]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 09:03:54 musicgenie2 sudo[1737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 09:03:54 musicgenie2 sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:54 musicgenie2 volumio[1462]: info: ___________ START PLUGINS ___________ Jan 26 09:03:54 musicgenie2 volumio[1462]: info: ControllerMpd::onStart: Initializing MPD Jan 26 09:03:54 musicgenie2 volumio[1462]: info: Creating MPD Configuration file Jan 26 09:03:54 musicgenie2 sudo[1737]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:54 musicgenie2 sudo[1744]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 09:03:54 musicgenie2 sudo[1744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 09:03:54 musicgenie2 sudo[1746]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 09:03:54 musicgenie2 sudo[1744]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:54 musicgenie2 volumio[1631]: Starting albumart workers Jan 26 09:03:54 musicgenie2 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Jan 26 09:03:54 musicgenie2 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:54 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 09:03:54 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 09:03:54 musicgenie2 volumio[1462]: info: [1769418234907] CoreMusicLibrary::Adding element Media Servers Jan 26 09:03:54 musicgenie2 sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 09:03:54 musicgenie2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 09:03:54 musicgenie2 sudo[1716]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:54 musicgenie2 sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 09:03:55 musicgenie2 sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:55 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 09:03:55 musicgenie2 sudo[1749]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:55 musicgenie2 sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 09:03:55 musicgenie2 volumio[1462]: info: UPNP Browser: Client initialized successfully Jan 26 09:03:55 musicgenie2 systemd[1]: mpd.service: Deactivated successfully. Jan 26 09:03:55 musicgenie2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 09:03:55 musicgenie2 systemd[1]: mpd.socket: Deactivated successfully. Jan 26 09:03:55 musicgenie2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 09:03:55 musicgenie2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 09:03:55 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:55 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 09:03:55 musicgenie2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 09:03:55 musicgenie2 systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 09:03:55 musicgenie2 volumio[1630]: Starting albumart workers Jan 26 09:03:56 musicgenie2 sudo[1766]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 09:03:56 musicgenie2 sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 09:03:56 musicgenie2 sudo[1771]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 09:03:56 musicgenie2 sudo[1766]: pam_unix(sudo:session): session closed for user root Jan 26 09:03:56 musicgenie2 volumio[1462]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 09:03:56 musicgenie2 volumio[1462]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 09:03:56 musicgenie2 volumio[1462]: info: [1769418236912] CoreMusicLibrary::Adding element Last_100 Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 09:03:56 musicgenie2 volumio[1462]: info: [1769418236915] CoreMusicLibrary::Adding element Webradio Jan 26 09:03:56 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 09:03:57 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 09:03:57 musicgenie2 volumio[1462]: info: Initializing BBC Radios Jan 26 09:03:58 musicgenie2 volumio[1632]: Starting albumart workers Jan 26 09:03:58 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 09:03:58 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 09:03:58 musicgenie2 volumio[1462]: info: Creating Spotify config file Jan 26 09:03:58 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 09:04:00 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:04:00] [connect] Successful connection Jan 26 09:04:05 musicgenie2 volumio[1462]: info: Loading i18n strings for locale en Jan 26 09:04:05 musicgenie2 volumio[1462]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 09:04:05 musicgenie2 volumio[1462]: info: [1769418245452] CoreMusicLibrary::Adding element Music Genie DJ Jan 26 09:04:05 musicgenie2 volumio[1462]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 09:04:05 musicgenie2 volumio[1462]: Cannot find translation for source Music Genie DJ Jan 26 09:04:05 musicgenie2 volumio[1462]: info: Volumio Calling Home Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-DISCONNECTED bssid=28:70:4e:76:83:9a reason=34 Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: BSSID 28:70:4e:76:83:9a ignore list count incremented to 2, ignoring for 10 seconds Jan 26 09:04:05 musicgenie2 dhcpcd[745]: wlan0: carrier lost Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB Jan 26 09:04:05 musicgenie2 avahi-daemon[1389]: Withdrawing address record for 10.0.2.170 on wlan0. Jan 26 09:04:05 musicgenie2 avahi-daemon[1389]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.170. Jan 26 09:04:05 musicgenie2 dhcpcd[745]: wlan0: deleting route to 10.0.2.0/24 Jan 26 09:04:05 musicgenie2 avahi-daemon[1389]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 26 09:04:05 musicgenie2 dhcpcd[745]: wlan0: deleting default route via 10.0.2.254 Jan 26 09:04:05 musicgenie2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:04:05 musicgenie2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 26 09:04:05 musicgenie2 systemd[1]: welcome.service: Deactivated successfully. Jan 26 09:04:05 musicgenie2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 26 09:04:05 musicgenie2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: wlan0: Trying to associate with 28:70:4e:76:84:5e (SSID='AER-Studios-WiFi' freq=2442 MHz) Jan 26 09:04:05 musicgenie2 wpa_supplicant[1134]: FT: Invalid key management type (2) Jan 26 09:04:05 musicgenie2 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 26 09:04:05 musicgenie2 welcome[1861]: Resolved ip:[0] Jan 26 09:04:05 musicgenie2 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 26 09:04:05 musicgenie2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:04:06 musicgenie2 wpa_supplicant[1134]: wlan0: Associated with 28:70:4e:76:84:5e Jan 26 09:04:06 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 26 09:04:06 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Jan 26 09:04:06 musicgenie2 wpa_supplicant[1134]: wlan0: WPA: Key negotiation completed with 28:70:4e:76:84:5e [PTK=CCMP GTK=CCMP] Jan 26 09:04:06 musicgenie2 wpa_supplicant[1134]: wlan0: CTRL-EVENT-CONNECTED - Connection to 28:70:4e:76:84:5e completed [id=2 id_str=] Jan 26 09:04:06 musicgenie2 dhcpcd[745]: wlan0: carrier acquired Jan 26 09:04:06 musicgenie2 dhcpcd[745]: wlan0: IAID eb:64:59:c3 Jan 26 09:04:06 musicgenie2 dhcpcd[745]: wlan0: soliciting an IPv6 router Jan 26 09:04:07 musicgenie2 dhcpcd[745]: wlan0: rebinding lease of 10.0.2.170 Jan 26 09:04:07 musicgenie2 dhcpcd[745]: wlan0: probing address 10.0.2.170/24 Jan 26 09:04:07 musicgenie2 mpd[1772]: 2026-01-26T09:04:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 09:04:07 musicgenie2 systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 09:04:07 musicgenie2 ntpd[947]: IO: Deleting interface #4 wlan0, 10.0.2.170#123, interface stats: received=5, sent=5, dropped=9, active_time=31 secs Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 83.151.207.133 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 91.109.118.94 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 212.71.233.40 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 95.215.175.2 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 213.219.38.86 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 185.177.149.33 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 176.58.124.166 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 109.74.192.36 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 109.74.197.50 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 178.79.138.215 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 82.16.107.176 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 194.213.3.203 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 149.22.188.7 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 93.93.131.118 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 85.199.214.99 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 ntpd[947]: PROTO: 178.79.150.226 unlink local addr 10.0.2.170 -> Jan 26 09:04:07 musicgenie2 sudo[1736]: pam_unix(sudo:session): session closed for user root Jan 26 09:04:07 musicgenie2 sudo[1751]: pam_unix(sudo:session): session closed for user root Jan 26 09:04:09 musicgenie2 volumio[1462]: info: An error occurred while refreshing Spotify Token Error: connect ENETUNREACH 66.241.124.64:443 - Local (0.0.0.0:0) Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Completed starting Core Plugins Jan 26 09:04:10 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:04:10 musicgenie2 volumio[1462]: info: ----- MyVolumio plugins startup ---- Jan 26 09:04:10 musicgenie2 volumio[1462]: info: ------------------------------------------- Jan 26 09:04:10 musicgenie2 volumio[1462]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 09:04:10 musicgenie2 volumio[1462]: info: MPD Permissions set Jan 26 09:04:10 musicgenie2 volumio[1462]: info: MPD Permissions set Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Upmpdcli Daemon Started Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Restarting Browsing Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Restarting Browsing Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Restart already pending, ignoring duplicate call Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Restarting Browsing Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: Restart already pending, ignoring duplicate call Jan 26 09:04:10 musicgenie2 volumio[1462]: info: Discovery: A device disappeared from network Jan 26 09:04:11 musicgenie2 volumio[1462]: info: MPD running with PID1772 Jan 26 09:04:11 musicgenie2 volumio[1462]: ,establishing connection Jan 26 09:04:11 musicgenie2 volumio[1462]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 09:04:11 musicgenie2 volumio[1462]: Error: addMembership ENODEV Jan 26 09:04:11 musicgenie2 volumio[1462]: at Socket.addMembership (node:dgram:860:11) Jan 26 09:04:11 musicgenie2 volumio[1462]: at addMembership (/volumio/node_modules/node-ssdp/lib/index.js:264:16) { Jan 26 09:04:11 musicgenie2 volumio[1462]: errno: -19, Jan 26 09:04:11 musicgenie2 volumio[1462]: code: 'ENODEV', Jan 26 09:04:11 musicgenie2 volumio[1462]: syscall: 'addMembership' Jan 26 09:04:11 musicgenie2 volumio[1462]: } Jan 26 09:04:11 musicgenie2 volumio[1462]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 09:04:11 musicgenie2 dhcpcd[745]: wlan0: leased 10.0.2.170 for 86400 seconds Jan 26 09:04:11 musicgenie2 avahi-daemon[1389]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.2.170. Jan 26 09:04:11 musicgenie2 dhcpcd[745]: wlan0: adding route to 10.0.2.0/24 Jan 26 09:04:11 musicgenie2 avahi-daemon[1389]: New relevant interface wlan0.IPv4 for mDNS. Jan 26 09:04:11 musicgenie2 dhcpcd[745]: wlan0: adding default route via 10.0.2.254 Jan 26 09:04:11 musicgenie2 avahi-daemon[1389]: Registering new address record for 10.0.2.170 on wlan0.IPv4. Jan 26 09:04:11 musicgenie2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:04:11 musicgenie2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 26 09:04:11 musicgenie2 systemd[1]: welcome.service: Deactivated successfully. Jan 26 09:04:11 musicgenie2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 26 09:04:11 musicgenie2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 26 09:04:12 musicgenie2 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 26 09:04:12 musicgenie2 welcome[1940]: Resolved ip:[1] 10.0.2.170 Jan 26 09:04:12 musicgenie2 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 26 09:04:12 musicgenie2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 26 09:04:12 musicgenie2 ntpd[947]: IO: Listen normally on 5 wlan0 10.0.2.170:123 Jan 26 09:04:12 musicgenie2 ntpd[947]: IO: new interface(s) found: waking up resolver Jan 26 09:04:15 musicgenie2 volumio-remote-updater[683]: [2026-01-26 09:04:15] [connect] Successful connection Jan 26 09:04:18 musicgenie2 npx[1813]: INFO Accepting connections at http://localhost:8080 Jan 26 09:04:22 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:22 AM 10.0.2.189 GET / Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:22 AM 10.0.2.189 Returned 304 in 462 ms Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 GET /static/css/main.e32c4f7c.css Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 GET /static/js/main.56b4e0b7.js Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 Returned 304 in 112 ms Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 Returned 304 in 181 ms Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 GET /favicon.ico Jan 26 09:04:23 musicgenie2 npx[1813]: HTTP 1/26/2026 9:04:23 AM 10.0.2.189 Returned 304 in 67 ms Jan 26 09:04:23 musicgenie2 sudo[1977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 09:03' Jan 26 09:04:23 musicgenie2 sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"