May 31 14:46:03 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
May 31 14:46:05 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
May 31 14:46:05 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
May 31 14:46:11 volumio volumio[1221]: error: MyVolumio Plugin failed to start in a timely fashion
May 31 14:46:11 volumio volumio[1221]: [Metrics] CommandRouter: 38s 434.92ms
May 31 14:46:11 volumio volumio[1221]: info: CoreCommandRouter::volumiosetStartupVolume
May 31 14:46:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 31 14:46:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 14:46:11 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent
May 31 14:46:11 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent
May 31 14:46:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 31 14:46:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 31 14:46:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 31 14:46:15 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 31 14:46:15 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 14:46:15 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 31 14:46:21 volumio upmpdcli[1795]: writing RSA key
May 31 14:46:28 volumio volumio[1221]: info: BOOT COMPLETED
May 31 14:46:55 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using xhci_hcd
May 31 14:46:56 volumio kernel: usb 1-1.3: New USB device found, idVendor=20b1, idProduct=0008, bcdDevice= 0.01
May 31 14:46:56 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0
May 31 14:46:56 volumio kernel: usb 1-1.3: Product: xmos usb audio
May 31 14:46:56 volumio kernel: usb 1-1.3: Manufacturer: FX-AUDIO
May 31 14:46:56 volumio (udev-worker)[1857]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99.
May 31 14:47:01 volumio kernel: usb 1-1.3: USB disconnect, device number 4
May 31 14:47:03 volumio kernel: usb 1-1.1: new high-speed USB device number 5 using xhci_hcd
May 31 14:47:03 volumio kernel: usb 1-1.1: New USB device found, idVendor=20b1, idProduct=0008, bcdDevice= 0.01
May 31 14:47:03 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
May 31 14:47:03 volumio kernel: usb 1-1.1: Product: xmos usb audio
May 31 14:47:03 volumio kernel: usb 1-1.1: Manufacturer: FX-AUDIO
May 31 14:47:05 volumio kernel: usb 1-1.1: USB disconnect, device number 5
May 31 14:47:34 volumio kernel: usb 1-1.1: new high-speed USB device number 6 using xhci_hcd
May 31 14:47:34 volumio kernel: usb 1-1.1: New USB device found, idVendor=20b1, idProduct=0008, bcdDevice= 0.01
May 31 14:47:34 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
May 31 14:47:34 volumio kernel: usb 1-1.1: Product: xmos usb audio
May 31 14:47:34 volumio kernel: usb 1-1.1: Manufacturer: FX-AUDIO
May 31 14:47:56 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 31 14:47:56 volumio volumio[1221]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
May 31 14:47:56 volumio volumio[1221]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
May 31 14:47:56 volumio volumio[1221]: at new NodeError (node:internal/errors:405:5)
May 31 14:47:56 volumio volumio[1221]: at assert (node:internal/assert:14:11)
May 31 14:47:56 volumio volumio[1221]: at internalConnectMultiple (node:net:1118:3)
May 31 14:47:56 volumio volumio[1221]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
May 31 14:47:56 volumio volumio[1221]: at listOnTimeout (node:internal/timers:575:11)
May 31 14:47:56 volumio volumio[1221]: at process.processTimers (node:internal/timers:514:7) {
May 31 14:47:56 volumio volumio[1221]: code: 'ERR_INTERNAL_ASSERTION'
May 31 14:47:56 volumio volumio[1221]: }
May 31 14:47:56 volumio volumio[1221]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 31 14:47:56 volumio (udev-worker)[1856]: pcmC5D0p: Spawned process '/bin/bash -c '/usr/local/bin/volumio usbattach'' [1858] is taking longer than 59s to complete
May 31 14:47:56 volumio systemd-udevd[465]: pcmC5D0p: Worker [1856] processing SEQNUM=2788 is taking a long time
May 31 14:47:59 volumio sudo[1981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 14:46'
May 31 14:47:59 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"