Aug 26 18:32:06 obbaudio ntpd[1091]: CLOCK: time stepped by 503199.073928 Aug 26 18:32:06 obbaudio ntpd[1091]: CLOCK: time changed from 2026-08-20 to 2026-08-26 Aug 26 18:32:06 obbaudio ntpd[1091]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 26 18:32:06 obbaudio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 26 18:32:06 obbaudio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 26 18:32:06 obbaudio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 26 18:32:06 obbaudio systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 26 18:32:06 obbaudio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 26 18:32:06 obbaudio volumio[1343]: info: MYVOLUMIO Environment detected Aug 26 18:32:06 obbaudio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 26 18:32:06 obbaudio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 26 18:32:06 obbaudio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 26 18:32:07 obbaudio volumio[1343]: info: Plugin folders cleanup Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning into folder /volumio/app/plugins/ Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category audio_interface Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category miscellanea Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category music_service Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category plugins.json Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category system_controller Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category user_interface Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning into folder /data/plugins/ Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category music_service Aug 26 18:32:07 obbaudio volumio[1343]: info: Scanning category user_interface Aug 26 18:32:07 obbaudio volumio[1343]: info: Plugin folders cleanup completed Aug 26 18:32:07 obbaudio volumio[1343]: info: ------------------------------------------- Aug 26 18:32:07 obbaudio volumio[1343]: info: ----- Core plugins startup ---- Aug 26 18:32:07 obbaudio volumio[1343]: info: ------------------------------------------- Aug 26 18:32:07 obbaudio volumio[1343]: info: Loading plugins from folder /volumio/app/plugins/ Aug 26 18:32:07 obbaudio volumio[1343]: info: Adding plugin upnp to MyMusic Plugins Aug 26 18:32:07 obbaudio volumio[1343]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 26 18:32:07 obbaudio volumio[1343]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 26 18:32:07 obbaudio volumio[1343]: info: Loading plugins from folder /data/plugins/ Aug 26 18:32:07 obbaudio volumio[1343]: info: Loading plugin "system"... Aug 26 18:32:07 obbaudio volumio[1343]: info: Loading plugin "appearance"... Aug 26 18:32:08 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 26 18:32:08 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:08 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:08 obbaudio upmpdcli[1504]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:32:08 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:32:08 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:32:09 obbaudio volumio-remote-updater[814]: [2026-08-26 18:32:09] [connect] Successful connection Aug 26 18:32:09 obbaudio volumio[1343]: info: Loading plugin "network"... Aug 26 18:32:09 obbaudio volumio[1343]: info: Refreshing Cached IP Addresses Aug 26 18:32:10 obbaudio volumio[1343]: info: Loading plugin "services"... Aug 26 18:32:10 obbaudio volumio[1343]: info: Loading plugin "volumio5onboarding"... Aug 26 18:32:10 obbaudio sudo[1507]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 18:32:10 obbaudio sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:10 obbaudio sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 18:32:10 obbaudio sudo[1507]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:10 obbaudio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:10 obbaudio volumio[1343]: info: Loading plugin "alsa_controller"... Aug 26 18:32:10 obbaudio sudo[1509]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:10 obbaudio sudo[1520]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 26 18:32:10 obbaudio sudo[1520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:10 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 18:32:10 obbaudio volumio[1343]: info: Loading plugin "wizard"... Aug 26 18:32:10 obbaudio volumio[1343]: info: Loading plugin "networkfs"... Aug 26 18:32:10 obbaudio volumio[1343]: info: Starting Udev Watcher for removable devices Aug 26 18:32:10 obbaudio volumio[1343]: info: Ignoring mount for partition: boot Aug 26 18:32:10 obbaudio volumio[1343]: info: Ignoring mount for partition: volumio Aug 26 18:32:10 obbaudio volumio[1343]: info: Ignoring mount for partition: volumio_data Aug 26 18:32:10 obbaudio volumio[1343]: info: Mounting Device 2TBVOLUMIO Aug 26 18:32:10 obbaudio sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/2TBVOLUMIO -o noatime Aug 26 18:32:10 obbaudio sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:10 obbaudio kernel: exFAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 26 18:32:11 obbaudio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 26 18:32:11 obbaudio sudo[1547]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:11 obbaudio volumio[1343]: info: Mounting Device 512VOLUMIO Aug 26 18:32:11 obbaudio sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/512VOLUMIO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 26 18:32:11 obbaudio sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:12 obbaudio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 26 18:32:12 obbaudio sudo[1560]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:12 obbaudio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 26 18:32:12 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 18:32:12 obbaudio volumio[1343]: info: Loading plugin "volumio_command_line_client"... Aug 26 18:32:12 obbaudio volumio[1343]: info: Plugin upnp is not enabled Aug 26 18:32:12 obbaudio volumio[1343]: info: Loading plugin "my_music"... Aug 26 18:32:12 obbaudio volumio[1343]: info: Loading plugin "mpd"... Aug 26 18:32:13 obbaudio volumio[1343]: info: Plugin upnp_browser is not enabled Aug 26 18:32:13 obbaudio volumio[1343]: info: Loading plugin "alarm-clock"... Aug 26 18:32:13 obbaudio volumio[1343]: info: Plugin airplay_emulation is not enabled Aug 26 18:32:13 obbaudio volumio[1343]: info: Loading plugin "last_100"... Aug 26 18:32:13 obbaudio volumio[1343]: info: Loading plugin "webradio"... Aug 26 18:32:14 obbaudio sudo[1520]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:15 obbaudio volumio[1343]: info: Loading plugin "i2s_dacs"... Aug 26 18:32:15 obbaudio volumio[1343]: info: Loading plugin "volumiodiscovery"... Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** For more information see Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 18:32:16 obbaudio volumio[1343]: *** WARNING *** For more information see Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** For more information see Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 18:32:16 obbaudio node[1343]: *** WARNING *** For more information see Aug 26 18:32:16 obbaudio volumio[1343]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 26 18:32:16 obbaudio volumio[1343]: info: Discovery: Started advertising with name: ObbAudio Aug 26 18:32:16 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 18:32:16 obbaudio volumio[1343]: info: Plugin ytmusic is not enabled Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "outputs"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "albumart"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Plugin example_plugin is not enabled Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "inputs"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "updater_comm"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Plugin mpdemulation is not enabled Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "rest_api"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading plugin "websocket"... Aug 26 18:32:16 obbaudio volumio[1343]: info: Starting Socket.io Server version 1.7.4 Aug 26 18:32:16 obbaudio volumio[1343]: info: Plugin touch_display is not enabled Aug 26 18:32:16 obbaudio volumio[1343]: info: Loading i18n strings for locale th Aug 26 18:32:17 obbaudio volumio[1343]: Updating browse sources language Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::initPlayerControls Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 18:32:17 obbaudio volumio[1343]: Express server listening on port 3000 Aug 26 18:32:17 obbaudio volumio[1343]: [Metrics] WebUI: 14s 180.26ms Aug 26 18:32:18 obbaudio volumio[1343]: info: CoreStateMachine::resetVolumioState Aug 26 18:32:18 obbaudio volumio[1343]: info: CoreStateMachine::getcurrentVolume Aug 26 18:32:18 obbaudio volumio[1343]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 18:32:18 obbaudio volumio[1343]: info: Volumio Network Manager: Network status updated: 2 Aug 26 18:32:18 obbaudio volumio[1566]: Forking 3 albumart workers Aug 26 18:32:19 obbaudio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 26 18:32:19 obbaudio volumio[1343]: MPD error: Connection refused Aug 26 18:32:19 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:19 obbaudio volumio[1343]: info: Reloading queue from file Aug 26 18:32:19 obbaudio volumio[1343]: info: VolumeController:: Volume=60 Mute =false Aug 26 18:32:19 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:19 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:19 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreStateMachine::updateTrackBlock Aug 26 18:32:20 obbaudio volumio[1343]: info: CorePlayQueue::getTrackBlock Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreStateMachine::setRepeat true single undefined Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:20 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreStateMachine::setRandom false Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:20 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 18:32:20 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:20 obbaudio volumio[1343]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 26 18:32:20 obbaudio volumio[1343]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Aug 26 18:32:20 obbaudio volumio[1343]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 26 18:32:20 obbaudio volumio[1343]: info: Setting Device type: Raspberry PI Aug 26 18:32:20 obbaudio volumio[1343]: info: Completed loading Core Plugins Aug 26 18:32:20 obbaudio volumio[1343]: info: Preparing to generate the ALSA configuration file Aug 26 18:32:21 obbaudio volumio[1343]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 26 18:32:21 obbaudio volumio[1343]: info: Discovery: Restarting Browsing Aug 26 18:32:21 obbaudio volumio[1343]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 26 18:32:21 obbaudio volumio[1343]: info: Discovery: Restarting Browsing Aug 26 18:32:21 obbaudio volumio[1343]: info: Discovery: Restart already pending, ignoring duplicate call Aug 26 18:32:21 obbaudio volumio[1343]: info: VolumeController:: Volume=60 Mute =false Aug 26 18:32:21 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:21 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:21 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:21 obbaudio volumio[1343]: info: Asound.conf file unchanged, so no further update is needed Aug 26 18:32:21 obbaudio volumio[1343]: info: Output device has changed, restarting MPD Aug 26 18:32:21 obbaudio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 26 18:32:21 obbaudio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 26 18:32:21 obbaudio systemd[1]: setdatetime-helper.service: Consumed 1.242s CPU time. Aug 26 18:32:21 obbaudio volumio[1343]: info: ___________ START PLUGINS ___________ Aug 26 18:32:21 obbaudio sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 18:32:21 obbaudio volumio[1343]: info: ControllerMpd::onStart: Initializing MPD Aug 26 18:32:21 obbaudio volumio[1343]: info: Creating MPD Configuration file Aug 26 18:32:21 obbaudio sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:21 obbaudio sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 18:32:22 obbaudio sudo[1661]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:22 obbaudio sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:22 obbaudio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 26 18:32:22 obbaudio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:22 obbaudio volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 18:32:22 obbaudio volumio[1343]: info: [1787743942242] CoreMusicLibrary::Adding element Last_100 Aug 26 18:32:22 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 18:32:22 obbaudio volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 18:32:22 obbaudio volumio[1343]: info: [1787743942276] CoreMusicLibrary::Adding element Webradio Aug 26 18:32:22 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 18:32:22 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 18:32:22 obbaudio sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 18:32:22 obbaudio volumio[1343]: info: Initializing BBC Radios Aug 26 18:32:22 obbaudio sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:22 obbaudio sudo[1672]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:22 obbaudio sudo[1675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 18:32:22 obbaudio sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:22 obbaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 18:32:22 obbaudio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 18:32:22 obbaudio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:32:22 obbaudio sudo[1669]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:22 obbaudio kernel: usb usb2-port1: over-current change #8 Aug 26 18:32:22 obbaudio kernel: usb 1-1-port1: over-current change #5 Aug 26 18:32:23 obbaudio kernel: usb usb2-port2: over-current change #8 Aug 26 18:32:23 obbaudio kernel: usb 1-1-port2: over-current change #5 Aug 26 18:32:23 obbaudio sudo[1683]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 18:32:23 obbaudio sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 18:32:23 obbaudio sudo[1693]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 26 18:32:23 obbaudio sudo[1683]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:23 obbaudio kernel: usb usb2-port3: over-current change #8 Aug 26 18:32:23 obbaudio kernel: usb 1-1-port3: over-current change #5 Aug 26 18:32:23 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 26 18:32:23 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:23 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:23 obbaudio systemd[1]: mpd.service: Deactivated successfully. Aug 26 18:32:23 obbaudio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 18:32:23 obbaudio systemd[1]: mpd.socket: Deactivated successfully. Aug 26 18:32:23 obbaudio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 18:32:23 obbaudio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 18:32:23 obbaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 18:32:23 obbaudio kernel: usb usb2-port4: over-current change #8 Aug 26 18:32:23 obbaudio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 18:32:23 obbaudio kernel: usb 1-1.3: USB disconnect, device number 15 Aug 26 18:32:23 obbaudio kernel: usb 1-1.3.3: USB disconnect, device number 16 Aug 26 18:32:23 obbaudio upmpdcli[1699]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:32:23 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:32:23 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:32:23 obbaudio volumio5-onboarding[1684]: time=2026-08-26T18:32:23.606+07:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 26 18:32:23 obbaudio kernel: usb 1-1.3.4: USB disconnect, device number 17 Aug 26 18:32:23 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 18:32:23 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:23 obbaudio volumio[1343]: info: Volumio Calling Home Aug 26 18:32:23 obbaudio sudo[1703]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 18:32:23 obbaudio sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 18:32:23 obbaudio sudo[1722]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 26 18:32:23 obbaudio sudo[1703]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:24 obbaudio kernel: usb 1-1.4: reset full-speed USB device number 8 using xhci_hcd Aug 26 18:32:24 obbaudio kernel: usb 1-1.3: new high-speed USB device number 18 using xhci_hcd Aug 26 18:32:24 obbaudio kernel: usb 1-1.3: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice=92.26 Aug 26 18:32:24 obbaudio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 26 18:32:24 obbaudio kernel: usb 1-1.3: Product: USB2.0 Hub Aug 26 18:32:24 obbaudio kernel: usb 1-1.3: Manufacturer: GenesysLogic Aug 26 18:32:24 obbaudio kernel: hub 1-1.3:1.0: USB hub found Aug 26 18:32:24 obbaudio kernel: hub 1-1.3:1.0: 4 ports detected Aug 26 18:32:24 obbaudio kernel: usb 1-1-port4: over-current change #4 Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: new high-speed USB device number 19 using xhci_hcd Aug 26 18:32:24 obbaudio volumio-remote-updater[814]: [2026-08-26 18:32:24] [connect] Successful connection Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: New USB device found, idVendor=0bda, idProduct=9210, bcdDevice=20.01 Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: Product: RTL9210B-CG Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: Manufacturer: Realtek Aug 26 18:32:24 obbaudio kernel: usb 1-1.3.3: SerialNumber: 012345679039 Aug 26 18:32:25 obbaudio kernel: usb-storage 1-1.3.3:1.0: USB Mass Storage device detected Aug 26 18:32:25 obbaudio kernel: scsi host2: usb-storage 1-1.3.3:1.0 Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: new high-speed USB device number 20 using xhci_hcd Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: Product: SanDisk 3.2Gen1 Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: Manufacturer: USB Aug 26 18:32:25 obbaudio kernel: usb 1-1.3.4: SerialNumber: 0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f Aug 26 18:32:25 obbaudio kernel: usb-storage 1-1.3.4:1.0: USB Mass Storage device detected Aug 26 18:32:25 obbaudio kernel: scsi host3: usb-storage 1-1.3.4:1.0 Aug 26 18:32:25 obbaudio mtp-probe[1731]: checking bus 1, device 19: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3" Aug 26 18:32:25 obbaudio mtp-probe[1731]: bus: 1, device: 19 was not an MTP device Aug 26 18:32:25 obbaudio mtp-probe[1732]: checking bus 1, device 20: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4" Aug 26 18:32:25 obbaudio mtp-probe[1732]: bus: 1, device: 20 was not an MTP device Aug 26 18:32:25 obbaudio mtp-probe[1733]: checking bus 1, device 19: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3" Aug 26 18:32:25 obbaudio mtp-probe[1733]: bus: 1, device: 19 was not an MTP device Aug 26 18:32:25 obbaudio mtp-probe[1734]: checking bus 1, device 20: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4" Aug 26 18:32:25 obbaudio mtp-probe[1734]: bus: 1, device: 20 was not an MTP device Aug 26 18:32:26 obbaudio kernel: scsi 2:0:0:0: Direct-Access Realtek RTL9210B-CG 1.00 PQ: 0 ANSI: 6 Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: [sdc] Write Protect is off Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: [sdc] Mode Sense: 37 00 00 08 Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 26 18:32:26 obbaudio kernel: sdc: sdc1 Aug 26 18:32:26 obbaudio kernel: sd 2:0:0:0: [sdc] Attached SCSI disk Aug 26 18:32:26 obbaudio kernel: scsi 3:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: Attached scsi generic sg1 type 0 Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: [sdd] 965246976 512-byte logical blocks: (494 GB/460 GiB) Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: [sdd] Write Protect is off Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: [sdd] Mode Sense: 43 00 00 00 Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: [sdd] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 26 18:32:26 obbaudio kernel: sdd: sdd1 Aug 26 18:32:26 obbaudio kernel: sd 3:0:0:0: [sdd] Attached SCSI removable disk Aug 26 18:32:28 obbaudio kernel: usb usb2-port1: over-current change #9 Aug 26 18:32:28 obbaudio kernel: usb 1-1-port1: over-current change #6 Aug 26 18:32:28 obbaudio kernel: usb usb2-port2: over-current change #9 Aug 26 18:32:28 obbaudio kernel: usb 1-1-port2: over-current change #6 Aug 26 18:32:28 obbaudio kernel: usb usb2-port3: over-current change #9 Aug 26 18:32:28 obbaudio kernel: usb 1-1-port3: over-current change #6 Aug 26 18:32:28 obbaudio kernel: usb usb2-port4: over-current change #9 Aug 26 18:32:28 obbaudio kernel: usb 1-1.3: USB disconnect, device number 18 Aug 26 18:32:28 obbaudio kernel: usb 1-1.3.3: USB disconnect, device number 19 Aug 26 18:32:29 obbaudio kernel: usb 1-1.3.4: USB disconnect, device number 20 Aug 26 18:32:29 obbaudio kernel: usb 1-1.4: reset full-speed USB device number 8 using xhci_hcd Aug 26 18:32:29 obbaudio kernel: usb 1-1.3: new high-speed USB device number 21 using xhci_hcd Aug 26 18:32:29 obbaudio kernel: usb 1-1.3: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice=92.26 Aug 26 18:32:29 obbaudio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 26 18:32:29 obbaudio kernel: usb 1-1.3: Product: USB2.0 Hub Aug 26 18:32:29 obbaudio kernel: usb 1-1.3: Manufacturer: GenesysLogic Aug 26 18:32:29 obbaudio kernel: hub 1-1.3:1.0: USB hub found Aug 26 18:32:29 obbaudio kernel: hub 1-1.3:1.0: 4 ports detected Aug 26 18:32:29 obbaudio kernel: usb 1-1-port4: over-current change #5 Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: new high-speed USB device number 22 using xhci_hcd Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: New USB device found, idVendor=0bda, idProduct=9210, bcdDevice=20.01 Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: Product: RTL9210B-CG Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: Manufacturer: Realtek Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.3: SerialNumber: 012345679039 Aug 26 18:32:30 obbaudio kernel: usb-storage 1-1.3.3:1.0: USB Mass Storage device detected Aug 26 18:32:30 obbaudio kernel: scsi host2: usb-storage 1-1.3.3:1.0 Aug 26 18:32:30 obbaudio kernel: usb 1-1.3.4: new high-speed USB device number 23 using xhci_hcd Aug 26 18:32:31 obbaudio kernel: usb 1-1.3.4: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Aug 26 18:32:31 obbaudio kernel: usb 1-1.3.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 26 18:32:31 obbaudio kernel: usb 1-1.3.4: Product: SanDisk 3.2Gen1 Aug 26 18:32:31 obbaudio kernel: usb 1-1.3.4: Manufacturer: USB Aug 26 18:32:31 obbaudio kernel: usb 1-1.3.4: SerialNumber: 0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f Aug 26 18:32:31 obbaudio kernel: usb-storage 1-1.3.4:1.0: USB Mass Storage device detected Aug 26 18:32:31 obbaudio kernel: scsi host3: usb-storage 1-1.3.4:1.0 Aug 26 18:32:31 obbaudio mtp-probe[1744]: checking bus 1, device 22: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3" Aug 26 18:32:31 obbaudio mtp-probe[1744]: bus: 1, device: 22 was not an MTP device Aug 26 18:32:31 obbaudio mtp-probe[1745]: checking bus 1, device 23: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4" Aug 26 18:32:31 obbaudio mtp-probe[1745]: bus: 1, device: 23 was not an MTP device Aug 26 18:32:31 obbaudio mtp-probe[1747]: checking bus 1, device 22: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3" Aug 26 18:32:31 obbaudio mtp-probe[1747]: bus: 1, device: 22 was not an MTP device Aug 26 18:32:31 obbaudio mtp-probe[1748]: checking bus 1, device 23: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4" Aug 26 18:32:31 obbaudio mtp-probe[1748]: bus: 1, device: 23 was not an MTP device Aug 26 18:32:31 obbaudio kernel: scsi 2:0:0:0: Direct-Access Realtek RTL9210B-CG 1.00 PQ: 0 ANSI: 6 Aug 26 18:32:31 obbaudio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 Aug 26 18:32:31 obbaudio kernel: sd 2:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Aug 26 18:32:31 obbaudio kernel: sd 2:0:0:0: [sdc] Write Protect is off Aug 26 18:32:31 obbaudio kernel: sd 2:0:0:0: [sdc] Mode Sense: 37 00 00 08 Aug 26 18:32:31 obbaudio kernel: sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 26 18:32:32 obbaudio kernel: sdc: sdc1 Aug 26 18:32:32 obbaudio kernel: sd 2:0:0:0: [sdc] Attached SCSI disk Aug 26 18:32:32 obbaudio kernel: scsi 3:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: Attached scsi generic sg1 type 0 Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: [sdd] 965246976 512-byte logical blocks: (494 GB/460 GiB) Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: [sdd] Write Protect is off Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: [sdd] Mode Sense: 43 00 00 00 Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: [sdd] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 26 18:32:32 obbaudio volumio[1343]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/2TBVOLUMIO /dev/disk/by-partuuid/b8046931-01 /dev/disk/by-uuid/4E44-C789 /dev/disk/by-id/usb-Realtek_RTL9210B-CG_012345679039-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"32","ID_BUS":"usb","ID_FS_LABEL":"2TBVOLUMIO","ID_FS_LABEL_ENC":"2TBVOLUMIO","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4E44-C789","ID_FS_UUID_ENC":"4E44-C789","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"RTL9210B-CG","ID_MODEL_ENC":"RTL9210B-CG\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9210","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3907027087","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"b8046931-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"b8046931","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Realtek_RTL9210B-CG_012345679039-0:0","ID_SERIAL_SHORT":"012345679039","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"RTL9210B-CG","ID_USB_MODEL_ENC":"RTL9210B-CG\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"9210","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"Realtek_RTL9210B-CG_012345679039-0:0","ID_USB_SERIAL_SHORT":"012345679039","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Realtek","ID_USB_VENDOR_ENC":"Realtek\\x20","ID_USB_VENDOR_ID":"0bda","ID_VENDOR":"Realtek","ID_VENDOR_ENC":"Realtek\\x20","ID_VENDOR_ID":"0bda","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3099","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"47122358"} Aug 26 18:32:32 obbaudio kernel: sdd: sdd1 Aug 26 18:32:32 obbaudio kernel: sd 3:0:0:0: [sdd] Attached SCSI removable disk Aug 26 18:32:32 obbaudio mpd[1723]: 2026-08-26T18:32:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 18:32:32 obbaudio sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Aug 26 18:32:32 obbaudio sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:32 obbaudio systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 18:32:32 obbaudio sudo[1663]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:32 obbaudio sudo[1675]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:32 obbaudio systemd[1]: media-2TBVOLUMIO.mount: Deactivated successfully. Aug 26 18:32:32 obbaudio sudo[1767]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:33 obbaudio volumio[1343]: info: MPD Permissions set Aug 26 18:32:33 obbaudio volumio[1343]: info: MPD Permissions set Aug 26 18:32:33 obbaudio volumio[1343]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0-part1 /dev/disk/by-uuid/D58A-3C52 /dev/disk/by-label/512VOLUMIO /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.4:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"33","ID_BUS":"usb","ID_FS_LABEL":"512VOLUMIO","ID_FS_LABEL_ENC":"512VOLUMIO","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"D58A-3C52","ID_FS_UUID_ENC":"D58A-3C52","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5591","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"64","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"965246912","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_4_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0","ID_SERIAL_SHORT":"0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5591","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0","ID_USB_SERIAL_SHORT":"0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"3115","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"47435939"} Aug 26 18:32:33 obbaudio volumio5-onboarding[1684]: 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:49652->127.0.0.1:3000: i/o timeout Aug 26 18:32:33 obbaudio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:32:33 obbaudio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 26 18:32:33 obbaudio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Aug 26 18:32:33 obbaudio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:33 obbaudio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 26 18:32:33 obbaudio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:32:33 obbaudio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:32:33 obbaudio volumio5-onboarding[1776]: time=2026-08-26T18:32:33.984+07:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 26 18:32:34 obbaudio systemd[1]: media-512VOLUMIO.mount: Deactivated successfully. Aug 26 18:32:34 obbaudio sudo[1774]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:34 obbaudio volumio[1583]: Starting albumart workers Aug 26 18:32:34 obbaudio volumio[1582]: Starting albumart workers Aug 26 18:32:34 obbaudio volumio[1343]: info: Completed starting Core Plugins Aug 26 18:32:34 obbaudio volumio[1343]: info: ------------------------------------------- Aug 26 18:32:34 obbaudio volumio[1343]: info: ----- MyVolumio plugins startup ---- Aug 26 18:32:34 obbaudio volumio[1343]: info: ------------------------------------------- Aug 26 18:32:34 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 18:32:34 obbaudio volumio[1343]: info: Volumio called home Aug 26 18:32:35 obbaudio volumio[1581]: Starting albumart workers Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:36 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:38 obbaudio volumio[1343]: error: MPD error: The expression evaluated to a falsy value: Aug 26 18:32:38 obbaudio volumio[1343]: assert.ok(self.idling) Aug 26 18:32:38 obbaudio volumio[1343]: error: The expression evaluated to a falsy value: Aug 26 18:32:38 obbaudio volumio[1343]: assert.ok(self.idling) Aug 26 18:32:38 obbaudio volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 26 18:32:38 obbaudio volumio[1343]: info: MPD running with PID1723 Aug 26 18:32:38 obbaudio volumio[1343]: ,establishing connection Aug 26 18:32:38 obbaudio volumio[1343]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/2TBVOLUMIO Aug 26 18:32:38 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Aug 26 18:32:38 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:38 obbaudio volumio[1343]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/512VOLUMIO Aug 26 18:32:38 obbaudio volumio[1343]: error: updateQueue error: null Aug 26 18:32:38 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:38 obbaudio upmpdcli[1800]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:32:38 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:32:38 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:32:38 obbaudio volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 26 18:32:39 obbaudio volumio[1343]: info: Mounting Device 2TBVOLUMIO Aug 26 18:32:39 obbaudio sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/2TBVOLUMIO -o noatime Aug 26 18:32:39 obbaudio sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:39 obbaudio volumio-remote-updater[814]: [2026-08-26 18:32:39] [connect] Successful connection Aug 26 18:32:40 obbaudio kernel: exFAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 26 18:32:43 obbaudio sudo[1811]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:43 obbaudio volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 26 18:32:43 obbaudio volumio[1343]: info: Received Get System Info Aug 26 18:32:43 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 18:32:43 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 18:32:43 obbaudio volumio[1343]: info: Discovery: Getting this device information Aug 26 18:32:43 obbaudio volumio[1343]: info: CoreCommandRouter::volumioGetState Aug 26 18:32:43 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:43 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 18:32:44 obbaudio volumio5-onboarding[1776]: time=2026-08-26T18:32:44.054+07:00 level=INFO msg="system info for a6e15201c33257b7aa080d0a43daf69f" deviceName=ObbAudio deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 26 18:32:44 obbaudio volumio5-onboarding[1776]: time=2026-08-26T18:32:44.066+07:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 26 18:32:44 obbaudio volumio[1343]: info: Received Get System Info Aug 26 18:32:44 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 18:32:44 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 18:32:44 obbaudio volumio[1343]: info: Discovery: Getting this device information Aug 26 18:32:44 obbaudio volumio[1343]: info: CoreCommandRouter::volumioGetState Aug 26 18:32:44 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:44 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 18:32:44 obbaudio volumio[1343]: error: updateQueue error: null Aug 26 18:32:44 obbaudio volumio[1343]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 26 18:32:44 obbaudio volumio[1343]: info: Discovery: Restarting Browsing Aug 26 18:32:44 obbaudio volumio[1343]: info: Scanning removed location : "USB/2TBVOLUMIO" Aug 26 18:32:44 obbaudio volumio[1343]: info: Mounting Device 512VOLUMIO Aug 26 18:32:44 obbaudio sudo[1857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdd1 /mnt/USB/512VOLUMIO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 26 18:32:45 obbaudio sudo[1857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:45 obbaudio kernel: FAT-fs (sdd1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 26 18:32:45 obbaudio kernel: FAT-fs (sdd1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 26 18:32:45 obbaudio sudo[1857]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:47 obbaudio volumio[1343]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/2TBVOLUMIO /dev/disk/by-partuuid/b8046931-01 /dev/disk/by-uuid/4E44-C789 /dev/disk/by-id/usb-Realtek_RTL9210B-CG_012345679039-0:0-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"34","ID_BUS":"usb","ID_FS_LABEL":"2TBVOLUMIO","ID_FS_LABEL_ENC":"2TBVOLUMIO","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4E44-C789","ID_FS_UUID_ENC":"4E44-C789","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"RTL9210B-CG","ID_MODEL_ENC":"RTL9210B-CG\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9210","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3907027087","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"b8046931-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"b8046931","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Realtek_RTL9210B-CG_012345679039-0:0","ID_SERIAL_SHORT":"012345679039","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"RTL9210B-CG","ID_USB_MODEL_ENC":"RTL9210B-CG\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"9210","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"Realtek_RTL9210B-CG_012345679039-0:0","ID_USB_SERIAL_SHORT":"012345679039","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Realtek","ID_USB_VENDOR_ENC":"Realtek\\x20","ID_USB_VENDOR_ID":"0bda","ID_VENDOR":"Realtek","ID_VENDOR_ENC":"Realtek\\x20","ID_VENDOR_ID":"0bda","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"3181","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"73257347"} Aug 26 18:32:47 obbaudio sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Aug 26 18:32:47 obbaudio sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:47 obbaudio sudo[1861]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:47 obbaudio volumio[1343]: umount: /media/2TBVOLUMIO: target is busy. Aug 26 18:32:47 obbaudio volumio[1343]: error: Failed to umount 2TBVOLUMIO: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdc1" Aug 26 18:32:47 obbaudio volumio[1343]: umount: /media/2TBVOLUMIO: target is busy. Aug 26 18:32:48 obbaudio volumio[1343]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 26 18:32:48 obbaudio volumio[1343]: info: Discovery: Restarting Browsing Aug 26 18:32:48 obbaudio volumio[1343]: info: Discovery: Restart already pending, ignoring duplicate call Aug 26 18:32:48 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:48 obbaudio volumio[1343]: info: Aug 26 18:32:48 obbaudio volumio[1343]: ---------------------------- MPD announces state update: update Aug 26 18:32:48 obbaudio volumio[1343]: info: ControllerMpd::getState Aug 26 18:32:48 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:48 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:48 obbaudio volumio[1343]: info: Aug 26 18:32:48 obbaudio volumio[1343]: ---------------------------- MPD announces state update: update Aug 26 18:32:48 obbaudio volumio[1343]: info: ControllerMpd::getState Aug 26 18:32:48 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:48 obbaudio volumio[1343]: info: Scanning new location : "USB/2TBVOLUMIO" Aug 26 18:32:48 obbaudio volumio[1343]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4:1.0/host3/target3:0:0/3:0:0:0/block/sdd/sdd1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0-part1 /dev/disk/by-uuid/D58A-3C52 /dev/disk/by-label/512VOLUMIO /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.4:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdd1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4:1.0/host3/target3:0:0/3:0:0:0/block/sdd/sdd1","DEVTYPE":"partition","DISKSEQ":"35","ID_BUS":"usb","ID_FS_LABEL":"512VOLUMIO","ID_FS_LABEL_ENC":"512VOLUMIO","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"D58A-3C52","ID_FS_UUID_ENC":"D58A-3C52","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5591","ID_PART_ENTRY_DISK":"8:48","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"64","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"965246912","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_4_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0","ID_SERIAL_SHORT":"0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5591","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f-0:0","ID_USB_SERIAL_SHORT":"0401df48aaab326ec598a71741aa1ac00941e75abbba894aae23a9c3260ecc4f33e800000000000000000000f4095896ff956718915581078caf341f","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"49","PARTN":"1","SEQNUM":"3198","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"73695855"} Aug 26 18:32:49 obbaudio sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdd1 Aug 26 18:32:49 obbaudio sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:32:49 obbaudio sudo[1868]: pam_unix(sudo:session): session closed for user root Aug 26 18:32:49 obbaudio systemd[1]: media-512VOLUMIO.mount: Deactivated successfully. Aug 26 18:32:52 obbaudio volumio[1343]: info: Scanning new location : "USB/512VOLUMIO" Aug 26 18:32:53 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Aug 26 18:32:53 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:53 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:32:53 obbaudio upmpdcli[1888]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:32:53 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:32:53 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:32:54 obbaudio volumio-remote-updater[814]: [2026-08-26 18:32:54] [connect] Successful connection Aug 26 18:32:56 obbaudio volumio[1343]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/512VOLUMIO Aug 26 18:32:56 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 18:32:56 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:56 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 18:32:56 obbaudio volumio[1343]: error: MyVolumio Plugin failed to start in a timely fashion Aug 26 18:32:56 obbaudio volumio[1343]: [Metrics] CommandRouter: 51s 912.74ms Aug 26 18:32:56 obbaudio volumio[1343]: info: CoreCommandRouter::volumiosetStartupVolume Aug 26 18:32:56 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 18:32:56 obbaudio volumio[1343]: info: VolumeController:: Setting startup Volume 40 Aug 26 18:32:57 obbaudio volumio[1343]: info: VolumeController::SetAlsaVolume40 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::Close All Modals sent Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::Close All Modals sent Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:57 obbaudio volumio[1343]: info: Aug 26 18:32:57 obbaudio volumio[1343]: ---------------------------- MPD announces state update: update Aug 26 18:32:57 obbaudio volumio[1343]: info: ControllerMpd::getState Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::sendMpdCommand status Aug 26 18:32:57 obbaudio volumio[1343]: info: sendMpdCommand status took 8900 milliseconds Aug 26 18:32:57 obbaudio volumio[1343]: info: sendMpdCommand status took 8819 milliseconds Aug 26 18:32:57 obbaudio volumio[1343]: info: sendMpdCommand status took 8819 milliseconds Aug 26 18:32:57 obbaudio volumio[1343]: info: sendMpdCommand status took 8819 milliseconds Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::parseState Aug 26 18:32:57 obbaudio volumio[1343]: info: Command Router : Notfying DB Updatetrue Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::parseState Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::parseState Aug 26 18:32:57 obbaudio volumio[1343]: info: Command Router : Notfying DB Updatetrue Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::Close All Modals sent Aug 26 18:32:57 obbaudio volumio[1343]: verbose: ControllerMpd::parseState Aug 26 18:32:57 obbaudio volumio[1343]: info: ControllerMpd::pushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::servicePushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:57 obbaudio volumio[1343]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 18:32:57 obbaudio volumio[1343]: verbose: CURRENT POSITION 0 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::syncState stateService stop Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::syncState currentStatus stop Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:57 obbaudio volumio[1343]: info: No code Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:57 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:57 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:57 obbaudio volumio[1343]: info: ControllerMpd::pushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreCommandRouter::servicePushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:58 obbaudio volumio[1343]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 18:32:58 obbaudio volumio[1343]: verbose: CURRENT POSITION 0 Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreStateMachine::syncState stateService stop Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreStateMachine::syncState currentStatus stop Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:58 obbaudio volumio[1343]: info: No code Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreStateMachine::pushState Aug 26 18:32:58 obbaudio volumio[1343]: info: CorePlayQueue::getTrack 0 Aug 26 18:32:58 obbaudio volumio[1343]: info: CoreCommandRouter::volumioPushState Aug 26 18:32:58 obbaudio volumio[1343]: info: ------------------------------ 9697ms Aug 26 18:32:58 obbaudio volumio[1343]: info: ------------------------------ 9650ms Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin bluetooth to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin multiroom to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin metavolumio to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin cd_controller to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 26 18:32:58 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 18:33:09 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Aug 26 18:33:09 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:33:09 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:33:09 obbaudio upmpdcli[1914]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:33:09 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:33:09 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:33:09 obbaudio volumio-remote-updater[814]: [2026-08-26 18:33:09] [connect] Successful connection Aug 26 18:33:11 obbaudio volumio[1343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 18:33:11 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 18:33:11 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:33:11 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 18:33:11 obbaudio volumio[1343]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 18:33:11 obbaudio volumio[1343]: info: MyVolumio login type: Token Aug 26 18:33:11 obbaudio volumio[1343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 18:33:11 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 26 18:33:14 obbaudio volumio5-onboarding[1776]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 26 18:33:14 obbaudio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:33:14 obbaudio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 26 18:33:14 obbaudio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Aug 26 18:33:14 obbaudio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:33:14 obbaudio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:33:14 obbaudio volumio5-onboarding[1928]: time=2026-08-26T18:33:14.538+07:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 26 18:33:20 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 26 18:33:20 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 26 18:33:20 obbaudio volumio[1343]: info: Streaming services startup Aug 26 18:33:20 obbaudio volumio[1343]: info: Starting Streaming Daemon Aug 26 18:33:21 obbaudio sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 18:33:21 obbaudio sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:33:21 obbaudio volumio[1343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 26 18:33:21 obbaudio sudo[1938]: pam_unix(sudo:session): session closed for user root Aug 26 18:33:21 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 26 18:33:21 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 18:33:21 obbaudio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 26 18:33:21 obbaudio volumio[1343]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 18:33:22 obbaudio volumio[1343]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 26 18:33:22 obbaudio volumio[1343]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 26 18:33:22 obbaudio volumio[1343]: at new NodeError (node:internal/errors:405:5) Aug 26 18:33:22 obbaudio volumio[1343]: at assert (node:internal/assert:14:11) Aug 26 18:33:22 obbaudio volumio[1343]: at internalConnectMultiple (node:net:1118:3) Aug 26 18:33:22 obbaudio volumio[1343]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 26 18:33:22 obbaudio volumio[1343]: at listOnTimeout (node:internal/timers:575:11) Aug 26 18:33:22 obbaudio volumio[1343]: at process.processTimers (node:internal/timers:514:7) { Aug 26 18:33:22 obbaudio volumio[1343]: code: 'ERR_INTERNAL_ASSERTION' Aug 26 18:33:22 obbaudio volumio[1343]: } Aug 26 18:33:22 obbaudio volumio[1343]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 18:33:24 obbaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Aug 26 18:33:24 obbaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:33:24 obbaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 18:33:24 obbaudio upmpdcli[1956]: Could not open config: /tmp/upmpdcli.conf Aug 26 18:33:24 obbaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:33:24 obbaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 18:33:24 obbaudio volumio5-onboarding[1928]: 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:37942->127.0.0.1:3000: i/o timeout Aug 26 18:33:24 obbaudio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:33:24 obbaudio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 26 18:33:24 obbaudio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Aug 26 18:33:24 obbaudio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:33:24 obbaudio volumio-remote-updater[814]: [2026-08-26 18:33:24] [connect] Successful connection Aug 26 18:33:24 obbaudio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 26 18:33:24 obbaudio volumio5-onboarding[1965]: time=2026-08-26T18:33:24.996+07:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 26 18:33:33 obbaudio sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 18:32' Aug 26 18:33:33 obbaudio sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 18:33:35 obbaudio volumio5-onboarding[1965]: 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:57352->127.0.0.1:3000: i/o timeout Aug 26 18:33:35 obbaudio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:33:35 obbaudio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. 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"