Aug 27 08:58:39 buddy ntpd[1036]: CLOCK: time stepped by 100.848668
Aug 27 08:58:39 buddy ntpd[1036]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Aug 27 08:58:39 buddy systemd[1]: Started mpd.service - Music Player Daemon.
Aug 27 08:58:39 buddy sudo[1517]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:39 buddy sudo[1503]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:39 buddy volumio[1249]: info: [now-playing] App is listening on port 4004.
Aug 27 08:58:39 buddy volumio[1249]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Aug 27 08:58:39 buddy systemd[1]: systemd-fsckd.service: Deactivated successfully.
Aug 27 08:58:39 buddy volumio-remote-updater[829]: [2026-08-27 08:58:39] [connect] Successful connection
Aug 27 08:58:39 buddy volumio[1249]: info: Discovery: this is already registered, 766a9397-683b-4240-80d0-0bb0d7418e08
Aug 27 08:58:39 buddy volumio[1249]: info: Discovery: Found device Buddy
Aug 27 08:58:39 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:39 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:39 buddy volumio[1249]: info: Discovery: adding 2ad5b742-fc08-4616-9b88-0af678d37b73
Aug 27 08:58:39 buddy volumio[1249]: info: Discovery: Found device Einstein
Aug 27 08:58:40 buddy volumio[1249]: info: Discovery: Connecting to remote: 192.168.10.63
Aug 27 08:58:40 buddy sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 27 08:58:40 buddy sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 27 08:58:40 buddy sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:40 buddy sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:40 buddy sudo[1595]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:40 buddy sudo[1593]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:40 buddy volumio[1249]: info: Volumio Network Manager: Network status updated: 1
Aug 27 08:58:40 buddy volumio[1249]: info: MPD Permissions set
Aug 27 08:58:40 buddy volumio[1249]: info: Completed starting Core Plugins
Aug 27 08:58:40 buddy volumio[1249]: info: -------------------------------------------
Aug 27 08:58:40 buddy volumio[1249]: info: ----- MyVolumio plugins startup ----
Aug 27 08:58:40 buddy volumio[1249]: info: -------------------------------------------
Aug 27 08:58:40 buddy volumio[1249]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 27 08:58:40 buddy volumio[1249]: info: MPD Permissions set
Aug 27 08:58:40 buddy volumio[1249]: info: Discovery: adding 526780f4-2c42-4e1d-96d1-a82cb53a75c9
Aug 27 08:58:40 buddy volumio[1249]: info: Discovery: Found device Tessa
Aug 27 08:58:40 buddy volumio[1249]: info: Discovery: Connecting to remote: 192.168.10.67
Aug 27 08:58:40 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 16
Aug 27 08:58:40 buddy volumio[1249]: info: Volumio called home
Aug 27 08:58:40 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 17
Aug 27 08:58:41 buddy volumio[1249]: error: MPD error: The expression evaluated to a falsy value:
Aug 27 08:58:41 buddy volumio[1249]: assert.ok(self.idling)
Aug 27 08:58:41 buddy volumio[1249]: error: The expression evaluated to a falsy value:
Aug 27 08:58:41 buddy volumio[1249]: assert.ok(self.idling)
Aug 27 08:58:41 buddy volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18
Aug 27 08:58:41 buddy volumio[1249]: info: MPD running with PID1536
Aug 27 08:58:41 buddy volumio[1249]: ,establishing connection
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:58:41 buddy volumio[1249]: error: updateQueue error: null
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18
Aug 27 08:58:42 buddy volumio[1249]: info: Received Get System Info
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 08:58:42 buddy volumio[1249]: info: Discovery: Getting this device information
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 08:58:42 buddy volumio5-onboarding[1523]: time=2026-08-27T08:58:42.050-05:00 level=INFO msg="system info for c9fdefbf28922d64c787b875fe2d1bd6" deviceName=Buddy deviceVariant=volumio deviceModel= softwareVersion=4.119
Aug 27 08:58:42 buddy volumio5-onboarding[1523]: time=2026-08-27T08:58:42.067-05:00 level=INFO msg="bootstrapping state" hasInternet=true
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127:3000 from 192.168.10.63 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19
Aug 27 08:58:42 buddy volumio[1249]: info: Received Get System Info
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 08:58:42 buddy volumio[1249]: info: Discovery: Getting this device information
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 20
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127:3000 from 192.168.10.67 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21
Aug 27 08:58:42 buddy volumio[1249]: info: Discovery: Connected to remote: 192.168.10.63
Aug 27 08:58:42 buddy volumio[1249]: error: updateQueue error: null
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 22
Aug 27 08:58:42 buddy volumio[1249]: info: Starting Shairport Sync
Aug 27 08:58:42 buddy volumio[1249]: info: Starting Shairport Sync
Aug 27 08:58:42 buddy volumio[1249]: info: Starting Shairport Sync
Aug 27 08:58:42 buddy sudo[1623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 27 08:58:42 buddy sudo[1623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy sudo[1625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 27 08:58:42 buddy sudo[1625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:42 buddy sudo[1627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 27 08:58:42 buddy sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:42 buddy sudo[1629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 27 08:58:42 buddy sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 23
Aug 27 08:58:42 buddy volumio[1249]: info: Received Get System Info
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 08:58:42 buddy volumio[1249]: info: Discovery: Getting this device information
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 27 08:58:42 buddy systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 27 08:58:42 buddy systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 08:58:42 buddy systemd[1]: shairport-sync.service: Consumed 1.625s CPU time.
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 27 08:58:42 buddy volumio[1249]: info: Received Get System Info
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 08:58:42 buddy volumio[1249]: info: Discovery: Getting this device information
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 08:58:42 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:42 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:42 buddy systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 08:58:42 buddy sudo[1625]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:42 buddy systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 27 08:58:42 buddy systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 27 08:58:42 buddy systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 08:58:42 buddy systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 08:58:42 buddy sudo[1627]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:42 buddy systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Aug 27 08:58:42 buddy volumio[1249]: info: Listing playlists
Aug 27 08:58:42 buddy sudo[1629]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:42 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 24
Aug 27 08:58:43 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:43 buddy volumio-remote-updater[829]: [2026-08-27 08:58:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787839119 101
Aug 27 08:58:43 buddy volumio[1249]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 25
Aug 27 08:58:43 buddy volumio[1249]: info: Shairport-Sync Started
Aug 27 08:58:43 buddy volumio[1249]: Error adding Membership: Error: addMembership EINVAL
Aug 27 08:58:43 buddy volumio[1249]: info: Shairport-Sync Started
Aug 27 08:58:43 buddy volumio[1249]: info: Shairport-Sync Started
Aug 27 08:58:43 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 26
Aug 27 08:58:43 buddy systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Aug 27 08:58:43 buddy systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 27 08:58:43 buddy systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 27 08:58:43 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127 from 192.168.10.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 27
Aug 27 08:58:43 buddy sudo[1623]: pam_unix(sudo:session): session closed for user root
Aug 27 08:58:43 buddy volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28
Aug 27 08:58:43 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127:3000 from 192.168.10.63 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29
Aug 27 08:58:43 buddy volumio[1249]: info: Upmpdcli Daemon Started
Aug 27 08:58:43 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:44 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:44 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Aug 27 08:58:44 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:44 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:44 buddy volumio[1249]: info: CoreCommandRouter::volumioGetState
Aug 27 08:58:44 buddy volumio[1249]: info: CorePlayQueue::getTrack 0
Aug 27 08:58:45 buddy volumio[1249]: info: Discovery: Connected to remote: 192.168.10.67
Aug 27 08:58:45 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:49 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:49 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:49 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:49 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:58:50 buddy upmpdcli[1686]: writing RSA key
Aug 27 08:58:59 buddy systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Aug 27 08:59:00 buddy systemd[1]: setdatetime-helper.service: Deactivated successfully.
Aug 27 08:59:00 buddy systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 08:59:02 buddy volumio[1249]: verbose: New Socket.io Connection to 192.168.10.127:3000 from 192.168.10.67 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30
Aug 27 08:59:02 buddy volumio[1249]: error: MyVolumio Plugin failed to start in a timely fashion
Aug 27 08:59:02 buddy volumio[1249]: [Metrics] CommandRouter: 39s 532.00ms
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::volumiosetStartupVolume
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::Close All Modals sent
Aug 27 08:59:02 buddy volumio[1249]: info: CoreCommandRouter::Close All Modals sent
Aug 27 08:59:02 buddy volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 08:59:02 buddy volumio[1249]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
Aug 27 08:59:02 buddy volumio[1249]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
Aug 27 08:59:02 buddy volumio[1249]: at new NodeError (node:internal/errors:405:5)
Aug 27 08:59:02 buddy volumio[1249]: at assert (node:internal/assert:14:11)
Aug 27 08:59:02 buddy volumio[1249]: at internalConnectMultiple (node:net:1118:3)
Aug 27 08:59:02 buddy volumio[1249]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
Aug 27 08:59:02 buddy volumio[1249]: at listOnTimeout (node:internal/timers:575:11)
Aug 27 08:59:02 buddy volumio[1249]: at process.processTimers (node:internal/timers:514:7) {
Aug 27 08:59:02 buddy volumio[1249]: code: 'ERR_INTERNAL_ASSERTION'
Aug 27 08:59:02 buddy volumio[1249]: }
Aug 27 08:59:02 buddy volumio[1249]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 08:59:05 buddy sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 08:58'
Aug 27 08:59:05 buddy sudo[1751]: 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"