Jun 01 10:21:36 volumio ntpd[1047]: CLOCK: time stepped by 329206.955751 Jun 01 10:21:36 volumio ntpd[1047]: CLOCK: time changed from 2026-05-28 to 2026-06-01 Jun 01 10:21:36 volumio ntpd[1047]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 01 10:21:36 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jun 01 10:21:36 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jun 01 10:21:36 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jun 01 10:21:36 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Jun 01 10:21:36 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jun 01 10:21:36 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jun 01 10:21:37 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jun 01 10:21:37 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jun 01 10:21:37 volumio volumio[1161]: info: Loading plugin "touch_display"... Jun 01 10:21:38 volumio volumio[1161]: info: Applying required configuration parameters for plugin touch_display Jun 01 10:21:39 volumio volumio[1161]: info: Loading i18n strings for locale en Jun 01 10:21:39 volumio volumio[1161]: Updating browse sources language Jun 01 10:21:39 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::initPlayerControls Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 10:21:40 volumio volumio[1161]: Express server listening on port 3000 Jun 01 10:21:40 volumio volumio[1161]: [Metrics] WebUI: 16s 371.49ms Jun 01 10:21:40 volumio volumio[1161]: info: CoreStateMachine::resetVolumioState Jun 01 10:21:40 volumio volumio[1161]: info: CoreStateMachine::getcurrentVolume Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 10:21:40 volumio volumio[1382]: Starting albumart workers Jun 01 10:21:40 volumio volumio[1161]: info: CoreStateMachine::pushState Jun 01 10:21:40 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:21:40 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Jun 01 10:21:40 volumio volumio[1383]: Starting albumart workers Jun 01 10:21:40 volumio volumio[1161]: info: Volumio Network Manager: Network status updated: 0 Jun 01 10:21:40 volumio volumio[1384]: Starting albumart workers Jun 01 10:21:40 volumio volumio[1161]: info: Cannot mount NAS Roon at system boot, trial number 1 ,retrying in 5 seconds Jun 01 10:21:41 volumio volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 01 10:21:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:21:41 volumio volumio[1161]: info: Reloading queue from file Jun 01 10:21:41 volumio volumio[1161]: info: Setting Device type: Raspberry PI Jun 01 10:21:41 volumio volumio[1161]: info: CoreStateMachine::setRepeat false single undefined Jun 01 10:21:41 volumio volumio[1161]: info: CoreStateMachine::pushState Jun 01 10:21:41 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:21:41 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Jun 01 10:21:41 volumio volumio[1161]: info: CoreStateMachine::setRandom true Jun 01 10:21:41 volumio volumio[1161]: info: CoreStateMachine::pushState Jun 01 10:21:41 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:21:41 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Jun 01 10:21:41 volumio volumio[1161]: info: Completed loading Core Plugins Jun 01 10:21:41 volumio volumio[1161]: info: Preparing to generate the ALSA configuration file Jun 01 10:21:42 volumio volumio[1161]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 01 10:21:42 volumio volumio[1161]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jun 01 10:21:42 volumio volumio[1161]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: A device disappeared from network Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: A device disappeared from network Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: adding 9b5802fa-0b74-4970-b757-69695ea2b6ae Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: Found device Volumio Jun 01 10:21:42 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:42 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: this is already registered, 9b5802fa-0b74-4970-b757-69695ea2b6ae Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: Found device Volumio Jun 01 10:21:42 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:42 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: this is already registered, 9b5802fa-0b74-4970-b757-69695ea2b6ae Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: Found device Volumio Jun 01 10:21:42 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:42 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: this is already registered, 9b5802fa-0b74-4970-b757-69695ea2b6ae Jun 01 10:21:42 volumio volumio[1161]: info: Discovery: Found device Volumio Jun 01 10:21:42 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:42 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:43 volumio volumio[1161]: info: Asound.conf file written Jun 01 10:21:43 volumio sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 10:21:43 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:43 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:43 volumio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 01 10:21:43 volumio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 01 10:21:43 volumio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 01 10:21:43 volumio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Jun 01 10:21:43 volumio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Jun 01 10:21:43 volumio volumio[1161]: info: Output device has changed, restarting MPD Jun 01 10:21:43 volumio volumio[1161]: info: ___________ START PLUGINS ___________ Jun 01 10:21:43 volumio sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 10:21:43 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:43 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:43 volumio sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 10:21:43 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Starting plugin Jun 01 10:21:43 volumio sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 01 10:21:43 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Loaded cached presets v1.5.9 Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Active presets source: cached, 231 presets loaded Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Detected SoC: bcm2711 Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Detected RAM: 1816 MB Jun 01 10:21:43 volumio volumio[1161]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":1816,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Jun 01 10:21:43 volumio volumio[1161]: info: ControllerMpd::onStart: Initializing MPD Jun 01 10:21:43 volumio volumio[1161]: info: Creating MPD Configuration file Jun 01 10:21:43 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:21:43 volumio volumio[1161]: info: [1780280503836] CoreMusicLibrary::Adding element Last_100 Jun 01 10:21:43 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:21:43 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:21:43 volumio volumio[1161]: info: [1780280503855] CoreMusicLibrary::Adding element Webradio Jun 01 10:21:43 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:21:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 10:21:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 10:21:43 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:21:43 volumio sudo[1490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 10:21:43 volumio volumio[1161]: info: Initializing BBC Radios Jun 01 10:21:43 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:21:43 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:43 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:43 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:43 volumio sudo[1492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 10:21:43 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:44 volumio sudo[1493]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 10:21:44 volumio sudo[1498]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 01 10:21:44 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 10:21:44 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:44 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 10:21:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 10:21:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 10:21:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 10:21:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 10:21:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 10:21:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 10:21:44 volumio sudo[1513]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 10:21:44 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:21:44 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:21:44 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 10:21:44 volumio sudo[1519]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 01 10:21:44 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:44 volumio volumio5-onboarding[1494]: time=2026-06-01T10:21:44.403+08:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Jun 01 10:21:44 volumio volumio[1161]: info: [now-playing] ConfigUpdater: config is up to date. Jun 01 10:21:45 volumio volumio-remote-updater[737]: [2026-06-01 10:21:45] [connect] Successful connection Jun 01 10:21:45 volumio volumio[1161]: info: [RpiEepromUpdater] Plugin started successfully Jun 01 10:21:45 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 01 10:21:45 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:21:45 volumio volumio[1161]: info: [1780280505532] CoreMusicLibrary::Adding element Randomizer Jun 01 10:21:45 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:21:45 volumio volumio[1161]: Cannot find translation for source Randomizer Jun 01 10:21:45 volumio volumio[1161]: info: Loading i18n strings for locale en Jun 01 10:21:45 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:21:45 volumio volumio[1161]: info: Volumio Calling Home Jun 01 10:21:45 volumio volumio[1161]: info: pi_screen_setup: Detected prior configuration (backup=true, banner=true) - restoring wizard_complete Jun 01 10:21:45 volumio volumio[1161]: info: pi_screen_setup: Boot validation - {"valid":true,"drift_detected":false,"missing_videoconfig":false,"missing_include":false,"config_mismatch":false,"volumioconfig_mismatch":false,"errors":[]} Jun 01 10:21:45 volumio sudo[1546]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jun 01 10:21:45 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:45 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:46 volumio volumio[1161]: info: pi_screen_setup: Management server started on port 4567 Jun 01 10:21:46 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 01 10:21:46 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:46 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 01 10:21:46 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:46 volumio sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 01 10:21:46 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:46 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:46 volumio systemd[1]: Reloading. Jun 01 10:21:47 volumio systemd[1]: Reloading. Jun 01 10:21:47 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:48 volumio volumio[1161]: info: [now-playing] App is listening on port 4004. Jun 01 10:21:49 volumio volumio[1161]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 01 10:21:49 volumio mpd[1520]: 2026-06-01T10:21:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 10:21:49 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 01 10:21:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jun 01 10:21:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:21:49 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:21:49 volumio upmpdcli[1610]: Could not open config: /tmp/upmpdcli.conf Jun 01 10:21:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:21:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 10:21:49 volumio volumio[1161]: info: touch_display: No backlight interface detected. Jun 01 10:21:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 10:21:49 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio volumio[1161]: info: Volumio Network Manager: Network status updated: 1 Jun 01 10:21:49 volumio sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dc2geeks@gmail.com,password=GGVyqs6i8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.17/Data/Storage/InternalStorage /mnt/NAS/Roon Jun 01 10:21:49 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:49 volumio volumio[1161]: info: touch_display: systemctl daemon-reload succeeded. Jun 01 10:21:49 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jun 01 10:21:49 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jun 01 10:21:49 volumio kernel: CIFS: Attempting to mount //192.168.1.17/Data/Storage/InternalStorage Jun 01 10:21:49 volumio volumio[1161]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 01 10:21:49 volumio volumio[1161]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 01 10:21:49 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio volumio[1161]: info: MPD Permissions set Jun 01 10:21:49 volumio volumio[1161]: info: MPD Permissions set Jun 01 10:21:49 volumio sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 01 10:21:49 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:49 volumio sudo[1628]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/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/ Jun 01 10:21:49 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:49 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jun 01 10:21:49 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:49 volumio volumio[1161]: 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 Jun 01 10:21:50 volumio startx[1656]: X.Org X Server 1.21.1.7 Jun 01 10:21:50 volumio startx[1656]: X Protocol Version 11, Revision 0 Jun 01 10:21:50 volumio startx[1656]: Current Operating System: Linux volumio 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Jun 01 10:21:50 volumio startx[1656]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:1280x720M@100,margin_left=48,margin_right=48,margin_top=48,margin_bottom=48 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=E4:5F:01:9E:37:11 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 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=b0b466fc-4556-43af-9f97-ebe56fe4abe4 imgfile=/volumio_current.sqsh bootpart=UUID=0428-C7E4 datapart=UUID=82051e0e-a859-4f54-aa9f-0cc23a932af8 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 video=HDMI-A-1:rotate=270 plymouth=270 fbcon=rotate:3 Jun 01 10:21:50 volumio startx[1656]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Jun 01 10:21:50 volumio startx[1656]: Current version of pixman: 0.44.0 Jun 01 10:21:50 volumio startx[1656]: Before reporting problems, check http://wiki.x.org Jun 01 10:21:50 volumio startx[1656]: to make sure that you have the latest version. Jun 01 10:21:50 volumio startx[1656]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 01 10:21:50 volumio startx[1656]: (++) from command line, (!!) notice, (II) informational, Jun 01 10:21:50 volumio startx[1656]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 01 10:21:50 volumio startx[1656]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jun 1 10:21:50 2026 Jun 01 10:21:50 volumio startx[1656]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 01 10:21:50 volumio startx[1656]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 01 10:21:50 volumio volumio[1161]: 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 Jun 01 10:21:50 volumio volumio[1161]: info: Received Get System Info Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 10:21:50 volumio volumio[1161]: info: Discovery: Getting this device information Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:50 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 10:21:50 volumio volumio5-onboarding[1494]: time=2026-06-01T10:21:50.584+08:00 level=INFO msg="system info for eb5ad0cf4a867a33231da75c455da33d" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Jun 01 10:21:50 volumio volumio[1161]: info: touch_display: IframeKeyboardBridge extension installed successfully Jun 01 10:21:50 volumio volumio[1161]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 01 10:21:50 volumio volumio[1161]: info: touch_display: Volumio Kiosk started. Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:50 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:50 volumio volumio[1161]: info: Completed starting Core Plugins Jun 01 10:21:50 volumio volumio[1161]: info: ------------------------------------------- Jun 01 10:21:50 volumio volumio[1161]: info: ----- MyVolumio plugins startup ---- Jun 01 10:21:50 volumio volumio[1161]: info: ------------------------------------------- Jun 01 10:21:50 volumio volumio[1161]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 01 10:21:50 volumio volumio[1161]: info: Volumio called home Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:50 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:21:51 volumio volumio[1161]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=66f84b7f9f5de707eb554b784e53a036&lat=1.29027&lon=103.851959&units=metric: Error: Response error: 401 - Unauthorized Jun 01 10:21:51 volumio volumio[1161]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) Jun 01 10:21:51 volumio volumio[1161]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 10:21:51 volumio volumio[1161]: at async Promise.all (index 0) Jun 01 10:21:51 volumio volumio[1161]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:93:24) Jun 01 10:21:51 volumio volumio[1161]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:106:45) Jun 01 10:21:51 volumio volumio[1161]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:291:25 Jun 01 10:21:51 volumio volumio[1161]: info: Received Get System Info Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 10:21:51 volumio volumio[1161]: info: Discovery: Getting this device information Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:51 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:51 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 10:21:52 volumio volumio[1161]: error: MPD error: The expression evaluated to a falsy value: Jun 01 10:21:52 volumio volumio[1161]: assert.ok(self.idling) Jun 01 10:21:52 volumio volumio[1161]: error: The expression evaluated to a falsy value: Jun 01 10:21:52 volumio volumio[1161]: assert.ok(self.idling) Jun 01 10:21:52 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:52 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:52 volumio volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 01 10:21:52 volumio volumio[1161]: info: MPD running with PID1520 Jun 01 10:21:52 volumio volumio[1161]: ,establishing connection Jun 01 10:21:52 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 10:21:52 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 10:21:52 volumio volumio5-onboarding[1494]: time=2026-06-01T10:21:52.683+08:00 level=INFO msg="enabling local network discovery" Jun 01 10:21:52 volumio volumio5-onboarding[1494]: time=2026-06-01T10:21:52.704+08:00 level=INFO msg="bootstrapping state" hasInternet=true Jun 01 10:21:52 volumio volumio[1161]: error: updateQueue error: null Jun 01 10:21:52 volumio volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: X display number found: 0 Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 01 10:21:53 volumio volumio[1161]: info: Received Get System Info Jun 01 10:21:53 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 10:21:53 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 10:21:53 volumio volumio[1161]: info: Discovery: Getting this device information Jun 01 10:21:53 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:53 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:53 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 10:21:53 volumio sudo[1744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 01 10:21:53 volumio sudo[1742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 01 10:21:53 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:53 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:53 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:21:53 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 01 10:21:53 volumio volumio[1161]: error: updateQueue error: null Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 01 10:21:53 volumio volumio[1161]: info: touch_display: X display number found: 0 Jun 01 10:21:53 volumio volumio[1161]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=66f84b7f9f5de707eb554b784e53a036&lat=1.29027&lon=103.851959&units=metric: Error: Response error: 401 - Unauthorized Jun 01 10:21:53 volumio volumio[1161]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) Jun 01 10:21:53 volumio volumio[1161]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 10:21:53 volumio volumio[1161]: at async Promise.all (index 0) Jun 01 10:21:53 volumio volumio[1161]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:93:24) Jun 01 10:21:53 volumio volumio[1161]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:106:45) Jun 01 10:21:53 volumio volumio[1161]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:291:25 Jun 01 10:21:53 volumio volumio[1161]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Response error: 401 - Unauthorized Jun 01 10:21:53 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:53 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:54 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:21:54 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:21:54 volumio volumio[1161]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 01 10:21:58 volumio volumio[1161]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 01 10:21:59 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 01 10:22:00 volumio volumio-remote-updater[737]: [2026-06-01 10:22:00] [connect] Successful connection Jun 01 10:22:00 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 01 10:22:00 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 01 10:22:01 volumio volumio-remote-updater[737]: [2026-06-01 10:22:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780280520 101 Jun 01 10:22:01 volumio volumio[1161]: 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: 5 Jun 01 10:22:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jun 01 10:22:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:04 volumio upmpdcli[1907]: Could not open config: /tmp/upmpdcli.conf Jun 01 10:22:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 10:22:15 volumio volumio[1161]: error: MyVolumio Plugin failed to start in a timely fashion Jun 01 10:22:15 volumio volumio[1161]: [Metrics] CommandRouter: 50s 498.77ms Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::Close All Modals sent Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::Close All Modals sent Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:22:15 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin bluetooth to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin multiroom to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin metavolumio to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin cd_controller to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 01 10:22:17 volumio volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 01 10:22:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Jun 01 10:22:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:19 volumio upmpdcli[1941]: Could not open config: /tmp/upmpdcli.conf Jun 01 10:22:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 10:22:19 volumio volumio[1161]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 01 10:22:19 volumio volumio[1161]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 01 10:22:19 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:22:19 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:22:19 volumio volumio[1161]: info: Starting MyVolumio Remote Streaming Endpoints Jun 01 10:22:20 volumio volumio[1161]: info: MyVolumio login type: Token Jun 01 10:22:20 volumio volumio[1161]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 01 10:22:20 volumio volumio[1161]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 01 10:22:22 volumio volumio5-onboarding[1494]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Jun 01 10:22:22 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:22 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Jun 01 10:22:22 volumio volumio[1161]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 01 10:22:22 volumio volumio[1161]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 01 10:22:22 volumio volumio[1161]: info: Streaming services startup Jun 01 10:22:22 volumio volumio[1161]: info: Starting Streaming Daemon Jun 01 10:22:22 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Jun 01 10:22:22 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:22 volumio volumio[1161]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 01 10:22:22 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:23 volumio volumio5-onboarding[1943]: time=2026-06-01T10:22:23.027+08:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Jun 01 10:22:23 volumio sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 01 10:22:23 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 10:22:23 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Jun 01 10:22:23 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 10:22:23 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 10:22:23 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 10:22:23 volumio volumio[1161]: info: BOOT COMPLETED Jun 01 10:22:33 volumio volumio5-onboarding[1943]: 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:46076->127.0.0.1:3000: i/o timeout Jun 01 10:22:33 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:33 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Jun 01 10:22:33 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Jun 01 10:22:33 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:33 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:33 volumio volumio5-onboarding[1971]: time=2026-06-01T10:22:33.280+08:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Jun 01 10:22:33 volumio volumio[1161]: error: Cannot start Volumio Streaming Daemon Jun 01 10:22:33 volumio volumio[1161]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 01 10:22:33 volumio volumio[1161]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 01 10:22:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Jun 01 10:22:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:35 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:35 volumio upmpdcli[1980]: Could not open config: /tmp/upmpdcli.conf Jun 01 10:22:35 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:35 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 10:22:43 volumio volumio5-onboarding[1971]: 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:45054->127.0.0.1:3000: i/o timeout Jun 01 10:22:43 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:43 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Jun 01 10:22:43 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Jun 01 10:22:43 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:43 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:43 volumio volumio5-onboarding[1995]: time=2026-06-01T10:22:43.505+08:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Jun 01 10:22:50 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Jun 01 10:22:50 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:50 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 10:22:50 volumio upmpdcli[2016]: Could not open config: /tmp/upmpdcli.conf Jun 01 10:22:50 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:50 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 10:22:53 volumio volumio5-onboarding[1995]: 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:53380->127.0.0.1:3000: i/o timeout Jun 01 10:22:53 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:22:53 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Jun 01 10:22:53 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Jun 01 10:22:53 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:53 volumio volumio[1161]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 01 10:22:53 volumio volumio[1161]: info: Retrying Login Due to Network Error Jun 01 10:22:53 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 01 10:22:53 volumio volumio5-onboarding[2017]: time=2026-06-01T10:22:53.774+08:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Jun 01 10:22:53 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 10:22:53 volumio volumio[1161]: info: Discovery: Getting this device information Jun 01 10:22:53 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Jun 01 10:22:53 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Jun 01 10:22:53 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 10:22:54 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:22:54 volumio volumio[1161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 10:22:54 volumio volumio[1161]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jun 01 10:22:54 volumio volumio[1161]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jun 01 10:22:54 volumio volumio[1161]: at new NodeError (node:internal/errors:405:5) Jun 01 10:22:54 volumio volumio[1161]: at assert (node:internal/assert:14:11) Jun 01 10:22:54 volumio volumio[1161]: at internalConnectMultiple (node:net:1118:3) Jun 01 10:22:54 volumio volumio[1161]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jun 01 10:22:54 volumio volumio[1161]: at listOnTimeout (node:internal/timers:575:11) Jun 01 10:22:54 volumio volumio[1161]: at process.processTimers (node:internal/timers:514:7) { Jun 01 10:22:54 volumio volumio[1161]: code: 'ERR_INTERNAL_ASSERTION' Jun 01 10:22:54 volumio volumio[1161]: } Jun 01 10:22:54 volumio volumio[1161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 10:22:57 volumio sudo[2037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 10:21' Jun 01 10:22:57 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"