Apr 15 09:28:27 volumio ntpd[1072]: CLOCK: time stepped by 75.576134
Apr 15 09:28:27 volumio ntpd[1072]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Apr 15 09:28:27 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 09:28:27 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:28:27 volumio volumio[1240]: info: EVO SABRE : Starting Plugin
Apr 15 09:28:27 volumio volumio[1240]: info: Loading i18n strings for locale fr
Apr 15 09:28:27 volumio volumio[1240]: info: Volumio Calling Home
Apr 15 09:28:28 volumio volumio[1451]: Starting albumart workers
Apr 15 09:28:28 volumio volumio[1452]: Starting albumart workers
Apr 15 09:28:29 volumio volumio[1453]: Starting albumart workers
Apr 15 09:28:30 volumio volumio[1240]: info: Discovery: adding 791bad7a-1c7f-495c-bae3-0282764c16ab
Apr 15 09:28:30 volumio volumio[1240]: info: Discovery: Found device Volumio
Apr 15 09:28:30 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:30 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:31 volumio volumio[1240]: info: Discovery: this is already registered, 791bad7a-1c7f-495c-bae3-0282764c16ab
Apr 15 09:28:31 volumio volumio[1240]: info: Discovery: Found device Volumio
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:31 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:31 volumio volumio[1240]: info: Volumio Network Manager: Network status updated: 1
Apr 15 09:28:31 volumio volumio[1240]: info: MPD Permissions set
Apr 15 09:28:31 volumio volumio[1240]: info: MPD Permissions set
Apr 15 09:28:31 volumio sudo[1579]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 15 09:28:31 volumio sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=LENAS2tournoulous,password=1CHEVAL2troisET1*2nuitJ58E-Z~s|xPp*,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft '//192.168.1.10/Beber-et-Vevel/RV/RV----Musique\\Music Taggées' /mnt/NAS/Music
Apr 15 09:28:31 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:31 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:31 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:31 volumio sudo[1581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Apr 15 09:28:31 volumio sudo[1577]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 15 09:28:31 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:31 volumio sudo[1581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:31 volumio kernel: CIFS: Attempting to mount //192.168.1.10/Beber-et-Vevel/RV/RV----Musique/Music Taggées
Apr 15 09:28:31 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.21 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2
Apr 15 09:28:31 volumio volumio[1240]: info: Volumio called home
Apr 15 09:28:31 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:31 volumio systemd[1]: Reloading.
Apr 15 09:28:31 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:31 volumio mpd[1538]: 2026-04-15T09:28:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 15 09:28:31 volumio volumio[1240]: 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
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:32 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Apr 15 09:28:32 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:32 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:32 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:32 volumio volumio[1240]: 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
Apr 15 09:28:32 volumio volumio[1240]: info: Received Get System Info
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:28:32 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:32 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:28:32 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:32.773Z level=INFO msg="system info for 334a0def4c56258c0fecce6ea91c5b1d" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119
Apr 15 09:28:32 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:32.781Z level=INFO msg="bootstrapping state" hasInternet=true
Apr 15 09:28:32 volumio volumio[1240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Apr 15 09:28:32 volumio volumio[1240]: info: Received Get System Info
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:28:32 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:32 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:32 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:28:33 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.21 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5
Apr 15 09:28:33 volumio volumio[1240]: info: EVO SABRE : systemd cmd daemon-reload : success
Apr 15 09:28:33 volumio sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service
Apr 15 09:28:33 volumio volumio-remote-updater[837]: Test mode disabled
Apr 15 09:28:33 volumio volumio-remote-updater[837]: Alpha mode disabled
Apr 15 09:28:33 volumio volumio-remote-updater[837]: Alpha legacy test mode disabled
Apr 15 09:28:33 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:33 volumio volumio[1240]: info: Starting Shairport Sync
Apr 15 09:28:33 volumio volumio[1240]: info: Starting Shairport Sync
Apr 15 09:28:33 volumio volumio[1240]: info: Starting Shairport Sync
Apr 15 09:28:33 volumio sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 09:28:33 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:33 volumio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 09:28:33 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:33 volumio systemd[1]: Started evo_oled2.service - OLED Display Service for EVO SABRE.
Apr 15 09:28:33 volumio sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 09:28:33 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 09:28:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 09:28:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio systemd[1]: shairport-sync.service: Consumed 1.760s CPU time.
Apr 15 09:28:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 09:28:33 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 09:28:33 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 09:28:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 09:28:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio volumio[1240]: error: MPD error: The expression evaluated to a falsy value:
Apr 15 09:28:33 volumio volumio[1240]: assert.ok(self.idling)
Apr 15 09:28:33 volumio volumio[1240]: error: The expression evaluated to a falsy value:
Apr 15 09:28:33 volumio volumio[1240]: assert.ok(self.idling)
Apr 15 09:28:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 09:28:33 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:33 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Apr 15 09:28:33 volumio sudo[1719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 15 09:28:33 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:33 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:28:33 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:28:33 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:28:33 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:33 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:33 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:28:33 volumio volumio[1240]: error: updateQueue error: null
Apr 15 09:28:34 volumio volumio[1240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Apr 15 09:28:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 15 09:28:34 volumio volumio[1240]: info: Shairport-Sync Started
Apr 15 09:28:34 volumio volumio[1240]: Error adding Membership: Error: addMembership EINVAL
Apr 15 09:28:34 volumio volumio[1240]: info: Shairport-Sync Started
Apr 15 09:28:34 volumio volumio[1240]: info: Shairport-Sync Started
Apr 15 09:28:34 volumio volumio[1240]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success
Apr 15 09:28:34 volumio volumio[1240]: info: MPD running with PID1538
Apr 15 09:28:34 volumio volumio[1240]: ,establishing connection
Apr 15 09:28:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 09:28:34 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:34.510Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory"
Apr 15 09:28:34 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:34.511Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory"
Apr 15 09:28:34 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:34.511Z 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"
Apr 15 09:28:34 volumio sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service
Apr 15 09:28:34 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 09:28:34 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.21 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5
Apr 15 09:28:34 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool...
Apr 15 09:28:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 09:28:35 volumio volumio[1240]: error: updateQueue error: null
Apr 15 09:28:35 volumio volumio[1240]: info: Received Get System Info
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:28:35 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:35 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 15 09:28:35 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:35.263Z level=INFO msg="enabling local network discovery"
Apr 15 09:28:35 volumio lircd-setup[1725]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead.
Apr 15 09:28:35 volumio lircd-setup[1725]: parser = configparser.SafeConfigParser()
Apr 15 09:28:35 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:35.295Z level=INFO msg="enabling BLE discovery"
Apr 15 09:28:35 volumio systemd[1]: lircd-setup.service: Deactivated successfully.
Apr 15 09:28:35 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool.
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:35 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:28:35 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:35 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:35 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:28:35 volumio systemd[1]: Started evo_remote.service.
Apr 15 09:28:35 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: lircd: Opening log, level: Info
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Version: lircd 0.10.1
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: System info: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l GNU/Linux
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: Initial device: /dev/lirc0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: 'lirc' written to protocols file /sys/class/rc/rc2/protocols
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: Initial device: /dev/lirc0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: [lirc] protocol is enabled
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: driver: default
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: output: /var/run/lirc/lircd
Apr 15 09:28:35 volumio lircd[1733]: lircd-0.10.1[1733]: Info: lircd: Opening log, level: Info
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: nodaemon: 1
Apr 15 09:28:35 volumio lircd[1733]: lircd-0.10.1[1733]: Warning: Running as root
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: logfile: syslog
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: immediate-init: 0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: permission: 666
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: driver-options:
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: pidfile: /var/run/lirc/lircd.pid
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: listen: 0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: connect: (null)
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: userelease: 0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: effective_user: (null)
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: release_suffix: _EVUP
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: allow_simulate: 0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: repeat_max: 600
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Options: dynamic_codes: (null)
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Current driver: default
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Driver API version: 3
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Driver version: 0.10.0
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html
Apr 15 09:28:35 volumio lircd[1733]: lircd-0.10.1[1733]: Info: Using remote: ApEvo.
Apr 15 09:28:35 volumio lircd[1733]: lircd-0.10.1[1733]: Notice: lircd(default) ready, using /var/run/lirc/lircd
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: lircd: Opening log, level: Info
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Warning: Running as root
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Info: Using remote: ApEvo.
Apr 15 09:28:35 volumio lircd-0.10.1[1733]: Notice: lircd(default) ready, using /var/run/lirc/lircd
Apr 15 09:28:35 volumio volumio[1240]: info: EVO SABRE : systemd cmd restart evo_remote.service : success
Apr 15 09:28:35 volumio sudo[1752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service
Apr 15 09:28:35 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:28:35 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool...
Apr 15 09:28:36 volumio lircd-setup[1754]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead.
Apr 15 09:28:36 volumio lircd-setup[1754]: parser = configparser.SafeConfigParser()
Apr 15 09:28:36 volumio systemd[1]: lircd-setup.service: Deactivated successfully.
Apr 15 09:28:36 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool.
Apr 15 09:28:36 volumio systemd[1]: Started evo_irexec.service.
Apr 15 09:28:36 volumio lircd[1733]: lircd-0.10.1[1733]: Notice: accepted new client on /var/run/lirc/lircd
Apr 15 09:28:36 volumio lircd-0.10.1[1733]: Notice: accepted new client on /var/run/lirc/lircd
Apr 15 09:28:36 volumio lircd[1733]: lircd-0.10.1[1733]: Info: [lirc] protocol is enabled
Apr 15 09:28:36 volumio lircd-0.10.1[1733]: Info: [lirc] protocol is enabled
Apr 15 09:28:36 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:36 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:36.265Z level=INFO msg="service successfully established" component=discovery/localnet
Apr 15 09:28:36 volumio volumio[1240]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success
Apr 15 09:28:36 volumio volumio[1240]: info: Completed starting Core Plugins
Apr 15 09:28:36 volumio volumio[1240]: info: -------------------------------------------
Apr 15 09:28:36 volumio volumio[1240]: info: ----- MyVolumio plugins startup ----
Apr 15 09:28:36 volumio volumio[1240]: info: -------------------------------------------
Apr 15 09:28:36 volumio volumio[1240]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 15 09:28:36 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Apr 15 09:28:36 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 15 09:28:36 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 15 09:28:36 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Apr 15 09:28:36 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root
Apr 15 09:28:37 volumio volumio[1240]: info: Upmpdcli Daemon Started
Apr 15 09:28:37 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:37.110Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.21:54090
Apr 15 09:28:38 volumio volumio[1240]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 15 09:28:41 volumio bluealsa[988]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_7D_72_8A_36_03, ...)
Apr 15 09:28:44 volumio volumio[1240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Apr 15 09:28:44 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:44.133Z level=INFO msg="new address was allocated" component=ble/conn old=1 new=2
Apr 15 09:28:44 volumio dbus-daemon[812]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1518 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=855 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Apr 15 09:28:49 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Apr 15 09:28:50 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:50.282Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.21:54090
Apr 15 09:28:50 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:50.282Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.21:54090
Apr 15 09:28:50 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:50.287Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.21:54102
Apr 15 09:28:51 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Apr 15 09:28:51 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Apr 15 09:28:51 volumio dbus-daemon[812]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1518 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=855 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Apr 15 09:28:56 volumio volumio[1240]: error: MyVolumio Plugin failed to start in a timely fashion
Apr 15 09:28:56 volumio volumio[1240]: [Metrics] CommandRouter: 40s 472.83ms
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 09:28:56 volumio volumio[1240]: info: VolumeController:: Setting startup Volume 100
Apr 15 09:28:56 volumio volumio[1240]: info: VolumeController::SetAlsaVolume100
Apr 15 09:28:56 volumio upmpdcli[1834]: writing RSA key
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::Close All Modals sent
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::Close All Modals sent
Apr 15 09:28:56 volumio volumio[1240]: info: CoreStateMachine::pushState
Apr 15 09:28:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 15 09:28:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:28:58 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue
Apr 15 09:28:58 volumio volumio[1240]: info: CoreStateMachine::getQueue
Apr 15 09:28:58 volumio volumio[1240]: info: CorePlayQueue::getQueue
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 15 09:28:58 volumio volumio[1240]: info: Version has changed, forcing UI Reload
Apr 15 09:28:58 volumio volumio[1240]: info: CoreCommandRouter::Reload Ui
Apr 15 09:28:59 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:59.219Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.21:54102 @ 0x21b9080" latency=35.67286ms timeout=10s
Apr 15 09:28:59 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:59.220Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080"
Apr 15 09:28:59 volumio volumio5-onboarding[1518]: time=2026-04-15T09:28:59.220Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.21:54102 @ 0x21b9080" latency=45.524415ms platform=PLATFORM_ANDROID version=5.260331.0
Apr 15 09:29:00 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:29:00 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:29:01 volumio volumio[1240]: info: Received Get System Info
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:29:01 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:29:01 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 15 09:29:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 15 09:29:01 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:01.687Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" name=Volumio
Apr 15 09:29:01 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:01.691Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" language=fr
Apr 15 09:29:02 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Apr 15 09:29:02 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:02.928Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" timezone=Atlantic/Reykjavik
Apr 15 09:29:02 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:02.929Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" available=true connected=true macAddress=d8:3a:dd:2c:93:0f ip4Address=192.168.1.20/24 ip6Address=
Apr 15 09:29:02 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:02.935Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Apr 15 09:29:02 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:02.939Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080" setupComplete=true
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin multiroom to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin qobuzconnect to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 15 09:29:03 volumio volumio[1240]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 15 09:29:04 volumio volumio[1240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 15 09:29:04 volumio volumio[1240]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 15 09:29:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:29:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:29:04 volumio volumio[1240]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 15 09:29:04 volumio volumio[1240]: info: MyVolumio login type: Token
Apr 15 09:29:04 volumio volumio[1240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 15 09:29:04 volumio volumio[1240]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 15 09:29:07 volumio volumio[1240]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 15 09:29:07 volumio volumio[1240]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 15 09:29:07 volumio volumio[1240]: info: Streaming services startup
Apr 15 09:29:07 volumio volumio[1240]: info: Starting Streaming Daemon
Apr 15 09:29:07 volumio sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 15 09:29:07 volumio volumio[1240]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 15 09:29:07 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 09:29:07 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root
Apr 15 09:29:07 volumio volumio[1240]: info: BOOT COMPLETED
Apr 15 09:29:09 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:09.229Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.21:54102 @ 0x21b9080" latency=35.67286ms timeout=10s error="failed to get output devices: could not get extended output devices: context deadline exceeded"
Apr 15 09:29:09 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:09.434Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.21:54102 @ 0x21b9080" latency=34.697947ms timeout=10s
Apr 15 09:29:09 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:09.434Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.21:54102 @ 0x21b9080"
Apr 15 09:29:09 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:09.434Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.21:54102 @ 0x21b9080" latency=35.705262ms platform=PLATFORM_ANDROID version=5.260331.0
Apr 15 09:29:12 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:12.220Z level=INFO msg="new address was allocated" component=ble/conn old=2 new=3
Apr 15 09:29:12 volumio dbus-daemon[812]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1518 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=855 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Apr 15 09:29:19 volumio volumio5-onboarding[1518]: time=2026-04-15T09:29:19.435Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.21:54102 @ 0x21b9080" latency=34.697947ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded"
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 15 09:29:22 volumio volumio[1240]: info: Received Get System Info
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 09:29:22 volumio volumio[1240]: info: Discovery: Getting this device information
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState
Apr 15 09:29:22 volumio volumio[1240]: info: CorePlayQueue::getTrack 0
Apr 15 09:29:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 09:29:22 volumio volumio[1240]: amixer -c 0 info | grep "bcm2835 ALSA"
Apr 15 09:29:22 volumio volumio[1240]: amixer -c 1 info | grep "bcm2835 Headphones"
Apr 15 09:29:22 volumio volumio[1240]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones'
Apr 15 09:29:22 volumio volumio[1240]: amixer -c 2 info | grep "vc4-hdmi-0"
Apr 15 09:29:22 volumio volumio[1240]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0'
Apr 15 09:29:22 volumio volumio[1240]: amixer -c 3 info | grep "vc4-hdmi-1"
Apr 15 09:29:23 volumio volumio[1240]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1'
Apr 15 09:29:23 volumio volumio[1240]: error: Cannot start Volumio Streaming Daemon
Apr 15 09:29:23 volumio volumio[1240]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 15 09:29:23 volumio volumio[1240]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 15 09:29:23 volumio volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 09:29:23 volumio volumio[1240]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
Apr 15 09:29:23 volumio volumio[1240]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
Apr 15 09:29:23 volumio volumio[1240]: at new NodeError (node:internal/errors:405:5)
Apr 15 09:29:23 volumio volumio[1240]: at assert (node:internal/assert:14:11)
Apr 15 09:29:23 volumio volumio[1240]: at internalConnectMultiple (node:net:1118:3)
Apr 15 09:29:23 volumio volumio[1240]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
Apr 15 09:29:23 volumio volumio[1240]: at listOnTimeout (node:internal/timers:575:11)
Apr 15 09:29:23 volumio volumio[1240]: at process.processTimers (node:internal/timers:514:7) {
Apr 15 09:29:23 volumio volumio[1240]: code: 'ERR_INTERNAL_ASSERTION'
Apr 15 09:29:23 volumio volumio[1240]: }
Apr 15 09:29:23 volumio volumio[1240]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 09:29:28 volumio sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-15 09:28'
Apr 15 09:29:28 volumio sudo[1935]: 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"