May 26 13:28:26 volumio ntpd[981]: CLOCK: time stepped by 5429213.025773 May 26 13:28:26 volumio ntpd[981]: CLOCK: time changed from 2026-03-24 to 2026-05-26 May 26 13:28:26 volumio ntpd[981]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 26 13:28:26 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 26 13:28:26 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 26 13:28:26 volumio sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 13:28:26 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:26 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root May 26 13:28:26 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 26 13:28:26 volumio volumio[1389]: info: Loading plugin "services"... May 26 13:28:26 volumio sudo[1629]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 13:28:26 volumio sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:26 volumio volumio[1389]: info: Loading plugin "volumio5onboarding"... May 26 13:28:26 volumio sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 13:28:26 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:26 volumio sudo[1629]: pam_unix(sudo:session): session closed for user root May 26 13:28:26 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 26 13:28:26 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 26 13:28:26 volumio volumio[1389]: info: Loading plugin "alsa_controller"... May 26 13:28:27 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 13:28:27 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "wizard"... May 26 13:28:27 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 13:28:27 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 13:28:27 volumio systemd[1]: setdatetime-helper.service: Consumed 2.116s CPU time. May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "networkfs"... May 26 13:28:27 volumio volumio[1389]: info: Starting Udev Watcher for removable devices May 26 13:28:27 volumio volumio[1389]: info: Ignoring mount for partition: boot May 26 13:28:27 volumio volumio[1389]: info: Ignoring mount for partition: volumio May 26 13:28:27 volumio volumio[1389]: info: Ignoring mount for partition: volumio_data May 26 13:28:27 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "volumio_command_line_client"... May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "upnp"... May 26 13:28:27 volumio volumio[1389]: info: [1779802107800] Starting Upmpd Daemon May 26 13:28:27 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "my_music"... May 26 13:28:27 volumio volumio[1389]: info: Loading plugin "mpd"... May 26 13:28:28 volumio volumio[1389]: info: Loading plugin "upnp_browser"... May 26 13:28:29 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 26 13:28:33 volumio volumio[1389]: info: Starting UPNP Browser May 26 13:28:33 volumio volumio[1389]: info: Loading plugin "alarm-clock"... May 26 13:28:33 volumio volumio[1389]: info: Loading plugin "airplay_emulation"... May 26 13:28:33 volumio volumio[1389]: info: Starting Shairport Sync May 26 13:28:33 volumio volumio[1389]: info: Loading plugin "last_100"... May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "webradio"... May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "i2s_dacs"... May 26 13:28:34 volumio volumio[1389]: info: I2S DAC not set, start Auto-detection May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "volumiodiscovery"... May 26 13:28:34 volumio volumio[1389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 13:28:34 volumio volumio[1389]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 13:28:34 volumio volumio[1389]: *** WARNING *** For more information see May 26 13:28:34 volumio volumio[1389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 13:28:34 volumio volumio[1389]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 13:28:34 volumio volumio[1389]: *** WARNING *** For more information see May 26 13:28:34 volumio node[1389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 13:28:34 volumio node[1389]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 13:28:34 volumio node[1389]: *** WARNING *** For more information see May 26 13:28:34 volumio node[1389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 13:28:34 volumio node[1389]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 13:28:34 volumio node[1389]: *** WARNING *** For more information see May 26 13:28:34 volumio volumio[1389]: info: Applying required configuration parameters for plugin volumiodiscovery May 26 13:28:34 volumio volumio[1389]: info: Discovery: Started advertising with name: Volumio May 26 13:28:34 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "outputs"... May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "albumart"... May 26 13:28:34 volumio volumio[1389]: info: Plugin example_plugin is not enabled May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "inputs"... May 26 13:28:34 volumio volumio[1389]: info: Loading plugin "updater_comm"... May 26 13:28:35 volumio volumio[1389]: info: Plugin mpdemulation is not enabled May 26 13:28:35 volumio volumio[1389]: info: Loading plugin "rest_api"... May 26 13:28:35 volumio volumio[1389]: info: Loading plugin "websocket"... May 26 13:28:35 volumio volumio[1389]: info: Starting Socket.io Server version 1.7.4 May 26 13:28:35 volumio volumio[1389]: info: Loading i18n strings for locale en May 26 13:28:35 volumio volumio[1389]: Updating browse sources language May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::initPlayerControls May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 13:28:35 volumio volumio[1389]: Express server listening on port 3000 May 26 13:28:35 volumio volumio[1389]: [Metrics] WebUI: 19s 118.16ms May 26 13:28:35 volumio volumio[1389]: info: CoreStateMachine::resetVolumioState May 26 13:28:35 volumio volumio[1389]: info: CoreStateMachine::getcurrentVolume May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::volumioRetrievevolume May 26 13:28:35 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 13:28:36 volumio volumio[1389]: info: Cannot read play queue from file May 26 13:28:36 volumio volumio[1389]: info: Volumio Network Manager: Network status updated: 1 May 26 13:28:36 volumio volumio[1389]: info: VolumeController:: Volume=86 Mute =false May 26 13:28:36 volumio volumio[1389]: info: CoreStateMachine::pushState May 26 13:28:36 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:36 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock May 26 13:28:37 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioRetrievevolume May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::setRepeat null single undefined May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::pushState May 26 13:28:37 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::setRandom null May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::pushState May 26 13:28:37 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState May 26 13:28:37 volumio volumio[1389]: info: Setting Device type: Raspberry PI May 26 13:28:37 volumio volumio[1389]: info: Discovery: adding 0b64a6b7-8b2c-4aed-b876-7cb1c2183d46 May 26 13:28:37 volumio volumio[1389]: info: Discovery: Found device Volumio May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState May 26 13:28:37 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:37 volumio volumio[1389]: info: VolumeController:: Volume=86 Mute =false May 26 13:28:37 volumio volumio[1389]: info: CoreStateMachine::pushState May 26 13:28:37 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:37 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState May 26 13:28:38 volumio volumio[1389]: info: Discovery: this is already registered, 0b64a6b7-8b2c-4aed-b876-7cb1c2183d46 May 26 13:28:38 volumio volumio[1389]: info: Discovery: Found device Volumio May 26 13:28:38 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState May 26 13:28:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 May 26 13:28:38 volumio sudo[1725]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 13:28:38 volumio sudo[1725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:38 volumio sudo[1727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 13:28:38 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:38 volumio sudo[1725]: pam_unix(sudo:session): session closed for user root May 26 13:28:38 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root May 26 13:28:38 volumio volumio[1389]: info: Completed loading Core Plugins May 26 13:28:38 volumio volumio[1389]: info: Preparing to generate the ALSA configuration file May 26 13:28:38 volumio volumio[1685]: Forking 3 albumart workers May 26 13:28:38 volumio volumio[1389]: info: Asound.conf file unchanged, so no further update is needed May 26 13:28:38 volumio volumio[1389]: info: Output device has changed, restarting MPD May 26 13:28:38 volumio volumio[1389]: info: Output device has changed, restarting Shairport Sync May 26 13:28:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 13:28:39 volumio volumio[1389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 13:28:39 volumio volumio[1389]: info: ___________ START PLUGINS ___________ May 26 13:28:39 volumio sudo[1733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 13:28:39 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:39 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root May 26 13:28:39 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 13:28:39 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:39 volumio volumio[1389]: info: ControllerMpd::onStart: Initializing MPD May 26 13:28:39 volumio volumio[1389]: info: Creating MPD Configuration file May 26 13:28:39 volumio sudo[1763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 13:28:39 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:39 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 26 13:28:39 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. May 26 13:28:39 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 26 13:28:39 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 13:28:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 13:28:39 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root May 26 13:28:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 13:28:39 volumio sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 26 13:28:39 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:39 volumio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 13:28:39 volumio volumio[1389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 13:28:39 volumio volumio[1389]: info: [1779802119854] CoreMusicLibrary::Adding element Media Servers May 26 13:28:39 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 13:28:39 volumio volumio[1389]: info: UPNP Browser: Client initialized successfully May 26 13:28:40 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:28:40 volumio sudo[1776]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 13:28:40 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 13:28:40 volumio sudo[1779]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 13:28:40 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root May 26 13:28:40 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:40 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 13:28:40 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:28:40 volumio kernel: May 26 13:28:40 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected May 26 13:28:40 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:40 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 26 13:28:40 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 13:28:40 volumio kernel: usb 1-1: USB disconnect, device number 8 May 26 13:28:40 volumio kernel: usb 1-1.1: USB disconnect, device number 9 May 26 13:28:40 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet May 26 13:28:40 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:40 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:40 volumio avahi-daemon[702]: Interface eth0.IPv4 no longer relevant for mDNS. May 26 13:28:40 volumio avahi-daemon[702]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.140. May 26 13:28:40 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 13:28:40 volumio dhcpcd[779]: eth0: carrier lost May 26 13:28:40 volumio avahi-daemon[702]: Withdrawing address record for 192.168.1.140 on eth0. May 26 13:28:40 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 13:28:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 13:28:40 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 13:28:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 13:28:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 13:28:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 13:28:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 13:28:40 volumio kernel: Indeed it is in host mode hprt0 = 00021501 May 26 13:28:40 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:40 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 13:28:40 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 13:28:40 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 13:28:40 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 13:28:40 volumio dhcpcd[779]: eth0: deleting route to 192.168.0.0/23 May 26 13:28:40 volumio dhcpcd[779]: eth0: deleting default route via 192.168.1.1 May 26 13:28:40 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 13:28:40 volumio welcome[1820]: Resolved ip:[0] May 26 13:28:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 13:28:41 volumio kernel: usb 1-1: new high-speed USB device number 10 using dwc_otg May 26 13:28:41 volumio kernel: Indeed it is in host mode hprt0 = 00001101 May 26 13:28:41 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:41 volumio ifplugd(eth0)[999]: Link beat lost. May 26 13:28:41 volumio volumio[1389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 13:28:41 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 May 26 13:28:41 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:41 volumio kernel: hub 1-1:1.0: USB hub found May 26 13:28:41 volumio kernel: hub 1-1:1.0: 5 ports detected May 26 13:28:41 volumio volumio-remote-updater[718]: [2026-05-26 13:28:41] [connect] Successful connection May 26 13:28:41 volumio dhcpcd[779]: eth0: removing interface May 26 13:28:41 volumio systemd[1]: Stopping ifup@eth0.service - ifup for eth0... May 26 13:28:41 volumio volumio[1389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 13:28:41 volumio kernel: usb 1-1.1: new high-speed USB device number 11 using dwc_otg May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 13:28:41 volumio volumio[1389]: info: [1779802121635] CoreMusicLibrary::Adding element Last_100 May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 13:28:41 volumio sudo[1815]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 13:28:41 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 May 26 13:28:41 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:41 volumio kernel: smsc95xx v2.0.0 May 26 13:28:41 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 13:28:41 volumio ifdown[1877]: dhcpcd not running May 26 13:28:41 volumio dhcpcd[1877]: dhcpcd not running May 26 13:28:41 volumio sudo[1879]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 13:28:41 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root May 26 13:28:41 volumio ifdown[1881]: Cannot find device "eth0" May 26 13:28:41 volumio kernel: SMSC LAN8700 usb-001:011:01: attached PHY driver (mii_bus:phy_addr=usb-001:011:01, irq=199) May 26 13:28:41 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:ba:7d:a2 May 26 13:28:41 volumio (udev-worker)[1796]: Network interface NamePolicy= disabled on kernel command line. May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 13:28:41 volumio volumio[1389]: info: [1779802121885] CoreMusicLibrary::Adding element Webradio May 26 13:28:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 13:28:41 volumio systemd[1]: ifup@eth0.service: Deactivated successfully. May 26 13:28:41 volumio systemd[1]: Stopped ifup@eth0.service - ifup for eth0. May 26 13:28:42 volumio volumio5-onboarding[1784]: time=2026-05-26T13:28:42.040Z level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 13:28:42 volumio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... May 26 13:28:42 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:42 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:42 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 13:28:42 volumio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - SMSC9512/9514 Fast Ethernet Adapter. May 26 13:28:42 volumio dhcpcd[779]: eth0: waiting for carrier May 26 13:28:42 volumio systemd[1]: Started ifup@eth0.service - ifup for eth0. May 26 13:28:42 volumio volumio[1389]: info: Initializing BBC Radios May 26 13:28:42 volumio sh[1933]: sending commands to dhcpcd process May 26 13:28:42 volumio dhcpcd[779]: ps_ctl_dispatch: cannot handle another client May 26 13:28:42 volumio dhcpcd[779]: control_free: No such file or directory May 26 13:28:43 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 26 13:28:43 volumio dhcpcd[779]: eth0: carrier acquired May 26 13:28:43 volumio dhcpcd[779]: eth0: IAID eb:ba:7d:a2 May 26 13:28:43 volumio dhcpcd[779]: eth0: adding address fe80::7c40:c773:d91e:f94c May 26 13:28:43 volumio dhcpcd[779]: ipv6_addaddr1: Permission denied May 26 13:28:43 volumio sh[1966]: eth0=eth0 May 26 13:28:43 volumio ifplugd(eth0)[999]: Link beat detected. May 26 13:28:43 volumio dhcpcd[779]: eth0: soliciting a DHCP lease May 26 13:28:43 volumio dhcpcd[779]: eth0: soliciting an IPv6 router May 26 13:28:43 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 13:28:43 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 13:28:43 volumio volumio[1389]: info: Volumio Calling Home May 26 13:28:43 volumio kernel: May 26 13:28:43 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected May 26 13:28:44 volumio dhcpcd[779]: eth0: carrier lost May 26 13:28:44 volumio kernel: usb 1-1: USB disconnect, device number 10 May 26 13:28:44 volumio kernel: usb 1-1.1: USB disconnect, device number 11 May 26 13:28:44 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet May 26 13:28:44 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:44 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:44 volumio kernel: Indeed it is in host mode hprt0 = 00021501 May 26 13:28:44 volumio kernel: usb 1-1: new high-speed USB device number 12 using dwc_otg May 26 13:28:44 volumio kernel: Indeed it is in host mode hprt0 = 00001101 May 26 13:28:44 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 May 26 13:28:44 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:44 volumio kernel: hub 1-1:1.0: USB hub found May 26 13:28:44 volumio kernel: hub 1-1:1.0: 5 ports detected May 26 13:28:44 volumio dhcpcd[779]: eth0: removing interface May 26 13:28:44 volumio systemd[1]: Stopping ifup@eth0.service - ifup for eth0... May 26 13:28:45 volumio kernel: usb 1-1.1: new high-speed USB device number 13 using dwc_otg May 26 13:28:45 volumio ifplugd(eth0)[999]: Link beat lost. May 26 13:28:45 volumio ifdown[2029]: dhcpcd not running May 26 13:28:45 volumio dhcpcd[2029]: dhcpcd not running May 26 13:28:45 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 May 26 13:28:45 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:45 volumio kernel: smsc95xx v2.0.0 May 26 13:28:45 volumio ifdown[2031]: Cannot find device "eth0" May 26 13:28:45 volumio kernel: SMSC LAN8700 usb-001:013:01: attached PHY driver (mii_bus:phy_addr=usb-001:013:01, irq=199) May 26 13:28:45 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:ba:7d:a2 May 26 13:28:45 volumio (udev-worker)[1797]: Network interface NamePolicy= disabled on kernel command line. May 26 13:28:45 volumio systemd[1]: ifup@eth0.service: Deactivated successfully. May 26 13:28:45 volumio systemd[1]: Stopped ifup@eth0.service - ifup for eth0. May 26 13:28:45 volumio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... May 26 13:28:45 volumio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - SMSC9512/9514 Fast Ethernet Adapter. May 26 13:28:45 volumio systemd[1]: Started ifup@eth0.service - ifup for eth0. May 26 13:28:45 volumio dhcpcd[779]: eth0: waiting for carrier May 26 13:28:45 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:45 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:46 volumio sh[2078]: sending commands to dhcpcd process May 26 13:28:46 volumio dhcpcd[779]: ps_ctl_dispatch: cannot handle another client May 26 13:28:46 volumio dhcpcd[779]: control_free: No such file or directory May 26 13:28:46 volumio sh[2105]: eth0=eth0 May 26 13:28:46 volumio dhcpcd[779]: eth0: carrier acquired May 26 13:28:46 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 26 13:28:46 volumio dhcpcd[779]: eth0: IAID eb:ba:7d:a2 May 26 13:28:46 volumio dhcpcd[779]: eth0: adding address fe80::7c40:c773:d91e:f94c May 26 13:28:46 volumio dhcpcd[779]: ipv6_addaddr1: Permission denied May 26 13:28:47 volumio ifplugd(eth0)[999]: Link beat detected. May 26 13:28:47 volumio dhcpcd[779]: eth0: soliciting an IPv6 router May 26 13:28:48 volumio ntpd[981]: IO: Deleting interface #4 eth0, 192.168.1.140#123, interface stats: received=30, sent=33, dropped=5, active_time=28 secs May 26 13:28:48 volumio ntpd[981]: PROTO: 45.76.211.39 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 138.3.209.153 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 157.7.213.62 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 103.131.151.20 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 208.88.66.8 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 167.179.119.205 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 160.16.113.133 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 85.121.50.82 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 129.250.35.251 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 108.160.132.224 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 46.250.253.227 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 142.91.108.61 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 163.44.119.85 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 109.61.109.140 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 162.159.200.123 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 85.121.122.62 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 45.77.20.103 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio ntpd[981]: PROTO: 43.229.16.158 unlink local addr 192.168.1.140 -> May 26 13:28:48 volumio dhcpcd[779]: eth0: soliciting a DHCP lease May 26 13:28:48 volumio dhcpcd[779]: eth0: offered 192.168.1.140 from 192.168.1.1 May 26 13:28:48 volumio dhcpcd[779]: eth0: probing address 192.168.1.140/23 May 26 13:28:52 volumio volumio5-onboarding[1784]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:39700->127.0.0.1:3000: i/o timeout May 26 13:28:52 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 26 13:28:52 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 26 13:28:52 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 26 13:28:52 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:28:52 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:28:52 volumio volumio5-onboarding[2123]: time=2026-05-26T13:28:52.373Z level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 13:28:53 volumio dhcpcd[779]: eth0: leased 192.168.1.140 for 86400 seconds May 26 13:28:53 volumio dhcpcd[779]: eth0: adding route to 192.168.0.0/23 May 26 13:28:53 volumio avahi-daemon[702]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.140. May 26 13:28:53 volumio avahi-daemon[702]: New relevant interface eth0.IPv4 for mDNS. May 26 13:28:53 volumio avahi-daemon[702]: Registering new address record for 192.168.1.140 on eth0.IPv4. May 26 13:28:53 volumio dhcpcd[779]: eth0: adding default route via 192.168.1.1 May 26 13:28:53 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:53 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 13:28:53 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 13:28:53 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 13:28:53 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 13:28:53 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 13:28:53 volumio welcome[2142]: Resolved ip:[1] 192.168.1.140 May 26 13:28:53 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 13:28:53 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:54 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:54 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:54 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 May 26 13:28:54 volumio avahi-daemon[702]: Interface eth0.IPv4 no longer relevant for mDNS. May 26 13:28:54 volumio avahi-daemon[702]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.140. May 26 13:28:54 volumio dhcpcd[779]: eth0: carrier lost May 26 13:28:54 volumio kernel: usb 1-1: USB disconnect, device number 12 May 26 13:28:54 volumio kernel: usb 1-1.1: USB disconnect, device number 13 May 26 13:28:54 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet May 26 13:28:54 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:54 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:54 volumio avahi-daemon[702]: Withdrawing address record for 192.168.1.140 on eth0. May 26 13:28:54 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:54 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 13:28:54 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 13:28:54 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 13:28:54 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 13:28:54 volumio dhcpcd[779]: eth0: deleting route to 192.168.0.0/23 May 26 13:28:54 volumio dhcpcd[779]: eth0: deleting default route via 192.168.1.1 May 26 13:28:54 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 13:28:54 volumio kernel: Indeed it is in host mode hprt0 = 00021501 May 26 13:28:54 volumio welcome[2178]: Resolved ip:[0] May 26 13:28:54 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 13:28:54 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:28:54 volumio kernel: usb 1-1: new high-speed USB device number 14 using dwc_otg May 26 13:28:54 volumio kernel: Indeed it is in host mode hprt0 = 00001101 May 26 13:28:54 volumio ifplugd(eth0)[999]: Link beat lost. May 26 13:28:55 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 May 26 13:28:55 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:55 volumio kernel: hub 1-1:1.0: USB hub found May 26 13:28:55 volumio kernel: hub 1-1:1.0: 5 ports detected May 26 13:28:55 volumio dhcpcd[779]: eth0: removing interface May 26 13:28:55 volumio systemd[1]: Stopping ifup@eth0.service - ifup for eth0... May 26 13:28:55 volumio kernel: usb 1-1.1: new high-speed USB device number 15 using dwc_otg May 26 13:28:55 volumio ifdown[2229]: dhcpcd not running May 26 13:28:55 volumio dhcpcd[2229]: dhcpcd not running May 26 13:28:55 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 May 26 13:28:55 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 26 13:28:55 volumio kernel: smsc95xx v2.0.0 May 26 13:28:55 volumio ifdown[2231]: Cannot find device "eth0" May 26 13:28:55 volumio kernel: SMSC LAN8700 usb-001:015:01: attached PHY driver (mii_bus:phy_addr=usb-001:015:01, irq=199) May 26 13:28:55 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:ba:7d:a2 May 26 13:28:55 volumio (udev-worker)[2162]: Network interface NamePolicy= disabled on kernel command line. May 26 13:28:55 volumio systemd[1]: ifup@eth0.service: Deactivated successfully. May 26 13:28:55 volumio systemd[1]: Stopped ifup@eth0.service - ifup for eth0. May 26 13:28:55 volumio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... May 26 13:28:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 26 13:28:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 26 13:28:56 volumio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - SMSC9512/9514 Fast Ethernet Adapter. May 26 13:28:56 volumio systemd[1]: Started ifup@eth0.service - ifup for eth0. May 26 13:28:56 volumio dhcpcd[779]: eth0: waiting for carrier May 26 13:28:56 volumio sh[2281]: sending commands to dhcpcd process May 26 13:28:56 volumio dhcpcd[779]: ps_ctl_dispatch: cannot handle another client May 26 13:28:56 volumio dhcpcd[779]: control_free: No such file or directory May 26 13:28:56 volumio volumio-remote-updater[718]: [2026-05-26 13:28:56] [connect] Successful connection May 26 13:28:56 volumio mpd[1883]: 2026-05-26T13:28:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 13:28:56 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 26 13:28:56 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root May 26 13:28:56 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root May 26 13:28:57 volumio dhcpcd[779]: eth0: carrier acquired May 26 13:28:57 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 26 13:28:57 volumio dhcpcd[779]: eth0: IAID eb:ba:7d:a2 May 26 13:28:57 volumio dhcpcd[779]: eth0: adding address fe80::7c40:c773:d91e:f94c May 26 13:28:57 volumio dhcpcd[779]: ipv6_addaddr1: Permission denied May 26 13:28:57 volumio sh[2311]: eth0=eth0 May 26 13:28:57 volumio ifplugd(eth0)[999]: Link beat detected. May 26 13:28:57 volumio dhcpcd[779]: eth0: soliciting an IPv6 router May 26 13:28:57 volumio volumio[1389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 13:28:57 volumio volumio[1389]: Error: addMembership ENODEV May 26 13:28:57 volumio volumio[1389]: at Socket.addMembership (node:dgram:860:11) May 26 13:28:57 volumio volumio[1389]: at addMembership (/volumio/node_modules/node-ssdp/lib/index.js:264:16) { May 26 13:28:57 volumio volumio[1389]: errno: -19, May 26 13:28:57 volumio volumio[1389]: code: 'ENODEV', May 26 13:28:57 volumio volumio[1389]: syscall: 'addMembership' May 26 13:28:57 volumio volumio[1389]: } May 26 13:28:57 volumio volumio[1389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 13:28:58 volumio dhcpcd[779]: eth0: rebinding lease of 192.168.1.140 May 26 13:28:58 volumio dhcpcd[779]: eth0: probing address 192.168.1.140/23 May 26 13:29:02 volumio volumio5-onboarding[2123]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:54634->127.0.0.1:3000: i/o timeout May 26 13:29:02 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 26 13:29:02 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 26 13:29:02 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. May 26 13:29:02 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:29:02 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:29:03 volumio volumio5-onboarding[2340]: time=2026-05-26T13:29:03.017Z level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 13:29:03 volumio volumio[1732]: Starting albumart workers May 26 13:29:03 volumio dhcpcd[779]: eth0: leased 192.168.1.140 for 86400 seconds May 26 13:29:03 volumio avahi-daemon[702]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.140. May 26 13:29:03 volumio avahi-daemon[702]: New relevant interface eth0.IPv4 for mDNS. May 26 13:29:03 volumio avahi-daemon[702]: Registering new address record for 192.168.1.140 on eth0.IPv4. May 26 13:29:03 volumio dhcpcd[779]: eth0: adding route to 192.168.0.0/23 May 26 13:29:03 volumio dhcpcd[779]: eth0: adding default route via 192.168.1.1 May 26 13:29:03 volumio volumio[1730]: Starting albumart workers May 26 13:29:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:29:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 13:29:03 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 13:29:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 13:29:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 13:29:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 13:29:03 volumio welcome[2363]: Resolved ip:[1] 192.168.1.140 May 26 13:29:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 13:29:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 13:29:04 volumio volumio[1734]: Starting albumart workers May 26 13:29:04 volumio ntpd[981]: IO: Listen normally on 5 eth0 192.168.1.140:123 May 26 13:29:04 volumio ntpd[981]: IO: new interface(s) found: waking up resolver May 26 13:29:11 volumio volumio-remote-updater[718]: [2026-05-26 13:29:11] [connect] Successful connection May 26 13:29:12 volumio sudo[2391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 13:28' May 26 13:29:12 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 13:29:13 volumio volumio5-onboarding[2340]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41302->127.0.0.1:3000: i/o timeout May 26 13:29:13 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 26 13:29:13 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 26 13:29:13 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. May 26 13:29:13 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:29:13 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 13:29:13 volumio volumio5-onboarding[2395]: time=2026-05-26T13:29:13.490Z level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"