Aug 31 20:02:43 volumio ntpd[1097]: CLOCK: time stepped by 168742.605119 Aug 31 20:02:43 volumio ntpd[1097]: CLOCK: time changed from 2026-08-29 to 2026-08-31 Aug 31 20:02:43 volumio ntpd[1097]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 31 20:02:43 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 31 20:02:43 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:43.856+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=-46h52m22.593853169s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.160834628s Aug 31 20:02:43 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 31 20:02:43 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 31 20:02:43 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 31 20:02:43 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 31 20:02:43 volumio volumio[6928]: info: Discovery: A device disappeared from network Aug 31 20:02:43 volumio volumio[6928]: info: Discovery: A device disappeared from network Aug 31 20:02:43 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 31 20:02:43 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 31 20:02:43 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 31 20:02:43 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:43.972+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=-46h52m22.593853169s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.277586017s Aug 31 20:02:44 volumio volumio[6928]: info: Received Get System Info Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:02:44 volumio volumio[6928]: info: Discovery: Getting this device information Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::volumioGetState Aug 31 20:02:44 volumio volumio[6928]: info: CorePlayQueue::getTrack 0 Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:02:44 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:44.236+04:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" available=true connected=true macAddress=88:a2:9e:9c:69:92 ip4Address=192.168.1.33/24 ip6Address= ssid=WIFI-VOLUMIO Aug 31 20:02:44 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:44.236+04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.10:51533 @ 0x242e990" available=true connected=true macAddress=88:a2:9e:9c:69:92 ip4Address=192.168.1.33/24 ip6Address= ssid=WIFI-VOLUMIO Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 20:02:44 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 20:02:44 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:44.558+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=-46h52m22.593853169s timeout=10s endpoint=http://cddb.volumio.org duration=1.862523942s Aug 31 20:02:44 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:44.805+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=-46h52m22.593853169s timeout=10s endpoint=https://google.com duration=2.110542702s Aug 31 20:02:45 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:45.140+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=-46h52m22.593853169s timeout=10s endpoint=http://plugins.volumio.org duration=2.44372098s Aug 31 20:02:45 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:45.197+04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 31 20:02:46 volumio sudo[6993]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio volumio[6928]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Aug 31 20:02:46 volumio volumio[6928]: info: Completed loading Core Plugins Aug 31 20:02:46 volumio volumio[6928]: info: Preparing to generate the ALSA configuration file Aug 31 20:02:46 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 31 20:02:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 31 20:02:46 volumio volumio[6928]: info: Asound.conf file unchanged, so no further update is needed Aug 31 20:02:46 volumio volumio[6928]: info: Output device has changed, restarting MPD Aug 31 20:02:46 volumio volumio[6928]: info: Output device has changed, restarting Shairport Sync Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:02:46 volumio sudo[7100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:02:46 volumio sudo[7100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio sudo[7100]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio volumio[6928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 20:02:46 volumio sudo[7102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:02:46 volumio volumio[6928]: info: ___________ START PLUGINS ___________ Aug 31 20:02:46 volumio sudo[7102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Starting plugin Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Detected SoC: bcm2711 Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Detected RAM: 3783 MB Aug 31 20:02:46 volumio volumio[6928]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":3783,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Aug 31 20:02:46 volumio sudo[7110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 31 20:02:46 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 20:02:46 volumio volumio[6928]: info: ControllerMpd::onStart: Initializing MPD Aug 31 20:02:46 volumio volumio[6928]: info: Creating MPD Configuration file Aug 31 20:02:46 volumio sudo[7110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 20:02:46 volumio volumio[6928]: info: [1788192166534] CoreMusicLibrary::Adding element Serveurs Média Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:02:46 volumio sudo[7110]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio sudo[7113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:02:46 volumio sudo[7113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio sudo[7115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:02:46 volumio sudo[7115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:02:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:02:46 volumio sudo[7113]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio systemd[1]: mpd.service: Consumed 1.555s CPU time. Aug 31 20:02:46 volumio volumio[6928]: info: UPNP Browser: Client initialized successfully Aug 31 20:02:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:02:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:02:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:02:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:02:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:02:46 volumio volumio[6928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 20:02:46 volumio volumio[6928]: info: [1788192166612] CoreMusicLibrary::Adding element Last_100 Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:02:46 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:02:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 20:02:46 volumio volumio[6928]: info: [1788192166615] CoreMusicLibrary::Adding element Webradio Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:02:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:02:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:02:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 20:02:46 volumio volumio[6928]: info: Initializing BBC Radios Aug 31 20:02:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:02:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:02:46 volumio volumio[6928]: info: Loading i18n strings for locale fr Aug 31 20:02:46 volumio volumio[6928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 20:02:46 volumio volumio[6928]: info: Volumio Calling Home Aug 31 20:02:46 volumio sudo[7145]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Aug 31 20:02:46 volumio sudo[7145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:02:46 volumio sudo[7145]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio sudo[7131]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 20:02:46 volumio sudo[7131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 20:02:46 volumio volumio[6928]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:02:46 volumio sudo[7131]: pam_unix(sudo:session): session closed for user root Aug 31 20:02:46 volumio volumio[6928]: Error: listen EADDRINUSE: address already in use :::3001 Aug 31 20:02:46 volumio volumio[6928]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Aug 31 20:02:46 volumio volumio[6928]: at listenInCluster (node:net:1920:12) Aug 31 20:02:46 volumio volumio[6928]: at Server.listen (node:net:2008:7) Aug 31 20:02:46 volumio volumio[6928]: at Function.listen (/data/plugins/user_interface/volumio-coverflow/node_modules/express/lib/application.js:635:24) Aug 31 20:02:46 volumio volumio[6928]: at CoverFlowPlugin._startWebServer (/data/plugins/user_interface/volumio-coverflow/index.js:190:22) Aug 31 20:02:46 volumio volumio[6928]: at CoverFlowPlugin.onStart (/data/plugins/user_interface/volumio-coverflow/index.js:21:8) Aug 31 20:02:46 volumio volumio[6928]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 31 20:02:46 volumio volumio[6928]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 31 20:02:46 volumio volumio[6928]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 31 20:02:46 volumio volumio[6928]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 31 20:02:46 volumio volumio[6928]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 31 20:02:46 volumio volumio[6928]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 31 20:02:46 volumio volumio[6928]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 31 20:02:46 volumio volumio[6928]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 31 20:02:46 volumio volumio[6928]: code: 'EADDRINUSE', Aug 31 20:02:46 volumio volumio[6928]: errno: -98, Aug 31 20:02:46 volumio volumio[6928]: syscall: 'listen', Aug 31 20:02:46 volumio volumio[6928]: address: '::', Aug 31 20:02:46 volumio volumio[6928]: port: 3001 Aug 31 20:02:46 volumio volumio[6928]: } Aug 31 20:02:46 volumio volumio[6928]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:02:47 volumio volumio5-onboarding[1982]: time=2026-08-31T20:02:47.169+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.1.10:51533 @ 0x2258b70" latency=12.609971ms timeout=10s Aug 31 20:02:47 volumio sudo[7166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 20:01' Aug 31 20:02:47 volumio sudo[7166]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"