Feb 09 10:11:57 test4.095 ntpd[1038]: CLOCK: time stepped by 348060.347044
Feb 09 10:11:57 test4.095 ntpd[1038]: CLOCK: time changed from 2026-02-05 to 2026-02-09
Feb 09 10:11:57 test4.095 ntpd[1038]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Feb 09 10:11:57 test4.095 systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities...
Feb 09 10:11:57 test4.095 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Feb 09 10:11:57 test4.095 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Feb 09 10:11:57 test4.095 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Feb 09 10:11:57 test4.095 systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Feb 09 10:11:57 test4.095 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Feb 09 10:11:57 test4.095 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Feb 09 10:11:57 test4.095 systemd[1]: e2scrub_all.service: Deactivated successfully.
Feb 09 10:11:57 test4.095 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Feb 09 10:11:58 test4.095 sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 09 10:11:58 test4.095 sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:11:58 test4.095 sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 09 10:11:58 test4.095 sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=camosnas,password=dydtks84082555,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.4/music /mnt/NAS/NAS
Feb 09 10:11:58 test4.095 sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:11:58 test4.095 sudo[1532]: pam_unix(sudo:session): session closed for user root
Feb 09 10:11:58 test4.095 sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:11:58 test4.095 sudo[1538]: pam_unix(sudo:session): session closed for user root
Feb 09 10:11:58 test4.095 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
Feb 09 10:11:58 test4.095 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
Feb 09 10:11:58 test4.095 kernel: CIFS: Attempting to mount //192.168.0.4/music
Feb 09 10:11:58 test4.095 systemd[1]: apt-daily-upgrade.service: Deactivated successfully.
Feb 09 10:11:58 test4.095 systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities.
Feb 09 10:11:59 test4.095 volumio[1141]: info: Volumio Network Manager: Network status updated: 1
Feb 09 10:11:59 test4.095 volumio[1141]: info: MPD Permissions set
Feb 09 10:11:59 test4.095 sudo[1528]: pam_unix(sudo:session): session closed for user root
Feb 09 10:11:59 test4.095 volumio[1141]: info: MPD Permissions set
Feb 09 10:11:59 test4.095 volumio[1141]: 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: 3
Feb 09 10:11:59 test4.095 mpd[1458]: 2026-02-09T10:11:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 09 10:11:59 test4.095 volumio[1141]: info: Volumio called home
Feb 09 10:11:59 test4.095 systemd[1]: Started mpd.service - Music Player Daemon.
Feb 09 10:11:59 test4.095 sudo[1441]: pam_unix(sudo:session): session closed for user root
Feb 09 10:11:59 test4.095 sudo[1427]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:00 test4.095 volumio[1141]: 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: 3
Feb 09 10:12:00 test4.095 volumio[1141]: info: Received Get System Info
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: Getting this device information
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:00 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 09 10:12:00 test4.095 volumio5-onboarding[1455]: time=2026-02-09T10:12:00.176+09:00 level=INFO msg="system info for 8b94011087542ec410ac63c7bcba0e0b" deviceName=TEST4.095 deviceVariant=volumio deviceModel= softwareVersion=4.095
Feb 09 10:12:00 test4.095 volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: A device disappeared from network
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1350]: Starting albumart workers
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:00 test4.095 volumio[1141]: info: Received Get System Info
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: Getting this device information
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:00 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 09 10:12:00 test4.095 volumio[1141]: info: Completed starting Core Plugins
Feb 09 10:12:00 test4.095 volumio[1141]: info: -------------------------------------------
Feb 09 10:12:00 test4.095 volumio[1141]: info: ----- MyVolumio plugins startup ----
Feb 09 10:12:00 test4.095 volumio[1141]: info: -------------------------------------------
Feb 09 10:12:00 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: A device disappeared from network
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 09 10:12:00 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 09 10:12:00 test4.095 volumio[1141]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:00 test4.095 volumio[1141]: info: Discovery: A device disappeared from network
Feb 09 10:12:00 test4.095 volumio5-onboarding[1455]: time=2026-02-09T10:12:00.981+09:00 level=INFO msg="enabling local network discovery"
Feb 09 10:12:01 test4.095 volumio5-onboarding[1455]: time=2026-02-09T10:12:01.035+09:00 level=INFO msg="bootstrapping state" hasInternet=true
Feb 09 10:12:01 test4.095 volumio[1141]: error: MPD error: The expression evaluated to a falsy value:
Feb 09 10:12:01 test4.095 volumio[1141]: assert.ok(self.idling)
Feb 09 10:12:01 test4.095 volumio[1141]: error: The expression evaluated to a falsy value:
Feb 09 10:12:01 test4.095 volumio[1141]: assert.ok(self.idling)
Feb 09 10:12:01 test4.095 volumio[1141]: info: MPD running with PID1458
Feb 09 10:12:01 test4.095 volumio[1141]: ,establishing connection
Feb 09 10:12:01 test4.095 volumio[1141]: info: Starting Shairport Sync
Feb 09 10:12:01 test4.095 volumio[1141]: info: Starting Shairport Sync
Feb 09 10:12:01 test4.095 sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 09 10:12:01 test4.095 sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:12:01 test4.095 volumio[1141]: info: Starting Shairport Sync
Feb 09 10:12:01 test4.095 volumio[1141]: error: updateQueue error: null
Feb 09 10:12:01 test4.095 volumio[1141]: info: Received Get System Info
Feb 09 10:12:01 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 09 10:12:01 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 09 10:12:01 test4.095 volumio[1141]: info: Discovery: Getting this device information
Feb 09 10:12:01 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:01 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:01 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 09 10:12:01 test4.095 sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 09 10:12:01 test4.095 sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 09 10:12:01 test4.095 sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:12:01 test4.095 sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:12:01 test4.095 volumio-remote-updater[746]: Test mode disabled
Feb 09 10:12:01 test4.095 volumio-remote-updater[746]: Alpha mode enabled
Feb 09 10:12:01 test4.095 volumio-remote-updater[746]: Alpha legacy test mode disabled
Feb 09 10:12:01 test4.095 sudo[1620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 09 10:12:01 test4.095 sudo[1620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:12:01 test4.095 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 09 10:12:01 test4.095 systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 09 10:12:01 test4.095 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 09 10:12:01 test4.095 systemd[1]: shairport-sync.service: Consumed 1.709s CPU time.
Feb 09 10:12:01 test4.095 volumio[1141]: error: updateQueue error: null
Feb 09 10:12:01 test4.095 volumio[1141]: info: Discovery: Connected to remote: 192.168.0.27
Feb 09 10:12:01 test4.095 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 09 10:12:01 test4.095 sudo[1616]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:01 test4.095 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 09 10:12:01 test4.095 systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 09 10:12:01 test4.095 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 09 10:12:01 test4.095 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 09 10:12:01 test4.095 sudo[1618]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:01 test4.095 sudo[1620]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:01 test4.095 volumio[1141]: info: Shairport-Sync Started
Feb 09 10:12:01 test4.095 volumio[1141]: Error adding Membership: Error: addMembership EINVAL
Feb 09 10:12:01 test4.095 volumio[1141]: info: Shairport-Sync Started
Feb 09 10:12:01 test4.095 volumio[1141]: info: Shairport-Sync Started
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: adding 3934e070-cc7f-4489-9dce-c07f1675cd0f
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: Found device TEST4.095
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:02 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: this is already registered, 3934e070-cc7f-4489-9dce-c07f1675cd0f
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: Found device TEST4.095
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:02 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetState
Feb 09 10:12:02 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:02 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:02 test4.095 volumio[1141]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for CPU utilization spike
\n- Fix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\n- Add BLE onboarding plugin
\n- Enable vc4-kms-v3d for all compatible RPi models
\n- Update Tidal SDK
\n
\n","title":"Update v4.096","updateavailable":true}
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 09 10:12:02 test4.095 volumio5-onboarding[1455]: time=2026-02-09T10:12:02.764+09:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory"
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 09 10:12:02 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 09 10:12:03 test4.095 volumio[1141]: info: Discovery: Connected to remote: 192.168.0.27
Feb 09 10:12:03 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:03 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:03 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Connected to remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Disconnected from remote: 192.168.0.27
Feb 09 10:12:05 test4.095 systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 09 10:12:05 test4.095 volumio[1141]: info: Discovery: Connected to remote: 192.168.0.27
Feb 09 10:12:05 test4.095 volumio[1141]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:07 test4.095 volumio[1141]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 09 10:12:07 test4.095 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 09 10:12:07 test4.095 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 09 10:12:08 test4.095 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 09 10:12:08 test4.095 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Feb 09 10:12:08 test4.095 sudo[1613]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:13 test4.095 volumio[1141]: info: Upmpdcli Daemon Started
Feb 09 10:12:13 test4.095 volumio[1141]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:20 test4.095 volumio[1141]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:29 test4.095 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 09 10:12:29 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 09 10:12:29 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:29 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 09 10:12:30 test4.095 volumio[1141]: error: MyVolumio Plugin failed to start in a timely fashion
Feb 09 10:12:30 test4.095 volumio[1141]: [Metrics] CommandRouter: 45s 97.65ms
Feb 09 10:12:30 test4.095 volumio[1141]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 09 10:12:30 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 10:12:30 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:30 test4.095 volumio[1141]: info: CoreCommandRouter::Close All Modals sent
Feb 09 10:12:30 test4.095 volumio[1141]: info: CoreCommandRouter::Close All Modals sent
Feb 09 10:12:30 test4.095 volumio[1141]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:31 test4.095 systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 09 10:12:31 test4.095 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 09 10:12:32 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 09 10:12:32 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 09 10:12:32 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 09 10:12:35 test4.095 kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1
Feb 09 10:12:35 test4.095 kernel: xhci_hcd 0000:01:00.0: @000000040cd06260 00000000 00000000 0e000000 02028000
Feb 09 10:12:36 test4.095 volumio[1141]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 09 10:12:37 test4.095 volumio[1141]: info: BOOT COMPLETED
Feb 09 10:12:39 test4.095 volumio[1141]: info: Discovery: adding 1fa73ef4-44b6-4b2e-bed0-cbcee8e4b632
Feb 09 10:12:39 test4.095 volumio[1141]: info: Discovery: Found device Rpi5-8GB M.2Nvme
Feb 09 10:12:42 test4.095 volumio[1141]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = ready
Feb 09 10:12:42 test4.095 volumio[1141]: info: AutoStart - System ready state CONFIRMED after 8 checks
Feb 09 10:12:42 test4.095 volumio[1141]: info: AutoStart - Applying additional delay of 1000ms before playback
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin multiroom to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 09 10:12:43 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 09 10:12:47 test4.095 volumio[1141]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 09 10:12:47 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 09 10:12:47 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:47 test4.095 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 10:12:47 test4.095 volumio[1141]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 09 10:12:47 test4.095 volumio[1141]: info: MyVolumio login type: Token
Feb 09 10:12:47 test4.095 volumio[1141]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 09 10:12:47 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 09 10:12:50 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 09 10:12:50 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 09 10:12:50 test4.095 volumio[1141]: info: Streaming services startup
Feb 09 10:12:50 test4.095 volumio[1141]: info: Starting Streaming Daemon
Feb 09 10:12:50 test4.095 sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 09 10:12:50 test4.095 sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 10:12:50 test4.095 volumio[1141]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 09 10:12:50 test4.095 sudo[1770]: pam_unix(sudo:session): session closed for user root
Feb 09 10:12:50 test4.095 volumio[1141]: info: AutoStart - startPlayback called
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreCommandRouter::volumioGetQueue
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::getQueue
Feb 09 10:12:50 test4.095 volumio[1141]: info: CorePlayQueue::getQueue
Feb 09 10:12:50 test4.095 volumio[1141]: info: AutoStart - Queue has 1 items
Feb 09 10:12:50 test4.095 volumio[1141]: info: AutoStart - Playing from position 0
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreCommandRouter::volumioPlay
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::play index 0
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::stop
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::play index undefined
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 09 10:12:50 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:50 test4.095 volumio[1141]: info: CoreStateMachine::startPlaybackTimer
Feb 09 10:12:50 test4.095 volumio[1141]: info: CorePlayQueue::getTrack 0
Feb 09 10:12:50 test4.095 volumio[1141]: info: [1770599570593] ControllerWebradio::clearAddPlayTrack
Feb 09 10:12:50 test4.095 volumio[1141]: verbose: ControllerMpd::sendMpdCommand stop
Feb 09 10:12:50 test4.095 volumio[1141]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 09 10:12:50 test4.095 volumio[1141]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
Feb 09 10:12:50 test4.095 volumio[1141]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
Feb 09 10:12:50 test4.095 volumio[1141]: at new NodeError (node:internal/errors:405:5)
Feb 09 10:12:50 test4.095 volumio[1141]: at assert (node:internal/assert:14:11)
Feb 09 10:12:50 test4.095 volumio[1141]: at internalConnectMultiple (node:net:1118:3)
Feb 09 10:12:50 test4.095 volumio[1141]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
Feb 09 10:12:50 test4.095 volumio[1141]: at listOnTimeout (node:internal/timers:575:11)
Feb 09 10:12:50 test4.095 volumio[1141]: at process.processTimers (node:internal/timers:514:7) {
Feb 09 10:12:50 test4.095 volumio[1141]: code: 'ERR_INTERNAL_ASSERTION'
Feb 09 10:12:50 test4.095 volumio[1141]: }
Feb 09 10:12:50 test4.095 volumio[1141]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 09 10:12:53 test4.095 sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 10:11'
Feb 09 10:12:53 test4.095 sudo[1790]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Jan 29 19:15:15 UTC 2026"
VOLUMIO_VERSION="4.095"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="936289d7e6112f7d71b395242f2871e4"