May 30 19:35:49 volumio-cuisine ntpd[903]: CLOCK: time stepped by 1419702.298076 May 30 19:35:49 volumio-cuisine ntpd[903]: CLOCK: time changed from 2026-05-14 to 2026-05-30 May 30 19:35:49 volumio-cuisine ntpd[903]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 30 19:35:49 volumio-cuisine systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 30 19:35:49 volumio-cuisine systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 30 19:35:49 volumio-cuisine systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 30 19:35:49 volumio-cuisine systemd[1]: e2scrub_all.service: Deactivated successfully. May 30 19:35:49 volumio-cuisine systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 30 19:35:49 volumio-cuisine systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 30 19:35:49 volumio-cuisine systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 30 19:35:50 volumio-cuisine systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "network"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: Refreshing Cached IP Addresses May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "services"... May 30 19:35:51 volumio-cuisine sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 19:35:51 volumio-cuisine sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "volumio5onboarding"... May 30 19:35:51 volumio-cuisine sudo[1413]: pam_unix(sudo:session): session closed for user root May 30 19:35:51 volumio-cuisine sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 19:35:51 volumio-cuisine sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "alsa_controller"... May 30 19:35:51 volumio-cuisine sudo[1414]: pam_unix(sudo:session): session closed for user root May 30 19:35:51 volumio-cuisine sudo[1417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 30 19:35:51 volumio-cuisine sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:35:51 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "wizard"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "networkfs"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: Starting Udev Watcher for removable devices May 30 19:35:51 volumio-cuisine sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=V0lumio!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.59/Musique /mnt/NAS/Nas May 30 19:35:51 volumio-cuisine sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:35:51 volumio-cuisine volumio[1232]: info: Ignoring mount for partition: boot May 30 19:35:51 volumio-cuisine volumio[1232]: info: Ignoring mount for partition: volumio May 30 19:35:51 volumio-cuisine volumio[1232]: info: Ignoring mount for partition: volumio_data May 30 19:35:51 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "volumio_command_line_client"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "upnp"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: [1780162551851] Starting Upmpd Daemon May 30 19:35:51 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "my_music"... May 30 19:35:51 volumio-cuisine volumio[1232]: info: Loading plugin "mpd"... May 30 19:35:51 volumio-cuisine kernel: netfs: FS-Cache loaded May 30 19:35:52 volumio-cuisine kernel: Key type cifs.spnego registered May 30 19:35:52 volumio-cuisine kernel: Key type cifs.idmap registered May 30 19:35:52 volumio-cuisine kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 30 19:35:52 volumio-cuisine kernel: CIFS: Attempting to mount //192.168.0.59/Musique May 30 19:35:52 volumio-cuisine volumio[1232]: info: Plugin upnp_browser is not enabled May 30 19:35:52 volumio-cuisine volumio[1232]: info: Loading plugin "alarm-clock"... May 30 19:35:53 volumio-cuisine volumio[1232]: info: Plugin airplay_emulation is not enabled May 30 19:35:53 volumio-cuisine volumio[1232]: info: Loading plugin "last_100"... May 30 19:35:53 volumio-cuisine volumio[1232]: info: Loading plugin "webradio"... May 30 19:35:54 volumio-cuisine sudo[1446]: pam_unix(sudo:session): session closed for user root May 30 19:35:54 volumio-cuisine systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 19:35:55 volumio-cuisine volumio[1232]: info: Loading plugin "i2s_dacs"... May 30 19:35:55 volumio-cuisine volumio[1232]: info: Loading plugin "volumiodiscovery"... May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** For more information see May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 19:35:55 volumio-cuisine volumio[1232]: *** WARNING *** For more information see May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** For more information see May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 19:35:55 volumio-cuisine node[1232]: *** WARNING *** For more information see May 30 19:35:55 volumio-cuisine volumio[1232]: info: Applying required configuration parameters for plugin volumiodiscovery May 30 19:35:55 volumio-cuisine volumio[1232]: info: Discovery: Started advertising with name: Volumio-cuisine May 30 19:35:55 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 19:35:55 volumio-cuisine volumio[1232]: info: Loading plugin "spop"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "outputs"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "albumart"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Plugin example_plugin is not enabled May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "inputs"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "updater_comm"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Plugin mpdemulation is not enabled May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "rest_api"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading plugin "websocket"... May 30 19:35:59 volumio-cuisine volumio[1232]: info: Starting Socket.io Server version 1.7.4 May 30 19:35:59 volumio-cuisine volumio[1232]: info: Loading i18n strings for locale fr May 30 19:35:59 volumio-cuisine volumio[1232]: Updating browse sources language May 30 19:35:59 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::initPlayerControls May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 19:36:00 volumio-cuisine volumio[1232]: Express server listening on port 3000 May 30 19:36:00 volumio-cuisine volumio[1232]: [Metrics] WebUI: 15s 616.10ms May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreStateMachine::resetVolumioState May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreStateMachine::getcurrentVolume May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioRetrievevolume May 30 19:36:00 volumio-cuisine volumio[1232]: info: Volumio Network Manager: Network status updated: 2 May 30 19:36:00 volumio-cuisine volumio[1232]: info: VolumeController:: Volume=100 Mute =false May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreStateMachine::pushState May 30 19:36:00 volumio-cuisine volumio[1500]: Forking 3 albumart workers May 30 19:36:00 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioPushState May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreStateMachine::updateTrackBlock May 30 19:36:00 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrackBlock May 30 19:36:00 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioRetrievevolume May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:01 volumio-cuisine volumio[1232]: info: Reloading queue from file May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreStateMachine::setRepeat null single undefined May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreStateMachine::pushState May 30 19:36:01 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioPushState May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreStateMachine::setRandom null May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreStateMachine::pushState May 30 19:36:01 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:01 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioPushState May 30 19:36:01 volumio-cuisine volumio[1232]: info: Setting Device type: Raspberry PI May 30 19:36:01 volumio-cuisine volumio[1232]: info: Completed loading Core Plugins May 30 19:36:01 volumio-cuisine volumio[1232]: info: Preparing to generate the ALSA configuration file May 30 19:36:01 volumio-cuisine systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 19:36:01 volumio-cuisine systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 19:36:01 volumio-cuisine systemd[1]: setdatetime-helper.service: Consumed 1.106s CPU time. May 30 19:36:02 volumio-cuisine volumio[1232]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 30 19:36:02 volumio-cuisine volumio[1232]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 May 30 19:36:02 volumio-cuisine volumio[1232]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 30 19:36:02 volumio-cuisine sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 19:36:02 volumio-cuisine sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:02 volumio-cuisine volumio[1232]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 19:36:02 volumio-cuisine volumio[1232]: info: Reading ALSA contributions from plugins. May 30 19:36:02 volumio-cuisine volumio[1232]: info: VolumeController:: Volume=100 Mute =false May 30 19:36:02 volumio-cuisine sudo[1576]: pam_unix(sudo:session): session closed for user root May 30 19:36:02 volumio-cuisine sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 19:36:02 volumio-cuisine sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:02 volumio-cuisine volumio[1232]: info: CoreStateMachine::pushState May 30 19:36:02 volumio-cuisine sudo[1578]: pam_unix(sudo:session): session closed for user root May 30 19:36:02 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:02 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioPushState May 30 19:36:02 volumio-cuisine systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 30 19:36:02 volumio-cuisine systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 19:36:02 volumio-cuisine volumio[1232]: info: Discovery: adding 2a75ad32-fb6a-4ee7-8685-e0af80afa0e3 May 30 19:36:02 volumio-cuisine volumio[1232]: info: Discovery: Found device Volumio-cuisine May 30 19:36:02 volumio-cuisine systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 19:36:02 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioGetState May 30 19:36:02 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:02 volumio-cuisine volumio[1232]: info: Discovery: this is already registered, 2a75ad32-fb6a-4ee7-8685-e0af80afa0e3 May 30 19:36:02 volumio-cuisine volumio[1232]: info: Discovery: Found device Volumio-cuisine May 30 19:36:02 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioGetState May 30 19:36:02 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:02 volumio-cuisine sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 30 19:36:02 volumio-cuisine sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:02 volumio-cuisine volumio[1232]: info: Asound.conf file unchanged, so no further update is needed May 30 19:36:02 volumio-cuisine volumio[1232]: info: Output device has changed, restarting MPD May 30 19:36:02 volumio-cuisine volumio[1232]: info: ___________ START PLUGINS ___________ May 30 19:36:03 volumio-cuisine sudo[1590]: pam_unix(sudo:session): session closed for user root May 30 19:36:03 volumio-cuisine sudo[1599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 19:36:03 volumio-cuisine sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 19:36:03 volumio-cuisine sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:03 volumio-cuisine sudo[1599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:03 volumio-cuisine volumio[1232]: info: ControllerMpd::onStart: Initializing MPD May 30 19:36:03 volumio-cuisine volumio[1232]: info: Creating MPD Configuration file May 30 19:36:03 volumio-cuisine sudo[1599]: pam_unix(sudo:session): session closed for user root May 30 19:36:03 volumio-cuisine systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 19:36:03 volumio-cuisine systemd[1]: Starting mpd.service - Music Player Daemon... May 30 19:36:03 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 19:36:03 volumio-cuisine volumio[1232]: info: [1780162563405] CoreMusicLibrary::Adding element Last_100 May 30 19:36:03 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:36:03 volumio-cuisine sudo[1605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 30 19:36:03 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 19:36:03 volumio-cuisine volumio[1232]: info: [1780162563549] CoreMusicLibrary::Adding element Webradio May 30 19:36:03 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:36:03 volumio-cuisine sudo[1605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:03 volumio-cuisine sudo[1607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 19:36:03 volumio-cuisine sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:03 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 19:36:03 volumio-cuisine sudo[1613]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 19:36:03 volumio-cuisine sudo[1610]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 19:36:03 volumio-cuisine sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 19:36:03 volumio-cuisine sudo[1610]: pam_unix(sudo:session): session closed for user root May 30 19:36:03 volumio-cuisine sudo[1607]: pam_unix(sudo:session): session closed for user root May 30 19:36:03 volumio-cuisine sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 19:36:03 volumio-cuisine volumio[1232]: info: Initializing BBC Radios May 30 19:36:03 volumio-cuisine sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:04 volumio-cuisine systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:04 volumio-cuisine sudo[1417]: pam_unix(sudo:session): session closed for user root May 30 19:36:04 volumio-cuisine sudo[1605]: pam_unix(sudo:session): session closed for user root May 30 19:36:04 volumio-cuisine systemd[1]: mpd.service: Deactivated successfully. May 30 19:36:04 volumio-cuisine systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 19:36:04 volumio-cuisine systemd[1]: mpd.socket: Deactivated successfully. May 30 19:36:04 volumio-cuisine systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 19:36:04 volumio-cuisine systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 19:36:04 volumio-cuisine volumio-remote-updater[730]: [2026-05-30 19:36:04] [connect] Successful connection May 30 19:36:04 volumio-cuisine systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 19:36:04 volumio-cuisine systemd[1]: Starting mpd.service - Music Player Daemon... May 30 19:36:04 volumio-cuisine sudo[1619]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 19:36:04 volumio-cuisine sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 19:36:04 volumio-cuisine sudo[1628]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 19:36:04 volumio-cuisine sudo[1619]: pam_unix(sudo:session): session closed for user root May 30 19:36:04 volumio-cuisine volumio5-onboarding[1617]: time=2026-05-30T19:36:04.783+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 30 19:36:04 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 19:36:04 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:05 volumio-cuisine volumio[1232]: info: Creating Spotify config file May 30 19:36:05 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:09 volumio-cuisine volumio[1232]: info: Volumio Calling Home May 30 19:36:12 volumio-cuisine mpd[1629]: 2026-05-30T19:36:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 19:36:12 volumio-cuisine volumio[1523]: Starting albumart workers May 30 19:36:12 volumio-cuisine volumio[1232]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 30 19:36:12 volumio-cuisine systemd[1]: Started mpd.service - Music Player Daemon. May 30 19:36:12 volumio-cuisine sudo[1611]: pam_unix(sudo:session): session closed for user root May 30 19:36:12 volumio-cuisine sudo[1601]: pam_unix(sudo:session): session closed for user root May 30 19:36:13 volumio-cuisine volumio[1232]: info: MPD Permissions set May 30 19:36:13 volumio-cuisine volumio[1232]: info: Completed starting Core Plugins May 30 19:36:13 volumio-cuisine volumio[1232]: info: ------------------------------------------- May 30 19:36:13 volumio-cuisine volumio[1232]: info: ----- MyVolumio plugins startup ---- May 30 19:36:13 volumio-cuisine volumio[1232]: info: ------------------------------------------- May 30 19:36:13 volumio-cuisine volumio[1232]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 19:36:13 volumio-cuisine volumio[1232]: info: MPD Permissions set May 30 19:36:13 volumio-cuisine volumio[1232]: info: Upmpdcli Daemon Started May 30 19:36:13 volumio-cuisine volumio[1517]: Starting albumart workers May 30 19:36:14 volumio-cuisine volumio[1232]: info: Volumio called home May 30 19:36:14 volumio-cuisine volumio[1232]: info: Spotify config file written May 30 19:36:14 volumio-cuisine sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 19:36:14 volumio-cuisine sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 19:36:14 volumio-cuisine systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 19:36:14 volumio-cuisine systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 19:36:14 volumio-cuisine volumio[1525]: Starting albumart workers May 30 19:36:14 volumio-cuisine systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 19:36:14 volumio-cuisine go-librespot[1669]: go-librespot daemon starting... May 30 19:36:14 volumio-cuisine sudo[1667]: pam_unix(sudo:session): session closed for user root May 30 19:36:14 volumio-cuisine volumio5-onboarding[1617]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:53986->127.0.0.1:3000: i/o timeout May 30 19:36:14 volumio-cuisine systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 30 19:36:14 volumio-cuisine systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 30 19:36:14 volumio-cuisine systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 30 19:36:14 volumio-cuisine systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:15 volumio-cuisine systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:15 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:15.057+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=info msg="running go-librespot 0.7.1" May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=debug msg="app state loaded" May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 19:36:15 volumio-cuisine volumio[1232]: error: MPD error: The expression evaluated to a falsy value: May 30 19:36:15 volumio-cuisine volumio[1232]: assert.ok(self.idling) May 30 19:36:15 volumio-cuisine volumio[1232]: error: The expression evaluated to a falsy value: May 30 19:36:15 volumio-cuisine volumio[1232]: assert.ok(self.idling) May 30 19:36:15 volumio-cuisine volumio[1232]: info: MPD running with PID1629 May 30 19:36:15 volumio-cuisine volumio[1232]: ,establishing connection May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=info msg="zeroconf server listening on port 36809" May 30 19:36:15 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:15+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:15 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="obtained new client token: AABfLZVRaoOiTspBSNiEBUkasrpMKcVUea4Y6dqQCKWjo3fvYu54bLicLR2G1UDTzMbqysFZGVEI14uLZnoNmPE59XMrYWRylgq995KlRQrD7GKP+DEsro8CqN9azZCeF1crYB7qzqR8CA2ZWNz3FrTBgq0BbODQ84+/giNE1FRHHA0SvipzD3VGStWX7r0dC0+fh/GbWAQYIUC7L4p9E4azUwCzEo1p8bs/fi5MrYOerLzfNQ==" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="completed keyexchange" May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:16 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="completed challenge" May 30 19:36:16 volumio-cuisine volumio[1232]: info: No need to fix Spotify hosts May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=info msg="authenticated AP" username="th***om" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=info msg="authenticated Login5" username="th***om" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="initializing zeroconf session" username="th***om" May 30 19:36:16 volumio-cuisine volumio[1232]: error: updateQueue error: null May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="dealer connection opened" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=trace msg="starting accesspoint recv loop" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=trace msg="starting dealer recv loop" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=trace msg="received accesspoint ping" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="received connection id: NTlhY2QwZGQtZmRk...NzVGMEI3ODVGQQ==" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=trace msg="received accesspoint pong ack" May 30 19:36:16 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:16+02:00" level=debug msg="put connect state because NEW_DEVICE" May 30 19:36:17 volumio-cuisine volumio[1232]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 30 19:36:17 volumio-cuisine volumio[1232]: info: New Spotify access tokenBQC4ksVo-R... May 30 19:36:17 volumio-cuisine volumio[1232]: info: Spotify credentials grant success - running version from March 24, 2019 May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.532+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.535+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.543+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.545+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.547+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.547+02:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" May 30 19:36:17 volumio-cuisine volumio[1232]: 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: 2 May 30 19:36:17 volumio-cuisine volumio[1232]: error: updateQueue error: null May 30 19:36:17 volumio-cuisine volumio[1232]: 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: 2 May 30 19:36:17 volumio-cuisine volumio[1232]: info: Received Get System Info May 30 19:36:17 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 19:36:17 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 19:36:17 volumio-cuisine volumio[1232]: info: Discovery: Getting this device information May 30 19:36:17 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioGetState May 30 19:36:17 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:17 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 19:36:17 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:17.985+02:00 level=INFO msg="system info for 0364d1eafc5df1a54c924528131f1b31" deviceName=Volumio-cuisine deviceVariant=volumio deviceModel= softwareVersion=4.119 May 30 19:36:18 volumio-cuisine volumio5-onboarding[1675]: time=2026-05-30T19:36:18.003+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 30 19:36:18 volumio-cuisine volumio[1232]: info: Received Get System Info May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 19:36:18 volumio-cuisine volumio[1232]: info: Discovery: Getting this device information May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioGetState May 30 19:36:18 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioGetState May 30 19:36:18 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:18 volumio-cuisine volumio[1232]: SPOTIFY: User informations: {"account_id":"SwAD8PffjW","country":"FR","display_name":"thibzom","email":"thib.mura68@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/thibzom"},"followers":{"href":null,"total":13},"href":"https://api.spotify.com/v1/users/thibzom","id":"thibzom","images":[],"product":"premium","type":"user","uri":"spotify:user:thibzom"} May 30 19:36:18 volumio-cuisine volumio[1232]: info: Spotify Successfully logged in May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 19:36:18 volumio-cuisine volumio[1232]: info: [1780162578992] CoreMusicLibrary::Adding element Spotify May 30 19:36:18 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:36:19 volumio-cuisine volumio[1232]: Cannot find translation for source Spotify May 30 19:36:19 volumio-cuisine volumio-remote-updater[730]: [2026-05-30 19:36:19] [connect] Successful connection May 30 19:36:19 volumio-cuisine volumio-remote-updater[730]: [2026-05-30 19:36:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780162579 101 May 30 19:36:19 volumio-cuisine volumio[1232]: 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: 3 May 30 19:36:20 volumio-cuisine volumio[1232]: info: go-librespot daemon successfully initialized May 30 19:36:20 volumio-cuisine volumio[1232]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 30 19:36:23 volumio-cuisine volumio[1232]: info: Initializing connection to go-librespot Websocket May 30 19:36:43 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:43+02:00" level=debug msg="new websocket client" May 30 19:36:43 volumio-cuisine volumio[1232]: error: MyVolumio Plugin failed to start in a timely fashion May 30 19:36:43 volumio-cuisine volumio[1232]: [Metrics] CommandRouter: 57s 13.58ms May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumiosetStartupVolume May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 19:36:43 volumio-cuisine volumio[1232]: info: VolumeController:: Setting startup Volume 100 May 30 19:36:43 volumio-cuisine volumio[1232]: info: VolumeController::SetAlsaVolume100 May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::Close All Modals sent May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::Close All Modals sent May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreStateMachine::pushState May 30 19:36:43 volumio-cuisine volumio[1232]: info: CorePlayQueue::getTrack 0 May 30 19:36:43 volumio-cuisine volumio[1232]: info: CoreCommandRouter::volumioPushState May 30 19:36:44 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 19:36:44 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 19:36:44 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 19:36:46 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:46+02:00" level=trace msg="sent dealer ping" May 30 19:36:46 volumio-cuisine go-librespot[1670]: time="2026-05-30T19:36:46+02:00" level=trace msg="received dealer pong" May 30 19:36:47 volumio-cuisine volumio5-onboarding[1675]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded May 30 19:36:47 volumio-cuisine systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 30 19:36:47 volumio-cuisine systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 30 19:36:48 volumio-cuisine systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. May 30 19:36:48 volumio-cuisine systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:48 volumio-cuisine systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:48 volumio-cuisine volumio5-onboarding[1768]: time=2026-05-30T19:36:48.302+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 30 19:36:52 volumio-cuisine volumio[1232]: info: Connection to go-librespot Websocket established May 30 19:36:53 volumio-cuisine volumio[1232]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 30 19:36:53 volumio-cuisine volumio[1232]: ALSA lib ./src/pcm_volumioswitch.c:1701:(_snd_pcm_volumioswitch_open) Failed to open control fifo May 30 19:36:53 volumio-cuisine volumio[1232]: aplay: main:831: audio open error: No such file or directory May 30 19:36:53 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 30 19:36:53 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 19:36:53 volumio-cuisine volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 30 19:36:53 volumio-cuisine volumio[1232]: info: BOOT COMPLETED May 30 19:36:53 volumio-cuisine volumio[1232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 19:36:53 volumio-cuisine volumio[1232]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 30 19:36:53 volumio-cuisine volumio[1232]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 30 19:36:53 volumio-cuisine volumio[1232]: at new NodeError (node:internal/errors:405:5) May 30 19:36:53 volumio-cuisine volumio[1232]: at assert (node:internal/assert:14:11) May 30 19:36:53 volumio-cuisine volumio[1232]: at internalConnectMultiple (node:net:1118:3) May 30 19:36:53 volumio-cuisine volumio[1232]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 30 19:36:53 volumio-cuisine volumio[1232]: at listOnTimeout (node:internal/timers:575:11) May 30 19:36:53 volumio-cuisine volumio[1232]: at process.processTimers (node:internal/timers:514:7) { May 30 19:36:53 volumio-cuisine volumio[1232]: code: 'ERR_INTERNAL_ASSERTION' May 30 19:36:53 volumio-cuisine volumio[1232]: } May 30 19:36:53 volumio-cuisine volumio[1232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 19:36:58 volumio-cuisine volumio5-onboarding[1768]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:55948->127.0.0.1:3000: i/o timeout May 30 19:36:58 volumio-cuisine systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 30 19:36:58 volumio-cuisine systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 30 19:36:58 volumio-cuisine systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. May 30 19:36:58 volumio-cuisine systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:58 volumio-cuisine systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 19:36:58 volumio-cuisine volumio5-onboarding[1802]: time=2026-05-30T19:36:58.534+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 30 19:36:58 volumio-cuisine sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 19:35' May 30 19:36:58 volumio-cuisine sudo[1811]: 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"