Oct 13 06:28:26 volumiogarage ntpd[947]: CLOCK: time stepped by 31581.844860 Oct 13 06:28:26 volumiogarage ntpd[947]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "network"... Oct 13 06:28:28 volumiogarage volumio[1258]: info: Refreshing Cached IP Addresses Oct 13 06:28:28 volumiogarage sudo[1415]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 06:28:28 volumiogarage sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:28 volumiogarage sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 06:28:28 volumiogarage sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "services"... Oct 13 06:28:28 volumiogarage sudo[1415]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "volumio5onboarding"... Oct 13 06:28:28 volumiogarage sudo[1412]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:28 volumiogarage sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 13 06:28:28 volumiogarage sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "alsa_controller"... Oct 13 06:28:28 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "wizard"... Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "networkfs"... Oct 13 06:28:28 volumiogarage systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 13 06:28:28 volumiogarage volumio[1258]: info: Starting Udev Watcher for removable devices Oct 13 06:28:28 volumiogarage sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=eric,password=Es-1972!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Media\\Musiques /mnt/NAS/Truenas Oct 13 06:28:28 volumiogarage sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:28 volumiogarage volumio[1258]: info: Ignoring mount for partition: boot Oct 13 06:28:28 volumiogarage systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 13 06:28:28 volumiogarage volumio[1258]: info: Ignoring mount for partition: volumio Oct 13 06:28:28 volumiogarage volumio[1258]: info: Ignoring mount for partition: volumio_data Oct 13 06:28:28 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:28:28 volumiogarage systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "volumio_command_line_client"... Oct 13 06:28:28 volumiogarage systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 13 06:28:28 volumiogarage volumio[1258]: info: Loading plugin "upnp"... Oct 13 06:28:29 volumiogarage volumio[1258]: info: [1791865709006] Starting Upmpd Daemon Oct 13 06:28:29 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:28:29 volumiogarage kernel: netfs: FS-Cache loaded Oct 13 06:28:29 volumiogarage volumio[1258]: info: Loading plugin "my_music"... Oct 13 06:28:29 volumiogarage volumio[1258]: info: Loading plugin "mpd"... Oct 13 06:28:29 volumiogarage kernel: Key type cifs.spnego registered Oct 13 06:28:29 volumiogarage kernel: Key type cifs.idmap registered Oct 13 06:28:29 volumiogarage 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. Oct 13 06:28:29 volumiogarage kernel: CIFS: Attempting to mount //192.168.0.200/Media/Musiques Oct 13 06:28:29 volumiogarage sudo[1427]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:29 volumiogarage systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 13 06:28:29 volumiogarage systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 13 06:28:29 volumiogarage sudo[1446]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:30 volumiogarage volumio[1258]: info: Loading plugin "upnp_browser"... Oct 13 06:28:30 volumiogarage dhcpcd[755]: timed out Oct 13 06:28:30 volumiogarage sh[745]: timed out Oct 13 06:28:30 volumiogarage sh[701]: ifup: failed to bring up eth0 Oct 13 06:28:30 volumiogarage systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 13 06:28:30 volumiogarage systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 13 06:28:32 volumiogarage volumio[1258]: info: Starting UPNP Browser Oct 13 06:28:32 volumiogarage volumio[1258]: info: Loading plugin "alarm-clock"... Oct 13 06:28:32 volumiogarage systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 13 06:28:32 volumiogarage systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 06:28:32 volumiogarage systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 06:28:32 volumiogarage upmpdcli[1541]: Could not open config: /tmp/upmpdcli.conf Oct 13 06:28:32 volumiogarage systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 06:28:32 volumiogarage systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 06:28:32 volumiogarage volumio[1258]: info: Loading plugin "airplay_emulation"... Oct 13 06:28:32 volumiogarage volumio[1258]: info: Starting Shairport Sync Oct 13 06:28:32 volumiogarage volumio[1258]: info: Loading plugin "last_100"... Oct 13 06:28:32 volumiogarage volumio[1258]: info: Loading plugin "webradio"... Oct 13 06:28:33 volumiogarage volumio[1258]: info: Loading plugin "i2s_dacs"... Oct 13 06:28:33 volumiogarage volumio[1258]: info: I2S DAC not set, start Auto-detection Oct 13 06:28:33 volumiogarage volumio[1258]: info: Loading plugin "volumiodiscovery"... Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** For more information see Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:28:33 volumiogarage volumio[1258]: *** WARNING *** For more information see Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** For more information see Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:28:33 volumiogarage node[1258]: *** WARNING *** For more information see Oct 13 06:28:33 volumiogarage volumio[1258]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 06:28:33 volumiogarage volumio[1258]: info: Discovery: Started advertising with name: VolumioGarage Oct 13 06:28:33 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:28:33 volumiogarage volumio[1258]: info: Loading plugin "spop"... Oct 13 06:28:35 volumiogarage nmbd[1112]: [2026/10/13 06:28:35.672921, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 13 06:28:35 volumiogarage nmbd[1112]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.110 for name WORKGROUP<1d>. Oct 13 06:28:35 volumiogarage nmbd[1112]: This response was from IP 192.168.0.200, reporting an IP address of 192.168.0.200. Oct 13 06:28:36 volumiogarage volumio[1258]: info: Plugin squeezelite_mc is not enabled Oct 13 06:28:36 volumiogarage volumio[1258]: info: Loading plugin "autostart"... Oct 13 06:28:36 volumiogarage volumio[1258]: info: Applying required configuration parameters for plugin autostart Oct 13 06:28:36 volumiogarage volumio[1258]: info: AutoStart - onVolumioStart - read config.json Oct 13 06:28:36 volumiogarage volumio[1258]: info: Loading plugin "outputs"... Oct 13 06:28:36 volumiogarage volumio[1258]: info: Loading plugin "albumart"... Oct 13 06:28:36 volumiogarage volumio[1258]: info: Plugin example_plugin is not enabled Oct 13 06:28:36 volumiogarage volumio[1258]: info: Loading plugin "inputs"... Oct 13 06:28:36 volumiogarage volumio[1258]: info: Loading plugin "updater_comm"... Oct 13 06:28:36 volumiogarage systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 13 06:28:37 volumiogarage volumio[1258]: info: Plugin mpdemulation is not enabled Oct 13 06:28:37 volumiogarage volumio[1258]: info: Loading plugin "rest_api"... Oct 13 06:28:37 volumiogarage volumio[1258]: info: Loading plugin "websocket"... Oct 13 06:28:37 volumiogarage volumio[1258]: info: Starting Socket.io Server version 1.7.4 Oct 13 06:28:37 volumiogarage volumio[1258]: info: Loading plugin "touch_display"... Oct 13 06:28:38 volumiogarage volumio[1546]: Forking 3 albumart workers Oct 13 06:28:39 volumiogarage volumio-remote-updater[780]: [2026-10-13 06:28:39] [connect] Successful connection Oct 13 06:28:40 volumiogarage volumio[1258]: info: Applying required configuration parameters for plugin touch_display Oct 13 06:28:41 volumiogarage volumio[1258]: info: Loading i18n strings for locale fr Oct 13 06:28:41 volumiogarage volumio[1258]: Updating browse sources language Oct 13 06:28:41 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::initPlayerControls Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:28:43 volumiogarage volumio[1258]: Express server listening on port 3000 Oct 13 06:28:43 volumiogarage volumio[1258]: [Metrics] WebUI: 19s 903.54ms Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreStateMachine::resetVolumioState Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreStateMachine::getcurrentVolume Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 06:28:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:28:44 volumiogarage sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 06:28:44 volumiogarage sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:44 volumiogarage sudo[1608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 06:28:44 volumiogarage sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:44 volumiogarage sudo[1610]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:44 volumiogarage sudo[1608]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:44 volumiogarage volumio[1258]: info: Volumio Network Manager: Network status updated: 2 Oct 13 06:28:44 volumiogarage systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 13 06:28:45 volumiogarage volumio[1258]: info: VolumeController:: Volume=100 Mute =false Oct 13 06:28:45 volumiogarage volumio[1258]: info: CoreStateMachine::pushState Oct 13 06:28:45 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:45 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:28:45 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioPushState Oct 13 06:28:45 volumiogarage volumio[1258]: info: CoreStateMachine::updateTrackBlock Oct 13 06:28:45 volumiogarage volumio[1258]: info: CorePlayQueue::getTrackBlock Oct 13 06:28:45 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 06:28:45 volumiogarage systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 06:28:45 volumiogarage systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 06:28:45 volumiogarage systemd[1]: setdatetime-helper.service: Consumed 1.097s CPU time. Oct 13 06:28:46 volumiogarage volumio[1258]: info: Reloading queue from file Oct 13 06:28:46 volumiogarage volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreStateMachine::setRepeat null single undefined Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreStateMachine::pushState Oct 13 06:28:46 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioPushState Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreStateMachine::setRandom null Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreStateMachine::pushState Oct 13 06:28:46 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:46 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioPushState Oct 13 06:28:47 volumiogarage volumio[1258]: info: Setting Device type: Raspberry PI Oct 13 06:28:47 volumiogarage volumio[1559]: Starting albumart workers Oct 13 06:28:47 volumiogarage volumio[1258]: info: VolumeController:: Volume=100 Mute =false Oct 13 06:28:47 volumiogarage volumio[1258]: info: CoreStateMachine::pushState Oct 13 06:28:47 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:47 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioPushState Oct 13 06:28:47 volumiogarage volumio[1258]: info: Discovery: adding 0bc03b1b-fef3-474b-b51c-85cd9618f109 Oct 13 06:28:47 volumiogarage volumio[1258]: info: Discovery: Found device VolumioGarage Oct 13 06:28:47 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:28:47 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:47 volumiogarage systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 13 06:28:47 volumiogarage systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 06:28:47 volumiogarage sudo[1650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 13 06:28:47 volumiogarage sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:47 volumiogarage systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 06:28:48 volumiogarage sudo[1650]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:48 volumiogarage volumio[1258]: info: Discovery: this is already registered, 0bc03b1b-fef3-474b-b51c-85cd9618f109 Oct 13 06:28:48 volumiogarage volumio[1258]: info: Discovery: Found device VolumioGarage Oct 13 06:28:48 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:28:48 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:28:48 volumiogarage volumio[1258]: info: Upmpdcli Daemon Started Oct 13 06:28:48 volumiogarage volumio[1258]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 13 06:28:48 volumiogarage volumio[1258]: info: Completed loading Core Plugins Oct 13 06:28:48 volumiogarage volumio[1258]: info: Preparing to generate the ALSA configuration file Oct 13 06:28:48 volumiogarage volumio[1560]: Starting albumart workers Oct 13 06:28:49 volumiogarage volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Oct 13 06:28:49 volumiogarage volumio[1258]: info: Output device has changed, restarting MPD Oct 13 06:28:49 volumiogarage volumio[1258]: info: Output device has changed, restarting Shairport Sync Oct 13 06:28:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:28:49 volumiogarage sudo[1680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 06:28:49 volumiogarage sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 06:28:49 volumiogarage sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:49 volumiogarage sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:49 volumiogarage volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 06:28:49 volumiogarage sudo[1677]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:49 volumiogarage volumio[1561]: Starting albumart workers Oct 13 06:28:49 volumiogarage volumio[1258]: info: ___________ START PLUGINS ___________ Oct 13 06:28:49 volumiogarage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 06:28:49 volumiogarage systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 06:28:49 volumiogarage volumio[1258]: info: ControllerMpd::onStart: Initializing MPD Oct 13 06:28:49 volumiogarage volumio[1258]: info: Creating MPD Configuration file Oct 13 06:28:50 volumiogarage sudo[1691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 13 06:28:50 volumiogarage sudo[1689]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 06:28:50 volumiogarage sudo[1689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 06:28:50 volumiogarage sudo[1695]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 06:28:50 volumiogarage sudo[1689]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:50 volumiogarage sudo[1691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:28:50 volumiogarage volumio[1258]: info: [1791865730169] CoreMusicLibrary::Adding element Serveurs Média Oct 13 06:28:50 volumiogarage sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 06:28:50 volumiogarage sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:50 volumiogarage sudo[1696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 06:28:50 volumiogarage sudo[1696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:50 volumiogarage sudo[1694]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:28:50 volumiogarage systemd[1]: mpd.service: Deactivated successfully. Oct 13 06:28:50 volumiogarage systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 06:28:50 volumiogarage systemd[1]: mpd.socket: Deactivated successfully. Oct 13 06:28:50 volumiogarage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 06:28:50 volumiogarage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 06:28:50 volumiogarage volumio[1258]: info: UPNP Browser: Client initialized successfully Oct 13 06:28:50 volumiogarage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 06:28:50 volumiogarage systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:28:50 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:28:50 volumiogarage sudo[1691]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:50 volumiogarage sudo[1702]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 06:28:50 volumiogarage sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 06:28:50 volumiogarage sudo[1708]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 06:28:50 volumiogarage sudo[1702]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:50 volumiogarage volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:28:51 volumiogarage volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 06:28:51 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:28:51 volumiogarage volumio[1258]: info: [1791865731321] CoreMusicLibrary::Adding element Last_100 Oct 13 06:28:51 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:28:51 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:28:51 volumiogarage volumio[1258]: info: [1791865731463] CoreMusicLibrary::Adding element Webradio Oct 13 06:28:51 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:28:51 volumiogarage volumio5-onboarding[1705]: time=2026-10-13T06:28:51.493+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 13 06:28:51 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:28:51 volumiogarage volumio[1258]: info: Initializing BBC Radios Oct 13 06:28:52 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:28:52 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:28:52 volumiogarage volumio[1258]: info: Creating Spotify config file Oct 13 06:28:52 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:28:54 volumiogarage volumio-remote-updater[780]: [2026-10-13 06:28:54] [connect] Successful connection Oct 13 06:28:55 volumiogarage volumio[1258]: info: AutoStart - onStart - waiting for system ready state Oct 13 06:28:55 volumiogarage volumio[1258]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Oct 13 06:28:55 volumiogarage volumio[1258]: info: AutoStart - Maximum wait time: 300 seconds Oct 13 06:28:55 volumiogarage volumio[1258]: info: AutoStart - Startup volume disabled Oct 13 06:28:55 volumiogarage volumio[1258]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:28:55 volumiogarage volumio[1258]: info: Loading i18n strings for locale fr Oct 13 06:28:55 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:28:55 volumiogarage volumio[1258]: info: Volumio Calling Home Oct 13 06:28:55 volumiogarage sudo[1754]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 13 06:28:55 volumiogarage sudo[1754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:56 volumiogarage sudo[1754]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:56 volumiogarage sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 13 06:28:56 volumiogarage sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:56 volumiogarage sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 13 06:28:56 volumiogarage sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:56 volumiogarage sudo[1759]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:56 volumiogarage sudo[1765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 13 06:28:56 volumiogarage sudo[1765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:28:56 volumiogarage systemd[1]: Reloading. Oct 13 06:28:58 volumiogarage sudo[1762]: pam_unix(sudo:session): session closed for user root Oct 13 06:28:58 volumiogarage systemd[1]: Reloading. Oct 13 06:28:59 volumiogarage volumio[1258]: info: touch_display: Backlight interface detected. Oct 13 06:28:59 volumiogarage mpd[1709]: 2026-10-13T06:28:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 06:28:59 volumiogarage volumio[1258]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 13 06:28:59 volumiogarage volumio[1258]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 13 06:28:59 volumiogarage volumio[1258]: info: MPD Permissions set Oct 13 06:28:59 volumiogarage volumio[1258]: info: MPD Permissions set Oct 13 06:28:59 volumiogarage volumio[1258]: 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 Oct 13 06:28:59 volumiogarage volumio[1258]: info: Volumio called home Oct 13 06:28:59 volumiogarage volumio[1258]: info: Spotify config file written Oct 13 06:29:00 volumiogarage sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 13 06:29:00 volumiogarage sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:00 volumiogarage volumio[1258]: 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 Oct 13 06:29:00 volumiogarage volumio[1258]: info: Received Get System Info Oct 13 06:29:00 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 06:29:00 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 06:29:00 volumiogarage volumio[1258]: info: Discovery: Getting this device information Oct 13 06:29:00 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:29:00 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:29:00 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 06:29:00 volumiogarage volumio5-onboarding[1705]: time=2026-10-13T06:29:00.294+02:00 level=INFO msg="system info for 202be56b39dc30a07dff30ed16355871" deviceName=VolumioGarage deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 13 06:29:00 volumiogarage sudo[1808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Oct 13 06:29:00 volumiogarage sudo[1808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:00 volumiogarage volumio5-onboarding[1705]: time=2026-10-13T06:29:00.318+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 13 06:29:00 volumiogarage sudo[1808]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:00 volumiogarage systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 06:29:00 volumiogarage 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. Oct 13 06:29:00 volumiogarage 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. Oct 13 06:29:00 volumiogarage sudo[1765]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:00 volumiogarage sudo[1696]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:00 volumiogarage sudo[1680]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:00 volumiogarage systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 06:29:00 volumiogarage go-librespot[1833]: go-librespot daemon starting... Oct 13 06:29:00 volumiogarage sudo[1828]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:01 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:01+02:00" level=info msg="running go-librespot 0.9.1" Oct 13 06:29:01 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 06:29:01 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:01+02:00" level=debug msg="app state loaded" Oct 13 06:29:01 volumiogarage volumio[1258]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:01 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+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]" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+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]" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+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]" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=info msg="zeroconf server listening on port 42359" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************um" Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:02 volumiogarage volumio[1258]: info: No need to fix Spotify hosts Oct 13 06:29:02 volumiogarage volumio[1258]: info: Received Get System Info Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 06:29:02 volumiogarage volumio[1258]: info: Discovery: Getting this device information Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:29:02 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:29:02 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=debug msg="obtained new client token: AAEjP5az9VL4IsCOUj4+v4+sJiz5rETj55xkDUGIFQovYSL52UrqbRWPPdj8xFxOmjG/DVWxNTWlu4cZzZQq8VkLdK1QN/0NlidgznoEXoVr2anJdUzzZ7u9a9VbFf3lNzJIElSDFLnhhRUA+ESFwX3slaxsl1AJA+Szx3AXjw9umPzhwBIbwsbU7XmJ1NrPNY9VoLkg0wkDzt2LLA8NTLAhpoLKJ9FnaNq1Ggjafygw7egHXMd6yAma" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=debug msg="completed keyexchange" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=debug msg="completed challenge" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=info msg="authenticated AP" username="31************************um" Oct 13 06:29:02 volumiogarage volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 13 06:29:02 volumiogarage volumio[1258]: info: touch_display: systemctl daemon-reload succeeded. Oct 13 06:29:02 volumiogarage volumio[1258]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=info msg="authenticated Login5" username="31************************um" Oct 13 06:29:02 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:02+02:00" level=debug msg="initializing zeroconf session" username="31************************um" Oct 13 06:29:03 volumiogarage sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 13 06:29:03 volumiogarage sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="connected to gew1-dealer.spotify.com:443" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=trace msg="starting accesspoint recv loop" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=trace msg="starting dealer recv loop" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="received connection id: YjgzZTMyNGYtNTVi...RkMyQ0MxNUM0NQ==" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="avahi service \"VolumioGarage\" established" Oct 13 06:29:03 volumiogarage systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Oct 13 06:29:03 volumiogarage sudo[1861]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 13 06:29:03 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:03+02:00" level=trace msg="emitting websocket event: playback_ready" Oct 13 06:29:03 volumiogarage sudo[1884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Oct 13 06:29:03 volumiogarage sudo[1884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:03 volumiogarage sudo[1884]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:03 volumiogarage startx[1887]: X.Org X Server 1.21.1.7 Oct 13 06:29:03 volumiogarage startx[1887]: X Protocol Version 11, Revision 0 Oct 13 06:29:03 volumiogarage startx[1887]: Current Operating System: Linux volumiogarage 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Oct 13 06:29:03 volumiogarage startx[1887]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 13 06:29:03 volumiogarage startx[1887]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Oct 13 06:29:03 volumiogarage startx[1887]: Current version of pixman: 0.44.0 Oct 13 06:29:03 volumiogarage startx[1887]: Before reporting problems, check http://wiki.x.org Oct 13 06:29:03 volumiogarage startx[1887]: to make sure that you have the latest version. Oct 13 06:29:03 volumiogarage startx[1887]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 13 06:29:03 volumiogarage startx[1887]: (++) from command line, (!!) notice, (II) informational, Oct 13 06:29:03 volumiogarage startx[1887]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 13 06:29:03 volumiogarage startx[1887]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Oct 13 06:29:03 2026 Oct 13 06:29:03 volumiogarage startx[1887]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 13 06:29:03 volumiogarage startx[1887]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 13 06:29:03 volumiogarage volumio[1258]: info: touch_display: Raspberry Pi Foundation touch screen detected. Oct 13 06:29:03 volumiogarage sudo[1890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 13 06:29:03 volumiogarage sudo[1890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:03 volumiogarage sudo[1890]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:03 volumiogarage sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 13 06:29:03 volumiogarage sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:03 volumiogarage sudo[1893]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:03 volumiogarage volumio[1258]: info: New Spotify access tokenBQAZ8QxMOR... Oct 13 06:29:03 volumiogarage volumio[1258]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 13 06:29:04 volumiogarage volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Oct 13 06:29:04 volumiogarage volumio[1258]: assert.ok(self.idling) Oct 13 06:29:04 volumiogarage volumio[1258]: error: The expression evaluated to a falsy value: Oct 13 06:29:04 volumiogarage volumio[1258]: assert.ok(self.idling) Oct 13 06:29:04 volumiogarage volumio[1258]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 13 06:29:04 volumiogarage volumio[1258]: info: touch_display: Volumio Kiosk started. Oct 13 06:29:04 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:29:04 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:29:04 volumiogarage volumio[1258]: info: Completed starting Core Plugins Oct 13 06:29:04 volumiogarage volumio[1258]: info: ------------------------------------------- Oct 13 06:29:04 volumiogarage volumio[1258]: info: ----- MyVolumio plugins startup ---- Oct 13 06:29:04 volumiogarage volumio[1258]: info: ------------------------------------------- Oct 13 06:29:04 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 13 06:29:04 volumiogarage volumio[1258]: info: MPD running with PID1709 Oct 13 06:29:04 volumiogarage volumio[1258]: ,establishing connection Oct 13 06:29:05 volumiogarage volumio[1258]: info: Starting Shairport Sync Oct 13 06:29:05 volumiogarage volumio[1258]: info: Starting Shairport Sync Oct 13 06:29:05 volumiogarage sudo[1914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 06:29:05 volumiogarage sudo[1914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:05 volumiogarage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 06:29:05 volumiogarage systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 06:29:05 volumiogarage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage systemd[1]: shairport-sync.service: Consumed 1.443s CPU time. Oct 13 06:29:05 volumiogarage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage volumio[1258]: info: Starting Shairport Sync Oct 13 06:29:05 volumiogarage sudo[1923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 06:29:05 volumiogarage sudo[1914]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:05 volumiogarage sudo[1923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:05 volumiogarage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 06:29:05 volumiogarage systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 06:29:05 volumiogarage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage sudo[1923]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:05 volumiogarage volumio[1258]: error: updateQueue error: null Oct 13 06:29:05 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:29:05 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:29:05 volumiogarage sudo[1930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 06:29:05 volumiogarage sudo[1930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:05 volumiogarage volumio[1258]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 13 06:29:05 volumiogarage volumio[1258]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 13 06:29:05 volumiogarage volumio[1258]: info: touch_display: File permissions for backlight brightness control set. Oct 13 06:29:05 volumiogarage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 06:29:05 volumiogarage systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 06:29:05 volumiogarage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 06:29:05 volumiogarage sudo[1930]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:05 volumiogarage volumio[1258]: info: Shairport-Sync Started Oct 13 06:29:05 volumiogarage volumio[1258]: Error adding Membership: Error: addMembership EINVAL Oct 13 06:29:05 volumiogarage volumio[1258]: info: Shairport-Sync Started Oct 13 06:29:05 volumiogarage volumio[1258]: info: touch_display: X display number found: 0 Oct 13 06:29:05 volumiogarage volumio[1258]: info: go-librespot daemon successfully initialized Oct 13 06:29:06 volumiogarage volumio[1258]: error: updateQueue error: null Oct 13 06:29:06 volumiogarage volumio[1258]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 13 06:29:06 volumiogarage volumio[1258]: info: Shairport-Sync Started Oct 13 06:29:06 volumiogarage volumio[1258]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:07 volumiogarage volumio[1258]: info: touch_display: X display number found: 0 Oct 13 06:29:07 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioGetState Oct 13 06:29:07 volumiogarage volumio[1258]: info: CorePlayQueue::getTrack 0 Oct 13 06:29:07 volumiogarage volumio[1258]: SPOTIFY: User informations: {"account_id":"2OOYsmfILX","country":"FR","display_name":"Eric","email":"saviric@yahoo.fr","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31j2elql7naia5dqvbqvcpqoryum"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31j2elql7naia5dqvbqvcpqoryum","id":"31j2elql7naia5dqvbqvcpqoryum","images":[],"product":"premium","type":"user","uri":"spotify:user:31j2elql7naia5dqvbqvcpqoryum"} Oct 13 06:29:07 volumiogarage volumio[1258]: info: Spotify Successfully logged in Oct 13 06:29:07 volumiogarage volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:29:07 volumiogarage volumio[1258]: info: [1791865747917] CoreMusicLibrary::Adding element Spotify Oct 13 06:29:07 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:29:07 volumiogarage volumio[1258]: Cannot find translation for source Spotify Oct 13 06:29:08 volumiogarage volumio[1258]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 06:29:09 volumiogarage volumio[1258]: info: Initializing connection to go-librespot Websocket Oct 13 06:29:09 volumiogarage go-librespot[1834]: time="2026-10-13T06:29:09+02:00" level=debug msg="new websocket client" Oct 13 06:29:09 volumiogarage volumio-remote-updater[780]: [2026-10-13 06:29:09] [connect] Successful connection Oct 13 06:29:09 volumiogarage volumio[1258]: info: Connection to go-librespot Websocket established Oct 13 06:29:09 volumiogarage volumio-remote-updater[780]: [2026-10-13 06:29:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791865749 101 Oct 13 06:29:09 volumiogarage volumio[1258]: 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: 4 Oct 13 06:29:10 volumiogarage volumio[1258]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 13 06:29:12 volumiogarage volumio[1258]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:12 volumiogarage volumio[1258]: info: Getting Spotify volume Oct 13 06:29:17 volumiogarage volumio[1258]: info: Spotify volume: 100 Oct 13 06:29:17 volumiogarage volumio[1258]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:19 volumiogarage volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 13 06:29:25 volumiogarage volumio[1258]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:25 volumiogarage volumio[1258]: error: MyVolumio Plugin failed to start in a timely fashion Oct 13 06:29:25 volumiogarage volumio[1258]: [Metrics] CommandRouter: 60s 610.12ms Oct 13 06:29:25 volumiogarage volumio[1258]: info: CoreCommandRouter::volumiosetStartupVolume Oct 13 06:29:25 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:29:25 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:25 volumiogarage volumio[1258]: info: CoreCommandRouter::Close All Modals sent Oct 13 06:29:25 volumiogarage volumio[1258]: info: CoreCommandRouter::Close All Modals sent Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 06:29:27 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 13 06:29:30 volumiogarage volumio5-onboarding[1705]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 13 06:29:30 volumiogarage systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 06:29:30 volumiogarage systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 06:29:30 volumiogarage systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 13 06:29:30 volumiogarage systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:30 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:31 volumiogarage volumio5-onboarding[2167]: time=2026-10-13T06:29:31.630+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 13 06:29:41 volumiogarage upmpdcli[2189]: writing RSA key Oct 13 06:29:41 volumiogarage volumio5-onboarding[2167]: 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:54832->127.0.0.1:3000: i/o timeout Oct 13 06:29:41 volumiogarage systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 06:29:41 volumiogarage systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 06:29:41 volumiogarage systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 13 06:29:41 volumiogarage systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:41 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:42 volumiogarage volumio5-onboarding[2194]: time=2026-10-13T06:29:42.016+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 13 06:29:43 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 06:29:43 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 06:29:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:43 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:43 volumiogarage volumio[1258]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 06:29:43 volumiogarage volumio[1258]: info: MyVolumio login type: Token Oct 13 06:29:43 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 06:29:43 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 06:29:48 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 06:29:48 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 06:29:48 volumiogarage volumio[1258]: info: Streaming services startup Oct 13 06:29:48 volumiogarage volumio[1258]: info: Starting Streaming Daemon Oct 13 06:29:48 volumiogarage volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 06:29:49 volumiogarage sudo[2217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 06:29:49 volumiogarage sudo[2217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 06:29:49 volumiogarage sudo[2217]: pam_unix(sudo:session): session closed for user root Oct 13 06:29:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:29:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:29:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 06:29:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 06:29:49 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 06:29:50 volumiogarage volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 06:29:50 volumiogarage volumio[1258]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 13 06:29:50 volumiogarage volumio[1258]: info: BOOT COMPLETED Oct 13 06:29:50 volumiogarage volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 06:29:50 volumiogarage volumio[1258]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 13 06:29:50 volumiogarage volumio[1258]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 13 06:29:50 volumiogarage volumio[1258]: at new NodeError (node:internal/errors:405:5) Oct 13 06:29:50 volumiogarage volumio[1258]: at assert (node:internal/assert:14:11) Oct 13 06:29:50 volumiogarage volumio[1258]: at internalConnectMultiple (node:net:1118:3) Oct 13 06:29:50 volumiogarage volumio[1258]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 13 06:29:50 volumiogarage volumio[1258]: at listOnTimeout (node:internal/timers:575:11) Oct 13 06:29:50 volumiogarage volumio[1258]: at process.processTimers (node:internal/timers:514:7) { Oct 13 06:29:50 volumiogarage volumio[1258]: code: 'ERR_INTERNAL_ASSERTION' Oct 13 06:29:50 volumiogarage volumio[1258]: } Oct 13 06:29:50 volumiogarage volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 06:29:52 volumiogarage volumio5-onboarding[2194]: 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:56538->127.0.0.1:3000: i/o timeout Oct 13 06:29:52 volumiogarage systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 06:29:52 volumiogarage systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 06:29:52 volumiogarage systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 13 06:29:52 volumiogarage systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:52 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 06:29:52 volumiogarage volumio5-onboarding[2240]: time=2026-10-13T06:29:52.512+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 13 06:29:55 volumiogarage sudo[2264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 06:28' Oct 13 06:29:55 volumiogarage sudo[2264]: 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"