Dec 14 14:18:00 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 14 14:18:00 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 14 14:18:00 volumio systemd[1]: setdatetime-helper.service: Consumed 1.996s CPU time. Dec 14 14:18:00 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 14 14:18:00 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 14 14:18:00 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 14 14:18:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 14 14:18:00 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 14 14:18:00 volumio systemd[1]: Startup finished in 20.213s (kernel) + 45.829s (userspace) = 1min 6.043s. Dec 14 14:18:01 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 14 14:18:01 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 14 14:18:03 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 14 14:18:03 volumio kernel: usb 1-1.2: USB disconnect, device number 14 Dec 14 14:18:05 volumio kernel: usb 1-1-port2: Cannot enable. Maybe the USB cable is bad? Dec 14 14:18:05 volumio kernel: usb 1-1.2: new high-speed USB device number 17 using dwc_otg Dec 14 14:18:05 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:05 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:05 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:05 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:05 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:05 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:05 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:18:05 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:18:05 volumio volumio[1571]: info: ----- Volumio3 ---- Dec 14 14:18:05 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:18:05 volumio volumio[1571]: info: ----- System startup ---- Dec 14 14:18:05 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:18:06 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:18:06 volumio kernel: sda: sda1 Dec 14 14:18:06 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:18:07 volumio nmbd[1389]: [2025/12/14 14:18:07.208724, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 14 14:18:07 volumio nmbd[1389]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.91.101 for name WORKGROUP<1d>. Dec 14 14:18:07 volumio nmbd[1389]: This response was from IP 192.168.91.126, reporting an IP address of 192.168.91.126. Dec 14 14:18:08 volumio volumio[1571]: info: MYVOLUMIO Environment detected Dec 14 14:18:08 volumio volumio-remote-updater[693]: [2025-12-14 14:18:08] [connect] Successful connection Dec 14 14:18:09 volumio volumio[1571]: info: Plugin folders cleanup Dec 14 14:18:09 volumio volumio[1571]: info: Scanning into folder /volumio/app/plugins/ Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category audio_interface Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category miscellanea Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category music_service Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category plugins.json Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category system_controller Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category user_interface Dec 14 14:18:09 volumio volumio[1571]: info: Scanning into folder /data/plugins/ Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category music_service Dec 14 14:18:09 volumio volumio[1571]: info: Scanning category user_interface Dec 14 14:18:09 volumio volumio[1571]: info: Plugin folders cleanup completed Dec 14 14:18:09 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:18:09 volumio volumio[1571]: info: ----- Core plugins startup ---- Dec 14 14:18:09 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:18:09 volumio volumio[1571]: info: Loading plugins from folder /volumio/app/plugins/ Dec 14 14:18:09 volumio volumio[1571]: info: Adding plugin upnp to MyMusic Plugins Dec 14 14:18:09 volumio volumio[1571]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 14 14:18:09 volumio volumio[1571]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 14 14:18:09 volumio volumio[1571]: info: Loading plugins from folder /data/plugins/ Dec 14 14:18:09 volumio volumio[1571]: info: Loading plugin "system"... Dec 14 14:18:09 volumio volumio[1571]: info: Loading plugin "appearance"... Dec 14 14:18:12 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 14 14:18:12 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:12 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:12 volumio upmpdcli[1703]: Could not open config: /tmp/upmpdcli.conf Dec 14 14:18:12 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:18:12 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 14 14:18:13 volumio volumio[1571]: info: Loading plugin "network"... Dec 14 14:18:13 volumio volumio[1571]: info: Refreshing Cached IP Addresses Dec 14 14:18:13 volumio sudo[1705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 14:18:13 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:13 volumio volumio[1571]: info: Loading plugin "services"... Dec 14 14:18:14 volumio volumio[1571]: info: Loading plugin "alsa_controller"... Dec 14 14:18:14 volumio sudo[1707]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 14:18:14 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:14 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:14 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:14 volumio sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 14:18:14 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:14 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:18:14 volumio volumio[1571]: info: Loading plugin "wizard"... Dec 14 14:18:14 volumio volumio[1571]: info: Loading plugin "networkfs"... Dec 14 14:18:14 volumio volumio[1571]: info: Starting Udev Watcher for removable devices Dec 14 14:18:14 volumio sudo[1739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.91.126/public /mnt/NAS/public Dec 14 14:18:14 volumio volumio[1571]: info: Ignoring mount for partition: boot Dec 14 14:18:14 volumio volumio[1571]: info: Ignoring mount for partition: volumio Dec 14 14:18:14 volumio volumio[1571]: info: Ignoring mount for partition: volumio_data Dec 14 14:18:14 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:14 volumio volumio[1571]: info: Mounting Device ESD-USB Dec 14 14:18:14 volumio sudo[1750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ESD-USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 14 14:18:14 volumio sudo[1750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:14 volumio kernel: netfs: FS-Cache loaded Dec 14 14:18:15 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 14 14:18:15 volumio sudo[1750]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:15 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 14 14:18:15 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:18:15 volumio volumio[1571]: info: Loading plugin "volumio_command_line_client"... Dec 14 14:18:15 volumio volumio[1571]: info: Loading plugin "upnp"... Dec 14 14:18:15 volumio volumio[1571]: info: [1765743495186] Starting Upmpd Daemon Dec 14 14:18:15 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:18:15 volumio volumio[1571]: info: Loading plugin "my_music"... Dec 14 14:18:15 volumio volumio[1571]: info: Loading plugin "mpd"... Dec 14 14:18:15 volumio kernel: Key type cifs.spnego registered Dec 14 14:18:15 volumio kernel: Key type cifs.idmap registered Dec 14 14:18:15 volumio kernel: CIFS: Attempting to mount //192.168.91.126/public Dec 14 14:18:16 volumio volumio[1571]: info: Loading plugin "upnp_browser"... Dec 14 14:18:17 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:17 volumio kernel: usb 1-1.2: USB disconnect, device number 17 Dec 14 14:18:18 volumio kernel: usb 1-1.2: new high-speed USB device number 18 using dwc_otg Dec 14 14:18:18 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:18 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:18 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:18 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:18 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:18 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:18 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Dec 14 14:18:18 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:19 volumio kernel: scsi 1:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Dec 14 14:18:19 volumio kernel: sdb: sdb1 Dec 14 14:18:19 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 14 14:18:21 volumio volumio[1571]: info: Starting UPNP Browser Dec 14 14:18:21 volumio volumio[1571]: info: Loading plugin "alarm-clock"... Dec 14 14:18:22 volumio volumio[1571]: info: Plugin airplay_emulation is not enabled Dec 14 14:18:22 volumio volumio[1571]: info: Loading plugin "last_100"... Dec 14 14:18:22 volumio volumio[1571]: info: Loading plugin "webradio"... Dec 14 14:18:22 volumio kernel: usb 1-1.2: USB disconnect, device number 18 Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "i2s_dacs"... Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "volumiodiscovery"... Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** For more information see Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:18:23 volumio volumio[1571]: *** WARNING *** For more information see Dec 14 14:18:23 volumio node[1571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 14:18:23 volumio node[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:18:23 volumio node[1571]: *** WARNING *** For more information see Dec 14 14:18:23 volumio node[1571]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 14:18:23 volumio node[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:18:23 volumio node[1571]: *** WARNING *** For more information see Dec 14 14:18:23 volumio volumio[1571]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 14 14:18:23 volumio volumio[1571]: info: Discovery: Started advertising with name: Volumio Dec 14 14:18:23 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "outputs"... Dec 14 14:18:23 volumio kernel: usb 1-1.2: new high-speed USB device number 19 using dwc_otg Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "albumart"... Dec 14 14:18:23 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:23 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:23 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:23 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:23 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:23 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:23 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Dec 14 14:18:23 volumio volumio[1571]: info: Plugin example_plugin is not enabled Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "inputs"... Dec 14 14:18:23 volumio volumio[1571]: info: Loading plugin "updater_comm"... Dec 14 14:18:23 volumio volumio-remote-updater[693]: [2025-12-14 14:18:23] [connect] Successful connection Dec 14 14:18:24 volumio volumio[1571]: info: Plugin mpdemulation is not enabled Dec 14 14:18:24 volumio volumio[1571]: info: Loading plugin "rest_api"... Dec 14 14:18:24 volumio volumio[1571]: info: Loading plugin "websocket"... Dec 14 14:18:24 volumio volumio[1571]: info: Starting Socket.io Server version 1.7.4 Dec 14 14:18:24 volumio volumio[1571]: info: Plugin 80s80s is not enabled Dec 14 14:18:24 volumio volumio[1571]: info: Loading plugin "minidlna"... Dec 14 14:18:24 volumio kernel: scsi 1:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Dec 14 14:18:24 volumio kernel: sdb: sdb1 Dec 14 14:18:24 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 14 14:18:25 volumio volumio[1571]: info: Applying required configuration parameters for plugin minidlna Dec 14 14:18:25 volumio kernel: usb 1-1.2: USB disconnect, device number 19 Dec 14 14:18:26 volumio volumio[1571]: info: Loading plugin "touch_display"... Dec 14 14:18:27 volumio kernel: usb 1-1-port2: Cannot enable. Maybe the USB cable is bad? Dec 14 14:18:27 volumio volumio[1786]: Forking 3 albumart workers Dec 14 14:18:28 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 14 14:18:28 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:28 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:28 volumio volumio[1571]: info: Applying required configuration parameters for plugin touch_display Dec 14 14:18:28 volumio kernel: usb 1-1-port2: Cannot enable. Maybe the USB cable is bad? Dec 14 14:18:28 volumio kernel: usb 1-1-port2: attempt power cycle Dec 14 14:18:28 volumio upmpdcli[1817]: Could not open config: /tmp/upmpdcli.conf Dec 14 14:18:28 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:18:28 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 14 14:18:28 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2x00usb_vendor_request: Error - Vendor Request 0x07 failed for offset 0x1700 with error -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Dec 14 14:18:28 volumio kernel: usb 1-1.2: new high-speed USB device number 22 using dwc_otg Dec 14 14:18:28 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:28 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:28 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:28 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:28 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:28 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:28 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Dec 14 14:18:28 volumio kernel: usb 1-1.5: USB disconnect, device number 15 Dec 14 14:18:29 volumio dhcpcd[759]: wlan0: carrier lost Dec 14 14:18:29 volumio avahi-daemon[1566]: Withdrawing address record for 192.168.91.101 on wlan0. Dec 14 14:18:29 volumio avahi-daemon[1566]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.91.101. Dec 14 14:18:29 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=e4:5e:1b:71:f3:fa reason=4 locally_generated=1 Dec 14 14:18:29 volumio wpa_supplicant[1477]: BSSID e4:5e:1b:71:f3:fa ignore list count incremented to 2, ignoring for 10 seconds Dec 14 14:18:29 volumio avahi-daemon[1566]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 14 14:18:29 volumio dhcpcd[759]: wlan0: deleting route to 192.168.91.0/24 Dec 14 14:18:29 volumio dhcpcd[759]: wlan0: deleting default route via 192.168.91.1 Dec 14 14:18:29 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Dec 14 14:18:29 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 14 14:18:29 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 14 14:18:29 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:18:29 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:18:29 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:18:29 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:18:29 volumio welcome[1871]: Resolved ip:[0] Dec 14 14:18:29 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:18:29 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 14 14:18:29 volumio kernel: ieee80211 phy2: rt2x00queue_flush_queue: Warning - Queue 0 failed to flush Dec 14 14:18:29 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Dec 14 14:18:29 volumio dhcpcd[759]: wlan0: removing interface Dec 14 14:18:29 volumio systemd[1]: Stopping ifup@wlan0.service - ifup for wlan0... Dec 14 14:18:29 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Dec 14 14:18:29 volumio kernel: scsi 1:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Dec 14 14:18:29 volumio kernel: usb 1-1.5: new high-speed USB device number 23 using dwc_otg Dec 14 14:18:29 volumio kernel: sdb: sdb1 Dec 14 14:18:29 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 14 14:18:30 volumio kernel: usb 1-1.5: New USB device found, idVendor=148f, idProduct=5370, bcdDevice= 1.01 Dec 14 14:18:30 volumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:30 volumio kernel: usb 1-1.5: Product: 802.11 n WLAN Dec 14 14:18:30 volumio kernel: usb 1-1.5: Manufacturer: Ralink Dec 14 14:18:30 volumio kernel: usb 1-1.5: SerialNumber: 1.0 Dec 14 14:18:30 volumio kernel: usb 1-1.5: reset high-speed USB device number 23 using dwc_otg Dec 14 14:18:30 volumio kernel: ieee80211 phy3: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected Dec 14 14:18:30 volumio kernel: ieee80211 phy3: rt2x00_set_rf: Info - RF chipset 5370 detected Dec 14 14:18:30 volumio kernel: ieee80211 phy3: Selected rate control algorithm 'minstrel_ht' Dec 14 14:18:30 volumio kernel: ieee80211 phy3: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin' Dec 14 14:18:30 volumio kernel: ieee80211 phy3: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36 Dec 14 14:18:30 volumio (udev-worker)[1774]: Network interface NamePolicy= disabled on kernel command line. Dec 14 14:18:30 volumio systemd[1]: ifup@wlan0.service: Deactivated successfully. Dec 14 14:18:30 volumio systemd[1]: Stopped ifup@wlan0.service - ifup for wlan0. Dec 14 14:18:30 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Dec 14 14:18:31 volumio rfkill[1927]: unblock set for type wifi Dec 14 14:18:31 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - RT5370 Wireless Adapter. Dec 14 14:18:31 volumio volumio[1571]: info: Loading i18n strings for locale en Dec 14 14:18:31 volumio dhcpcd[759]: wlan0: waiting for carrier Dec 14 14:18:31 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Dec 14 14:18:31 volumio volumio[1571]: Updating browse sources language Dec 14 14:18:31 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:18:31 volumio sh[1980]: wlan0=wlan0 Dec 14 14:18:31 volumio wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 24:05:88:15:59:e9 (SSID='sec_family_two' freq=2462 MHz) Dec 14 14:18:31 volumio kernel: wlan0: authenticate with 24:05:88:15:59:e9 (local address=7c:dd:90:4b:3c:e5) Dec 14 14:18:31 volumio kernel: wlan0: send auth to 24:05:88:15:59:e9 (try 1/3) Dec 14 14:18:31 volumio wpa_supplicant[1477]: wlan0: Trying to associate with 24:05:88:15:59:e9 (SSID='sec_family_two' freq=2462 MHz) Dec 14 14:18:31 volumio kernel: wlan0: authenticated Dec 14 14:18:31 volumio kernel: wlan0: associate with 24:05:88:15:59:e9 (try 1/3) Dec 14 14:18:32 volumio kernel: wlan0: RX AssocResp from 24:05:88:15:59:e9 (capab=0x1431 status=0 aid=2) Dec 14 14:18:32 volumio kernel: wlan0: associated Dec 14 14:18:32 volumio wpa_supplicant[1477]: wlan0: Associated with 24:05:88:15:59:e9 Dec 14 14:18:32 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 14 14:18:32 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Dec 14 14:18:32 volumio wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 24:05:88:15:59:e9 [PTK=CCMP GTK=CCMP] Dec 14 14:18:32 volumio wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 24:05:88:15:59:e9 completed [id=0 id_str=] Dec 14 14:18:32 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 24:05:88:15:59:e9 Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: carrier acquired Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: connected to Access Point: sec_family_two Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: IAID 90:4b:3c:e5 Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: adding address fe80::7edd:90ff:fe4b:3ce5 Dec 14 14:18:32 volumio dhcpcd[759]: ipv6_addaddr1: Permission denied Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: soliciting an IPv6 router Dec 14 14:18:32 volumio dhcpcd[759]: wlan0: soliciting a DHCP lease Dec 14 14:18:34 volumio ntpd[1312]: IO: Deleting interface #3 wlan0, 192.168.91.101#123, interface stats: received=81, sent=85, dropped=4, active_time=42 secs Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 144.202.0.197 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 44.190.5.123 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 144.202.41.38 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 198.199.14.101 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 45.83.234.123 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 72.14.183.39 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 23.159.16.194 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 134.215.155.177 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 129.146.193.200 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 172.232.15.202 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 24.111.79.186 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 141.11.89.193 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 97.107.136.23 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 74.208.25.46 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 23.186.168.127 unlink local addr 192.168.91.101 -> Dec 14 14:18:34 volumio ntpd[1312]: PROTO: 23.168.24.210 unlink local addr 192.168.91.101 -> Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::initPlayerControls Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 14:18:35 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 14 14:18:35 volumio volumio[1571]: Express server listening on port 3000 Dec 14 14:18:35 volumio volumio[1571]: [Metrics] WebUI: 32s 259.70ms Dec 14 14:18:35 volumio dhcpcd[759]: wlan0: offered 192.168.91.101 from 192.168.91.1 Dec 14 14:18:35 volumio dhcpcd[759]: wlan0: probing address 192.168.91.101/24 Dec 14 14:18:35 volumio volumio[1571]: info: CoreStateMachine::resetVolumioState Dec 14 14:18:35 volumio volumio[1571]: info: CoreStateMachine::getcurrentVolume Dec 14 14:18:35 volumio volumio[1571]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 14:18:36 volumio sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 14:18:36 volumio sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:36 volumio sudo[2005]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 14:18:36 volumio sudo[2003]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:36 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:36 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:37 volumio volumio[1571]: info: Volumio Network Manager: Network status updated: 2 Dec 14 14:18:37 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2275","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"72235369"} Dec 14 14:18:37 volumio sudo[2010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 14 14:18:37 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:38 volumio systemd[1]: media-ESD\x2dUSB.mount: Deactivated successfully. Dec 14 14:18:38 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:38 volumio volumio-remote-updater[693]: [2025-12-14 14:18:38] [connect] Successful connection Dec 14 14:18:40 volumio dhcpcd[759]: wlan0: leased 192.168.91.101 for 86400 seconds Dec 14 14:18:40 volumio avahi-daemon[1566]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.91.101. Dec 14 14:18:40 volumio avahi-daemon[1566]: New relevant interface wlan0.IPv4 for mDNS. Dec 14 14:18:40 volumio avahi-daemon[1566]: Registering new address record for 192.168.91.101 on wlan0.IPv4. Dec 14 14:18:40 volumio dhcpcd[759]: wlan0: adding route to 192.168.91.0/24 Dec 14 14:18:40 volumio dhcpcd[759]: wlan0: adding default route via 192.168.91.1 Dec 14 14:18:40 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 14 14:18:40 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 14 14:18:40 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:18:40 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:18:40 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:18:40 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:18:40 volumio kernel: usb 1-1.2: USB disconnect, device number 22 Dec 14 14:18:40 volumio welcome[2029]: Resolved ip:[1] 192.168.91.101 Dec 14 14:18:40 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:18:40 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 14 14:18:41 volumio volumio[1571]: info: VolumeController:: Volume=86 Mute =false Dec 14 14:18:41 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:18:41 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:41 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 14:18:41 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:18:41 volumio volumio[1571]: info: CoreStateMachine::updateTrackBlock Dec 14 14:18:41 volumio kernel: usb 1-1.2: new high-speed USB device number 24 using dwc_otg Dec 14 14:18:41 volumio volumio[1571]: info: CorePlayQueue::getTrackBlock Dec 14 14:18:41 volumio volumio[1571]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 14:18:41 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:41 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:41 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:41 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:41 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:41 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:41 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:18:42 volumio ntpd[1312]: IO: Listen normally on 4 wlan0 192.168.91.101:123 Dec 14 14:18:42 volumio ntpd[1312]: IO: new interface(s) found: waking up resolver Dec 14 14:18:42 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:18:42 volumio volumio[1571]: info: Reloading queue from file Dec 14 14:18:42 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:18:42 volumio kernel: sda: sda1 Dec 14 14:18:42 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:18:42 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:42 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:42 volumio volumio[1571]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/sdb1)" Dec 14 14:18:42 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:42 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:42 volumio volumio[1571]: info: Ignoring mount for partition: ESD-USB Dec 14 14:18:43 volumio volumio[1571]: info: Discovery: Browse raised the following error Error: index has no corresponding interface Dec 14 14:18:43 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 14 14:18:43 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:43 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:18:43 volumio volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 14 14:18:43 volumio volumio[1571]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/ESD-USB Dec 14 14:18:43 volumio upmpdcli[2064]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.91.101 port 49152 IPV6 port 49152 Dec 14 14:18:43 volumio upmpdcli[2064]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Dec 14 14:18:43 volumio upmpdcli[2064]: :2:../src/main.cxx:728::MPD connection failed Dec 14 14:18:43 volumio volumio[1571]: info: CoreStateMachine::setRepeat true single undefined Dec 14 14:18:43 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:18:43 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:43 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 14:18:43 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:18:43 volumio volumio[1571]: info: CoreStateMachine::setRandom null Dec 14 14:18:43 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:18:43 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:43 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:18:43 volumio volumio[1571]: info: Setting Device type: Raspberry PI Dec 14 14:18:43 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"30","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2308","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"85347727"} Dec 14 14:18:44 volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Dec 14 14:18:44 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:44 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:44 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:44 volumio volumio[1571]: error: Failed to umount ESD-USB: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Dec 14 14:18:44 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:45 volumio volumio[1571]: info: VolumeController:: Volume=86 Mute =false Dec 14 14:18:45 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:18:45 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:45 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:18:45 volumio upmpdcli[2064]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Dec 14 14:18:45 volumio upmpdcli[2064]: :2:../src/main.cxx:728::MPD connection failed Dec 14 14:18:45 volumio volumio[1571]: info: Discovery: Browse raised the following error Error: index has no corresponding interface Dec 14 14:18:45 volumio volumio[1571]: info: Discovery: A device disappeared from network Dec 14 14:18:46 volumio volumio[1571]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Dec 14 14:18:46 volumio sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 14:18:46 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:46 volumio volumio[1571]: MPD error: Cannot assign requested address Dec 14 14:18:46 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:46 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:46 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:46 volumio volumio[1571]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/sdb1)" Dec 14 14:18:46 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:46 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:46 volumio volumio[1571]: info: Ignoring mount for partition: ESD-USB Dec 14 14:18:47 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"31","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2342","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"90613212"} Dec 14 14:18:47 volumio sudo[2107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Dec 14 14:18:47 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:47 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:47 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:47 volumio volumio[1571]: error: Failed to umount ESD-USB: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Dec 14 14:18:47 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:47 volumio volumio[1571]: info: Upmpdcli Daemon Started Dec 14 14:18:47 volumio volumio[1571]: info: Discovery: adding ed439dd6-1528-46dd-a477-29a27b03e132 Dec 14 14:18:47 volumio volumio[1571]: info: Discovery: Found device Volumio Dec 14 14:18:47 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 14 14:18:47 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:48 volumio volumio[1571]: info: Completed loading Core Plugins Dec 14 14:18:48 volumio volumio[1814]: Starting albumart workers Dec 14 14:18:48 volumio volumio[1571]: info: Preparing to generate the ALSA configuration file Dec 14 14:18:48 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:48 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:48 volumio volumio[1571]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/sdb1)" Dec 14 14:18:48 volumio volumio[1571]: lsblk: /dev/sdb1: not a block device Dec 14 14:18:48 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:18:48 volumio volumio[1571]: info: Ignoring mount for partition: ESD-USB Dec 14 14:18:48 volumio kernel: usb 1-1.2: USB disconnect, device number 24 Dec 14 14:18:49 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"32","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2401","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"95788118"} Dec 14 14:18:49 volumio kernel: usb 1-1.2: new high-speed USB device number 25 using dwc_otg Dec 14 14:18:49 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:49 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:49 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:49 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:49 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:49 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:49 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:18:49 volumio volumio[1816]: Starting albumart workers Dec 14 14:18:49 volumio sudo[2124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Dec 14 14:18:49 volumio sudo[2124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:49 volumio sudo[2124]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:49 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:49 volumio volumio[1571]: error: Failed to umount ESD-USB: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Dec 14 14:18:49 volumio volumio[1571]: umount: /dev/sdb1: no mount point specified. Dec 14 14:18:49 volumio upmpdcli[2064]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Dec 14 14:18:49 volumio upmpdcli[2064]: :2:../src/main.cxx:728::MPD connection failed Dec 14 14:18:49 volumio volumio[1571]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 14 14:18:49 volumio volumio[1571]: info: Discovery: this is already registered, ed439dd6-1528-46dd-a477-29a27b03e132 Dec 14 14:18:49 volumio volumio[1571]: info: Discovery: Found device Volumio Dec 14 14:18:49 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 14 14:18:49 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:18:50 volumio volumio[1571]: info: Asound.conf file unchanged, so no further update is needed Dec 14 14:18:50 volumio volumio[1571]: info: Output device has changed, restarting MPD Dec 14 14:18:50 volumio volumio[1571]: info: ___________ START PLUGINS ___________ Dec 14 14:18:50 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:18:50 volumio volumio[1815]: Starting albumart workers Dec 14 14:18:50 volumio sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 14:18:50 volumio kernel: sda: sda1 Dec 14 14:18:50 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:18:50 volumio volumio[1571]: info: ControllerMpd::onStart: Initializing MPD Dec 14 14:18:50 volumio volumio[1571]: info: Creating MPD Configuration file Dec 14 14:18:50 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:50 volumio sudo[2132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 14:18:50 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:50 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:50 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 14:18:50 volumio volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:18:51 volumio volumio[1571]: info: [1765743530992] CoreMusicLibrary::Adding element Media Servers Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:18:51 volumio sudo[2137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 14:18:51 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:51 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:51 volumio volumio[1571]: info: UPNP Browser: Client initialized successfully Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:18:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 14:18:51 volumio sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 14:18:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 14:18:51 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:51 volumio volumio[1571]: info: [1765743531390] CoreMusicLibrary::Adding element Last_100 Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:18:51 volumio volumio[1571]: info: [1765743531456] CoreMusicLibrary::Adding element Webradio Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:18:51 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:18:51 volumio volumio[1571]: info: Initializing BBC Radios Dec 14 14:18:51 volumio sudo[2142]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 14:18:51 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 14:18:51 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 14 14:18:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 14:18:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 14 14:18:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 14:18:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 14:18:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 14:18:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 14:18:52 volumio kernel: usb 1-1.2: USB disconnect, device number 25 Dec 14 14:18:52 volumio sudo[2148]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 14:18:52 volumio sudo[2148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 14:18:52 volumio sudo[2155]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 14 14:18:52 volumio sudo[2148]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:52 volumio kernel: usb 1-1.2: new high-speed USB device number 26 using dwc_otg Dec 14 14:18:52 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:18:52 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:18:52 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:18:52 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:18:52 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:18:52 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:18:52 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:18:52 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:18:52 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:18:53 volumio volumio[1571]: info: Loading i18n strings for locale en Dec 14 14:18:53 volumio volumio[1571]: info: Loading i18n strings for locale en Dec 14 14:18:53 volumio volumio-remote-updater[693]: [2025-12-14 14:18:53] [connect] Successful connection Dec 14 14:18:53 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:18:53 volumio kernel: sda: sda1 Dec 14 14:18:53 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:18:54 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:18:54 volumio volumio[1571]: info: Volumio Calling Home Dec 14 14:18:54 volumio sudo[2169]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 14 14:18:54 volumio sudo[2169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:54 volumio sudo[2169]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:57 volumio sudo[2189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 14 14:18:57 volumio sudo[2191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 14 14:18:57 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:57 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:57 volumio sudo[2193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 14 14:18:57 volumio sudo[2193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:18:57 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root Dec 14 14:18:57 volumio systemd[1]: Reloading. Dec 14 14:18:59 volumio upmpdcli[2064]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 14 14:18:59 volumio upmpdcli[2064]: :2:../src/main.cxx:728::MPD connection failed Dec 14 14:19:01 volumio systemd[1]: Reloading. Dec 14 14:19:01 volumio sudo[2193]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:04 volumio mpd[2157]: 2025-12-14T14:19:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 14:19:04 volumio kernel: usb 1-1.2: USB disconnect, device number 26 Dec 14 14:19:04 volumio kernel: usb 1-1.2: new high-speed USB device number 27 using dwc_otg Dec 14 14:19:05 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:05 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:05 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:05 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:05 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:05 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:05 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:05 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 14:19:05 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:05 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:06 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:06 volumio kernel: sda: sda1 Dec 14 14:19:06 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:07 volumio volumio[1571]: info: Mounting Device ESD-USB Dec 14 14:19:08 volumio sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ESD-USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 14 14:19:08 volumio sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:08 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 14 14:19:08 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 14 14:19:08 volumio sudo[2283]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:08 volumio volumio[1571]: info: touch_display: No backlight interface detected. Dec 14 14:19:08 volumio volumio-remote-updater[693]: [2025-12-14 14:19:08] [connect] Successful connection Dec 14 14:19:09 volumio volumio[1571]: info: touch_display: systemctl daemon-reload succeeded. Dec 14 14:19:09 volumio volumio[1571]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 14 14:19:09 volumio volumio[1571]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 14 14:19:09 volumio volumio[1571]: info: minidlna: Found miniDLNA version 1.3.0 Dec 14 14:19:09 volumio volumio[1571]: info: minidlna: Starting minidlna.service Dec 14 14:19:09 volumio volumio[1571]: info: MPD Permissions set Dec 14 14:19:09 volumio sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 14 14:19:09 volumio sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:09 volumio volumio[1571]: info: MPD Permissions set Dec 14 14:19:09 volumio sudo[2291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 14 14:19:09 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:09 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"33","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2435","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"108344036"} Dec 14 14:19:10 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 14 14:19:10 volumio sudo[2286]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:10 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:10 volumio sudo[2299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 14 14:19:10 volumio sudo[2299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:10 volumio startx[2322]: X.Org X Server 1.21.1.7 Dec 14 14:19:10 volumio startx[2322]: X Protocol Version 11, Revision 0 Dec 14 14:19:10 volumio startx[2322]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Dec 14 14:19:10 volumio startx[2322]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 14 14:19:10 volumio startx[2322]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Dec 14 14:19:10 volumio startx[2322]: Current version of pixman: 0.44.0 Dec 14 14:19:10 volumio startx[2322]: Before reporting problems, check http://wiki.x.org Dec 14 14:19:10 volumio startx[2322]: to make sure that you have the latest version. Dec 14 14:19:10 volumio startx[2322]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 14 14:19:10 volumio startx[2322]: (++) from command line, (!!) notice, (II) informational, Dec 14 14:19:10 volumio startx[2322]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 14 14:19:10 volumio startx[2322]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 14 14:19:10 2025 Dec 14 14:19:10 volumio startx[2322]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 14 14:19:10 volumio startx[2322]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 14 14:19:10 volumio systemd[1]: media-ESD\x2dUSB.mount: Deactivated successfully. Dec 14 14:19:10 volumio sudo[2299]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:10 volumio sudo[2289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 14 14:19:10 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:10 volumio volumio[1571]: info: Volumio called home Dec 14 14:19:11 volumio startx[2322]: (EE) Dec 14 14:19:11 volumio startx[2322]: Fatal server error: Dec 14 14:19:11 volumio startx[2322]: (EE) no screens found(EE) Dec 14 14:19:11 volumio startx[2322]: (EE) Dec 14 14:19:11 volumio startx[2322]: Please consult the The X.Org Foundation support Dec 14 14:19:11 volumio startx[2322]: at http://wiki.x.org Dec 14 14:19:11 volumio startx[2322]: for help. Dec 14 14:19:11 volumio startx[2322]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. Dec 14 14:19:11 volumio startx[2322]: (EE) Dec 14 14:19:11 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:11 volumio startx[2322]: (EE) Server terminated with error (1). Closing log file. Dec 14 14:19:11 volumio kernel: usb 1-1.2: USB disconnect, device number 27 Dec 14 14:19:11 volumio kernel: usb 1-1.2: new high-speed USB device number 28 using dwc_otg Dec 14 14:19:11 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:11 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:11 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:11 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:11 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:11 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:11 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:12 volumio kernel: usb 1-1.2: USB disconnect, device number 28 Dec 14 14:19:12 volumio kernel: usb 1-1.2: new high-speed USB device number 29 using dwc_otg Dec 14 14:19:13 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:13 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:13 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:13 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:13 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:13 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:13 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:13 volumio volumio[1571]: error: MPD error: The expression evaluated to a falsy value: Dec 14 14:19:13 volumio volumio[1571]: assert.ok(self.idling) Dec 14 14:19:13 volumio volumio[1571]: error: The expression evaluated to a falsy value: Dec 14 14:19:13 volumio volumio[1571]: assert.ok(self.idling) Dec 14 14:19:13 volumio volumio[1571]: info: minidlna: systemctl start minidlna.service succeeded. Dec 14 14:19:13 volumio volumio[1571]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 14 14:19:13 volumio volumio[1571]: info: touch_display: Volumio Kiosk started. Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 14 14:19:13 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:13 volumio volumio[1571]: info: Completed starting Core Plugins Dec 14 14:19:13 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:19:13 volumio volumio[1571]: info: ----- MyVolumio plugins startup ---- Dec 14 14:19:13 volumio volumio[1571]: info: ------------------------------------------- Dec 14 14:19:13 volumio volumio[1571]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 14 14:19:13 volumio volumio[1571]: info: MPD running with PID2157 Dec 14 14:19:13 volumio volumio[1571]: ,establishing connection Dec 14 14:19:13 volumio volumio[1571]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 14:19:13 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 14:19:13 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:19:13 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:13 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 14:19:14 volumio mpd_monitor.sh[2355]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 14:19:14 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:14 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 14:19:14 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 14:19:14 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 14:19:14 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 14:19:14 volumio mpd_monitor.sh[2359]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 14:19:14 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:14 volumio volumio[1571]: error: updateQueue error: null Dec 14 14:19:14 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:14 volumio kernel: sda: sda1 Dec 14 14:19:14 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:14 volumio volumio[1571]: info: touch_display: X display number found: 0 Dec 14 14:19:14 volumio volumio[1571]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/ESD-USB Dec 14 14:19:14 volumio volumio[1571]: info: Scanning new location : "USB/ESD-USB" Dec 14 14:19:15 volumio volumio[1571]: info: Mounting Device ESD-USB Dec 14 14:19:15 volumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ESD-USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 14 14:19:15 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:15 volumio upmpdcli[2379]: writing RSA key Dec 14 14:19:15 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 14 14:19:15 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 14 14:19:15 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:16 volumio volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 14 14:19:16 volumio volumio[1571]: info: Successfully started MPD Monitor Dec 14 14:19:16 volumio volumio[1571]: info: Successfully started MPD Monitor Dec 14 14:19:16 volumio volumio[1571]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 14 14:19:16 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"34","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2469","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"116259500"} Dec 14 14:19:16 volumio sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 14 14:19:16 volumio sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:16 volumio sudo[2387]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:17 volumio sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 14 14:19:17 volumio sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:17 volumio sudo[2390]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:17 volumio systemd[1]: media-ESD\x2dUSB.mount: Deactivated successfully. Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: info: Dec 14 14:19:17 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:17 volumio volumio[1571]: error: updateQueue error: null Dec 14 14:19:17 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: info: Dec 14 14:19:17 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:17 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: info: Dec 14 14:19:17 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:17 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 521 milliseconds Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 419 milliseconds Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 438 milliseconds Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 385 milliseconds Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 383 milliseconds Dec 14 14:19:17 volumio volumio[1571]: info: sendMpdCommand status took 380 milliseconds Dec 14 14:19:17 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: info: Command Router : Notfying DB Updatetrue Dec 14 14:19:19 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: info: Command Router : Notfying DB Updatetrue Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::Close All Modals sent Dec 14 14:19:19 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: info: Command Router : Notfying DB Updatetrue Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::Close All Modals sent Dec 14 14:19:19 volumio volumio[1571]: verbose: ControllerMpd::parseState Dec 14 14:19:19 volumio volumio[1571]: info: ControllerMpd::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::servicePushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 14 14:19:19 volumio volumio[1571]: info: ControllerMpd::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::servicePushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 14 14:19:19 volumio volumio[1571]: info: ControllerMpd::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::servicePushState Dec 14 14:19:19 volumio volumio[1571]: info: CoreStateMachine::pushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: CoreCommandRouter::volumioPushState Dec 14 14:19:19 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 14 14:19:19 volumio volumio[1571]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 14 14:19:19 volumio volumio[1571]: info: ------------------------------ 2715ms Dec 14 14:19:19 volumio volumio[1571]: info: ------------------------------ 2596ms Dec 14 14:19:19 volumio volumio[1571]: info: ------------------------------ 2540ms Dec 14 14:19:20 volumio volumio[1571]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 14 14:19:20 volumio volumio[1571]: info: touch_display: X display number found: 0 Dec 14 14:19:20 volumio volumio[1571]: info: MPD Database updated - AlbumList cache refreshed Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: database Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: MPD Database updated - AlbumList cache refreshed Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: database Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: MPD Database updated - AlbumList cache refreshed Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: database Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: Dec 14 14:19:20 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:20 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:20 volumio kernel: usb 1-1.2: USB disconnect, device number 29 Dec 14 14:19:21 volumio kernel: usb 1-1.2: new high-speed USB device number 30 using dwc_otg Dec 14 14:19:21 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:21 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:21 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:21 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:21 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:21 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:21 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:22 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:22 volumio kernel: sda: sda1 Dec 14 14:19:22 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:23 volumio volumio-remote-updater[693]: [2025-12-14 14:19:23] [connect] Successful connection Dec 14 14:19:25 volumio startx[2321]: xinit: giving up Dec 14 14:19:25 volumio startx[2321]: xinit: unable to connect to X server: Cannot assign requested address Dec 14 14:19:25 volumio startx[2321]: xinit: server error Dec 14 14:19:25 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:19:25 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 14 14:19:26 volumio kernel: usb 1-1.2: USB disconnect, device number 30 Dec 14 14:19:27 volumio kernel: usb 1-1.2: new high-speed USB device number 31 using dwc_otg Dec 14 14:19:27 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:27 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:27 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:27 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:27 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:27 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:27 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:27 volumio volumio[1571]: info: Scanning removed location : "USB/ESD-USB" Dec 14 14:19:27 volumio kernel: usb 1-1.2: USB disconnect, device number 31 Dec 14 14:19:28 volumio volumio[1571]: lsblk: /dev/sda1: not a block device Dec 14 14:19:28 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:19:28 volumio volumio[1571]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/sda1)" Dec 14 14:19:28 volumio volumio[1571]: lsblk: /dev/sda1: not a block device Dec 14 14:19:28 volumio volumio[1571]: lsblk: /dev/: not a block device Dec 14 14:19:28 volumio volumio[1571]: info: Ignoring mount for partition: ESD-USB Dec 14 14:19:28 volumio volumio[1571]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 14 14:19:28 volumio kernel: usb 1-1.2: new high-speed USB device number 32 using dwc_otg Dec 14 14:19:28 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:28 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:28 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:28 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:28 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:28 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:28 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:28 volumio volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 14 14:19:28 volumio volumio[1571]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/ESD-USB Dec 14 14:19:29 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:19:29 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:19:29 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 14:19:29 volumio volumio[1571]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120 /dev/disk/by-label/ESD-USB /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-VendorCo_ProductCode_KT2020000001813-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"35","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","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":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2503","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"119727009"} Dec 14 14:19:29 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:29 volumio sudo[2474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 14 14:19:29 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:29 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Dec 14 14:19:29 volumio volumio[1571]: umount: /dev/sda1: no mount point specified. Dec 14 14:19:29 volumio volumio[1571]: error: Failed to umount ESD-USB: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Dec 14 14:19:29 volumio volumio[1571]: umount: /dev/sda1: no mount point specified. Dec 14 14:19:29 volumio kernel: sda: sda1 Dec 14 14:19:29 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:30 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:30 volumio volumio[1571]: info: Dec 14 14:19:30 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:30 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:30 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:30 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:30 volumio volumio[1571]: info: Dec 14 14:19:30 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:30 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:30 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:30 volumio volumio[1571]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 14:19:30 volumio volumio[1571]: info: touch_display: X display number found: 0 Dec 14 14:19:33 volumio kernel: usb 1-1.2: USB disconnect, device number 32 Dec 14 14:19:33 volumio kernel: usb 1-1.2: new high-speed USB device number 33 using dwc_otg Dec 14 14:19:33 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:33 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:33 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:33 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:33 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:33 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:33 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:34 volumio kernel: usb 1-1.2: USB disconnect, device number 33 Dec 14 14:19:34 volumio kernel: usb 1-1.2: new high-speed USB device number 34 using dwc_otg Dec 14 14:19:34 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:34 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:34 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:34 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:34 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:34 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:35 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:36 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:36 volumio kernel: sda: sda1 Dec 14 14:19:36 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:38 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:38 volumio volumio[1571]: info: Dec 14 14:19:38 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:38 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:38 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:38 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:38 volumio volumio[1571]: info: Dec 14 14:19:38 volumio volumio[1571]: ---------------------------- MPD announces state update: update Dec 14 14:19:38 volumio volumio[1571]: info: ControllerMpd::getState Dec 14 14:19:38 volumio volumio[1571]: verbose: ControllerMpd::sendMpdCommand status Dec 14 14:19:38 volumio volumio-remote-updater[693]: [2025-12-14 14:19:38] [connect] Successful connection Dec 14 14:19:38 volumio volumio[1571]: error: MyVolumio Plugin failed to start in a timely fashion Dec 14 14:19:38 volumio volumio[1571]: [Metrics] CommandRouter: 93s 190.60ms Dec 14 14:19:38 volumio volumio[1571]: info: CoreCommandRouter::volumiosetStartupVolume Dec 14 14:19:38 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:19:38 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:19:39 volumio volumio[1571]: info: CoreCommandRouter::Close All Modals sent Dec 14 14:19:39 volumio volumio[1571]: info: CoreCommandRouter::Close All Modals sent Dec 14 14:19:39 volumio volumio[1571]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 14:19:39 volumio volumio[1571]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 14 14:19:39 volumio volumio[1571]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 14 14:19:39 volumio volumio[1571]: at new NodeError (node:internal/errors:405:5) Dec 14 14:19:39 volumio volumio[1571]: at assert (node:internal/assert:14:11) Dec 14 14:19:39 volumio volumio[1571]: at internalConnectMultiple (node:net:1118:3) Dec 14 14:19:39 volumio volumio[1571]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 14 14:19:39 volumio volumio[1571]: at listOnTimeout (node:internal/timers:575:11) Dec 14 14:19:39 volumio volumio[1571]: at process.processTimers (node:internal/timers:514:7) { Dec 14 14:19:39 volumio volumio[1571]: code: 'ERR_INTERNAL_ASSERTION' Dec 14 14:19:39 volumio volumio[1571]: } Dec 14 14:19:39 volumio volumio[1571]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 14:19:40 volumio kernel: usb 1-1.2: USB disconnect, device number 34 Dec 14 14:19:40 volumio kernel: usb 1-1.2: new high-speed USB device number 35 using dwc_otg Dec 14 14:19:40 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:40 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:40 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:40 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:40 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:40 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:40 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:41 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:41 volumio kernel: sda: sda1 Dec 14 14:19:41 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:44 volumio kernel: usb 1-1.2: USB disconnect, device number 35 Dec 14 14:19:44 volumio kernel: usb 1-1.2: new high-speed USB device number 36 using dwc_otg Dec 14 14:19:44 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:44 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:44 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:44 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:44 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:44 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:44 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:45 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:45 volumio kernel: sda: sda1 Dec 14 14:19:45 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:49 volumio kernel: usb 1-1.2: USB disconnect, device number 36 Dec 14 14:19:50 volumio kernel: usb 1-1.2: new high-speed USB device number 37 using dwc_otg Dec 14 14:19:50 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:50 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:50 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:50 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:50 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:50 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:50 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:51 volumio kernel: usb 1-1.2: USB disconnect, device number 37 Dec 14 14:19:51 volumio kernel: usb 1-1.2: new high-speed USB device number 38 using dwc_otg Dec 14 14:19:51 volumio sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 14:18' Dec 14 14:19:51 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:19:51 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 14 14:19:51 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 14:19:51 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 14 14:19:51 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 14 14:19:51 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 14 14:19:51 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 14 14:19:51 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Dec 14 14:19:53 volumio kernel: scsi 0:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Dec 14 14:19:53 volumio kernel: sda: sda1 Dec 14 14:19:53 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Dec 14 14:19:53 volumio volumio-remote-updater[693]: [2025-12-14 14:19:53] [connect] Successful connection 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"