Jun 01 06:11:14 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon...
Jun 01 06:11:14 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Jun 01 06:11:14 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Jun 01 06:11:14 volumio systemd[1]: setdatetime-helper.service: Consumed 1.076s CPU time.
Jun 01 06:11:14 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Jun 01 06:11:14 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Jun 01 06:11:14 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Jun 01 06:11:14 volumio smbd[1375]: [2026/06/01 06:11:14.419387,  0] ../../source3/smbd/server.c:1741(main)
Jun 01 06:11:14 volumio smbd[1375]:   smbd version 4.17.8-Raspbian started.
Jun 01 06:11:14 volumio smbd[1375]:   Copyright Andrew Tridgell and the Samba Team 1992-2022
Jun 01 06:11:14 volumio winbindd[1331]: [2026/06/01 06:11:14.545119,  0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done)
Jun 01 06:11:14 volumio winbindd[1331]:   wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO' failed 'NT_STATUS_IO_TIMEOUT'
Jun 01 06:11:14 volumio systemd[1]: Started smbd.service - Samba SMB Daemon.
Jun 01 06:11:14 volumio systemd[1]: Reached target multi-user.target - Multi-User System.
Jun 01 06:11:14 volumio systemd[1]: Reached target graphical.target - Graphical Interface.
Jun 01 06:11:14 volumio ntpd[1037]: IO: Listen normally on 3 eth0 192.168.2.38:123
Jun 01 06:11:14 volumio ntpd[1037]: IO: new interface(s) found: waking up resolver
Jun 01 06:11:14 volumio ntpd[1037]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Jun 01 06:11:14 volumio ntpd[1037]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Jun 01 06:11:14 volumio ntpd[1037]: DNS: Pool taking: 202.12.97.45
Jun 01 06:11:14 volumio ntpd[1037]: DNS: Pool taking: 202.29.58.130
Jun 01 06:11:14 volumio ntpd[1037]: DNS: Pool taking: 124.109.2.169
Jun 01 06:11:14 volumio ntpd[1037]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Jun 01 06:11:14 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Jun 01 06:11:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Jun 01 06:11:14 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Jun 01 06:11:14 volumio systemd[1]: Startup finished in 13.216s (kernel) + 13.273s (userspace) = 26.490s.
Jun 01 06:11:15 volumio winbindd[1363]: [2026/06/01 06:11:15.506843,  0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response)
Jun 01 06:11:15 volumio winbindd[1363]:   Could not write result
Jun 01 06:11:15 volumio ntpd[1037]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Jun 01 06:11:15 volumio ntpd[1037]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 203.159.70.33
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 202.28.92.208
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 171.103.24.86
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 2a05:dfc3:f751:ef3a:d7fe:3837:e723:dee2
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 2a0d:5600:21:25:0:2:0:5
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 2406:3100:1010:c03::149
Jun 01 06:11:15 volumio ntpd[1037]: DNS: Pool taking: 240b:400f:8:3a07:400:115:974f:c952
Jun 01 06:11:15 volumio ntpd[1037]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Jun 01 06:11:16 volumio ntpd[1037]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Jun 01 06:11:16 volumio ntpd[1037]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Jun 01 06:11:16 volumio ntpd[1037]: DNS: Pool taking: 192.100.77.19
Jun 01 06:11:16 volumio ntpd[1037]: DNS: Pool taking: 202.28.33.225
Jun 01 06:11:16 volumio ntpd[1037]: DNS: Pool taking: 47.87.71.223
Jun 01 06:11:16 volumio ntpd[1037]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Jun 01 06:11:17 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:17 volumio volumio[1307]: info: -----            Volumio3              ----
Jun 01 06:11:17 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:17 volumio volumio[1307]: info: -----          System startup          ----
Jun 01 06:11:17 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:17 volumio ntpd[1037]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Jun 01 06:11:17 volumio ntpd[1037]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Jun 01 06:11:17 volumio ntpd[1037]: DNS: Pool taking: 202.80.227.130
Jun 01 06:11:17 volumio ntpd[1037]: DNS: Pool taking: 202.28.117.7
Jun 01 06:11:17 volumio ntpd[1037]: DNS: Pool taking: 202.28.116.236
Jun 01 06:11:17 volumio ntpd[1037]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Jun 01 06:11:18 volumio volumio[1307]: info: MYVOLUMIO Environment detected
Jun 01 06:11:18 volumio volumio-remote-updater[747]: [2026-06-01 06:11:18] [connect] Successful connection
Jun 01 06:11:18 volumio volumio[1307]: info: Plugin folders cleanup
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning into folder /volumio/app/plugins/
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category audio_interface
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category miscellanea
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category music_service
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category plugins.json
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category system_controller
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category user_interface
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning into folder /data/plugins/
Jun 01 06:11:18 volumio volumio[1307]: info: Scanning category user_interface
Jun 01 06:11:18 volumio volumio[1307]: info: Plugin folders cleanup completed
Jun 01 06:11:18 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:18 volumio volumio[1307]: info: -----      Core plugins startup        ----
Jun 01 06:11:18 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:18 volumio volumio[1307]: info: Loading plugins from folder /volumio/app/plugins/
Jun 01 06:11:18 volumio volumio[1307]: info: Adding plugin upnp to MyMusic Plugins
Jun 01 06:11:18 volumio volumio[1307]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 01 06:11:18 volumio volumio[1307]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 01 06:11:18 volumio volumio[1307]: info: Loading plugins from folder /data/plugins/
Jun 01 06:11:18 volumio volumio[1307]: info: Loading plugin "system"...
Jun 01 06:11:18 volumio volumio[1307]: info: Loading plugin "appearance"...
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "network"...
Jun 01 06:11:20 volumio volumio[1307]: info: Refreshing Cached IP Addresses
Jun 01 06:11:20 volumio sudo[1417]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 01 06:11:20 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:20 volumio sudo[1418]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 01 06:11:20 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:20 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "services"...
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "volumio5onboarding"...
Jun 01 06:11:20 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:20 volumio sudo[1422]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 01 06:11:20 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "alsa_controller"...
Jun 01 06:11:20 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "wizard"...
Jun 01 06:11:20 volumio volumio[1307]: info: Loading plugin "networkfs"...
Jun 01 06:11:20 volumio volumio[1307]: info: Starting Udev Watcher for removable devices
Jun 01 06:11:20 volumio sudo[1447]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.37/Music /mnt/NAS/Nas_Music
Jun 01 06:11:20 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:20 volumio volumio[1307]: info: Ignoring mount for partition: boot
Jun 01 06:11:20 volumio volumio[1307]: info: Ignoring mount for partition: volumio
Jun 01 06:11:20 volumio volumio[1307]: info: Ignoring mount for partition: volumio_data
Jun 01 06:11:21 volumio ntpd[1037]: CLOCK: time stepped by 0.961629
Jun 01 06:11:21 volumio ntpd[1037]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Jun 01 06:11:21 volumio kernel: netfs: FS-Cache loaded
Jun 01 06:11:21 volumio volumio[1307]: info: Mounting Device 19H1X64_6IN
Jun 01 06:11:21 volumio sudo[1459]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/19H1X64_6IN -o noatime
Jun 01 06:11:21 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:21 volumio kernel: Key type cifs.spnego registered
Jun 01 06:11:21 volumio kernel: Key type cifs.idmap registered
Jun 01 06:11:21 volumio 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.
Jun 01 06:11:21 volumio kernel: CIFS: Attempting to mount //192.168.2.37/Music
Jun 01 06:11:21 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 01 06:11:21 volumio volumio[1307]: info: Loading plugin "volumio_command_line_client"...
Jun 01 06:11:21 volumio volumio[1307]: info: Loading plugin "upnp"...
Jun 01 06:11:21 volumio volumio[1307]: info: [1780269081985] Starting Upmpd Daemon
Jun 01 06:11:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 01 06:11:21 volumio volumio[1307]: info: Loading plugin "my_music"...
Jun 01 06:11:22 volumio volumio[1307]: info: Loading plugin "mpd"...
Jun 01 06:11:22 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Jun 01 06:11:22 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:22 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:22 volumio upmpdcli[1488]: Could not open config: /tmp/upmpdcli.conf
Jun 01 06:11:22 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Jun 01 06:11:22 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Jun 01 06:11:22 volumio volumio[1307]: info: Loading plugin "upnp_browser"...
Jun 01 06:11:22 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:23 volumio volumio[1307]: info: Starting UPNP Browser
Jun 01 06:11:23 volumio volumio[1307]: info: Loading plugin "alarm-clock"...
Jun 01 06:11:23 volumio volumio[1307]: info: Loading plugin "airplay_emulation"...
Jun 01 06:11:23 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:23 volumio volumio[1307]: info: Loading plugin "last_100"...
Jun 01 06:11:23 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:23 volumio volumio[1307]: info: Loading plugin "webradio"...
Jun 01 06:11:24 volumio volumio[1307]: info: Loading plugin "i2s_dacs"...
Jun 01 06:11:24 volumio volumio[1307]: info: I2S DAC not set, start Auto-detection
Jun 01 06:11:24 volumio volumio[1307]: info: Loading plugin "volumiodiscovery"...
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** For more information see 
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 01 06:11:24 volumio volumio[1307]: *** WARNING *** For more information see 
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** For more information see 
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 01 06:11:24 volumio node[1307]: *** WARNING *** For more information see 
Jun 01 06:11:24 volumio volumio[1307]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 01 06:11:24 volumio volumio[1307]: info: Discovery: Started advertising with name: Volumio
Jun 01 06:11:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 01 06:11:24 volumio volumio[1307]: info: Loading plugin "peppy_screensaver"...
Jun 01 06:11:24 volumio volumio[1307]: info: Applying required configuration parameters for plugin peppy_screensaver
Jun 01 06:11:24 volumio volumio[1307]: info: Loading plugin "outputs"...
Jun 01 06:11:24 volumio volumio[1307]: info: Loading plugin "albumart"...
Jun 01 06:11:25 volumio volumio[1307]: info: Plugin example_plugin is not enabled
Jun 01 06:11:25 volumio volumio[1307]: info: Loading plugin "inputs"...
Jun 01 06:11:25 volumio volumio[1307]: info: Loading plugin "updater_comm"...
Jun 01 06:11:25 volumio volumio[1307]: info: Plugin mpdemulation is not enabled
Jun 01 06:11:25 volumio volumio[1307]: info: Loading plugin "rest_api"...
Jun 01 06:11:25 volumio volumio[1307]: info: Loading plugin "websocket"...
Jun 01 06:11:25 volumio volumio[1307]: info: Starting Socket.io Server version 1.7.4
Jun 01 06:11:25 volumio volumio[1307]: info: Loading plugin "touch_display"...
Jun 01 06:11:25 volumio volumio[1307]: info: Applying required configuration parameters for plugin touch_display
Jun 01 06:11:25 volumio volumio[1307]: info: Loading i18n strings for locale en
Jun 01 06:11:25 volumio volumio[1307]: Updating browse sources language
Jun 01 06:11:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:11:26 volumio volumio[1497]: Forking 3 albumart workers
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::initPlayerControls
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 01 06:11:26 volumio volumio[1307]: Express server listening on port 3000
Jun 01 06:11:26 volumio volumio[1307]:  [Metrics]  WebUI:  9s 265.51ms 
Jun 01 06:11:26 volumio volumio[1307]: info: CoreStateMachine::resetVolumioState
Jun 01 06:11:26 volumio volumio[1307]: info: CoreStateMachine::getcurrentVolume
Jun 01 06:11:26 volumio volumio[1307]: info: CoreCommandRouter::volumioRetrievevolume
Jun 01 06:11:26 volumio volumio[1307]: info: CoreStateMachine::pushState
Jun 01 06:11:27 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:27 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 01 06:11:27 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState
Jun 01 06:11:27 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:27 volumio volumio[1307]: info: Volumio Network Manager: Network status updated: 1
Jun 01 06:11:27 volumio volumio[1307]: 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 06:11:28 volumio volumio[1307]: info: Reloading queue from file
Jun 01 06:11:28 volumio volumio[1307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Jun 01 06:11:28 volumio volumio[1307]: info: Setting Device type: Raspberry PI
Jun 01 06:11:28 volumio volumio[1307]: info: CoreStateMachine::setRepeat null single undefined
Jun 01 06:11:28 volumio volumio[1307]: info: CoreStateMachine::pushState
Jun 01 06:11:28 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:28 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 01 06:11:28 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState
Jun 01 06:11:28 volumio volumio[1307]: info: CoreStateMachine::setRandom null
Jun 01 06:11:28 volumio volumio[1307]: info: CoreStateMachine::pushState
Jun 01 06:11:28 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:28 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState
Jun 01 06:11:28 volumio volumio[1307]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jun 01 06:11:28 volumio volumio[1307]: info: USB Boot Capable - System SBC Revision found in cpuinfo:  b03115
Jun 01 06:11:28 volumio volumio[1307]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jun 01 06:11:28 volumio volumio[1307]: info: Discovery: adding 67435506-15f2-415f-a60b-5ef18caf3b20
Jun 01 06:11:28 volumio volumio[1307]: info: Discovery: Found device Volumio
Jun 01 06:11:28 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:28 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:28 volumio volumio[1307]: info: Discovery: this is already registered,  67435506-15f2-415f-a60b-5ef18caf3b20
Jun 01 06:11:28 volumio volumio[1307]: info: Discovery: Found device Volumio
Jun 01 06:11:28 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:28 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:29 volumio volumio[1307]: info: Completed loading Core Plugins
Jun 01 06:11:29 volumio volumio[1307]: info: Preparing to generate the ALSA configuration file
Jun 01 06:11:29 volumio volumio[1307]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Jun 01 06:11:29 volumio volumio[1307]: info: Reading ALSA contributions from plugins.
Jun 01 06:11:30 volumio volumio[1307]: info: Asound.conf file unchanged, so no further update is needed
Jun 01 06:11:30 volumio volumio[1307]: info: Output device has changed, restarting MPD
Jun 01 06:11:30 volumio volumio[1307]: info: Output device has changed, restarting Shairport Sync
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:30 volumio sudo[1550]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 01 06:11:30 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:30 volumio sudo[1553]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 01 06:11:30 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:30 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:30 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 01 06:11:30 volumio volumio[1307]: info: ___________ START PLUGINS ___________
Jun 01 06:11:30 volumio volumio[1307]: info: ControllerMpd::onStart: Initializing MPD
Jun 01 06:11:30 volumio volumio[1307]: info: Creating MPD Configuration file
Jun 01 06:11:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jun 01 06:11:30 volumio sudo[1563]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 01 06:11:30 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 01 06:11:30 volumio volumio[1307]: info: [1780269090532] CoreMusicLibrary::Adding element Media Servers
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:11:30 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:30 volumio sudo[1564]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 01 06:11:30 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jun 01 06:11:30 volumio sudo[1568]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Jun 01 06:11:30 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:30 volumio sudo[1561]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Jun 01 06:11:30 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:30 volumio sudo[1567]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 01 06:11:30 volumio sudo[1567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:30 volumio volumio[1307]: info: UPNP Browser: Client initialized successfully
Jun 01 06:11:30 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:30 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:30 volumio systemd[1]: mpd.service: Deactivated successfully.
Jun 01 06:11:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Jun 01 06:11:30 volumio systemd[1]: mpd.socket: Deactivated successfully.
Jun 01 06:11:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Jun 01 06:11:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jun 01 06:11:30 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:30 volumio sudo[1575]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 01 06:11:30 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jun 01 06:11:30 volumio sudo[1586]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Jun 01 06:11:30 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:31 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 01 06:11:31 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 01 06:11:31 volumio volumio[1307]: info: [1780269091158] CoreMusicLibrary::Adding element Last_100
Jun 01 06:11:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:11:31 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 01 06:11:31 volumio volumio[1307]: info: [1780269091161] CoreMusicLibrary::Adding element Webradio
Jun 01 06:11:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:11:31 volumio volumio5-onboarding[1572]: time=2026-06-01T06:11:31.162+07:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Jun 01 06:11:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:11:31 volumio volumio[1307]: info: Initializing BBC Radios
Jun 01 06:11:32 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:11:32 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:32 volumio volumio[1307]: info: Loading i18n strings for locale en
Jun 01 06:11:32 volumio volumio[1507]: Starting albumart workers
Jun 01 06:11:32 volumio volumio[1307]: info: /tmp/myfifo created
Jun 01 06:11:32 volumio volumio[1307]: info: /tmp/myfifosa created
Jun 01 06:11:32 volumio volumio[1509]: Starting albumart workers
Jun 01 06:11:32 volumio volumio[1508]: Starting albumart workers
Jun 01 06:11:32 volumio sudo[1631]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Jun 01 06:11:32 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:32 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:32 volumio volumio[1307]: info: snd-dummy loaded
Jun 01 06:11:33 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:33 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:33 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
Jun 01 06:11:33 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:33 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:33 volumio sudo[1671]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl
Jun 01 06:11:33 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:33 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 01 06:11:33 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:33 volumio volumio[1307]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver
Jun 01 06:11:33 volumio volumio[1307]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver
Jun 01 06:11:33 volumio volumio[1307]: info: Adding peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver
Jun 01 06:11:33 volumio volumio[1307]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font
Jun 01 06:11:33 volumio volumio[1307]: info: Adding peppy_screensaver_vinyl REST Endpoint for plugin: user_interface/peppy_screensaver
Jun 01 06:11:33 volumio volumio[1307]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_vinyl
Jun 01 06:11:33 volumio volumio[1307]: info: peppy_screensaver: Config version updated: bbe1ee9c
Jun 01 06:11:34 volumio volumio[1307]: info: Loading i18n strings for locale en
Jun 01 06:11:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:11:34 volumio volumio[1307]: info: Volumio Calling Home
Jun 01 06:11:34 volumio volumio-remote-updater[747]: [2026-06-01 06:11:34] [connect] Successful connection
Jun 01 06:11:34 volumio sudo[1676]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge
Jun 01 06:11:34 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Jun 01 06:11:34 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:34 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:34 volumio sudo[1683]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Jun 01 06:11:34 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:34 volumio sudo[1685]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Jun 01 06:11:34 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:34 volumio systemd[1]: Reloading.
Jun 01 06:11:34 volumio sudo[1688]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Jun 01 06:11:34 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:36 volumio mpd[1587]: 2026-06-01T06:11:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 01 06:11:36 volumio systemd[1]: Reloading.
Jun 01 06:11:36 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:36 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:37 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Jun 01 06:11:37 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Jun 01 06:11:37 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:37 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:37 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:37 volumio sudo[1567]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:37 volumio upmpdcli[1744]: Could not open config: /tmp/upmpdcli.conf
Jun 01 06:11:37 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Jun 01 06:11:37 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Jun 01 06:11:37 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Jun 01 06:11:38 volumio sudo[1749]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 01 06:11:38 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:38 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:38 volumio sudo[1751]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 01 06:11:38 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:38 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:38 volumio volumio[1307]: info: touch_display: No backlight interface detected.
Jun 01 06:11:38 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:38 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:38 volumio volumio[1307]: info: touch_display: systemctl daemon-reload succeeded.
Jun 01 06:11:38 volumio volumio[1307]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Jun 01 06:11:38 volumio volumio[1307]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Jun 01 06:11:38 volumio sudo[1757]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Jun 01 06:11:38 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:38 volumio volumio[1307]: info: MPD Permissions set
Jun 01 06:11:38 volumio volumio[1307]: info: MPD Permissions set
Jun 01 06:11:38 volumio volumio[1307]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false
Jun 01 06:11:38 volumio volumio[1307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3
Jun 01 06:11:39 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk.
Jun 01 06:11:39 volumio volumio[1307]: info: peppy_screensaver: Startup - MPD output 1 enabled
Jun 01 06:11:39 volumio volumio[1307]: info: Volumio called home
Jun 01 06:11:39 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:39 volumio volumio[1307]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.conf.tmpl (isX64=false)
Jun 01 06:11:39 volumio sudo[1760]:  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/
Jun 01 06:11:39 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:39 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:39 volumio sudo[1799]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl
Jun 01 06:11:39 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:39 volumio volumio[1307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3
Jun 01 06:11:39 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:11:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:11:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:11:39 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:11:39 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:39 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:39 volumio startx[1797]: X.Org X Server 1.21.1.7
Jun 01 06:11:39 volumio startx[1797]: X Protocol Version 11, Revision 0
Jun 01 06:11:39 volumio startx[1797]: Current Operating System: Linux volumio 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l
Jun 01 06:11:39 volumio startx[1797]: 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_hdmi=0 video=HDMI-A-1:640x480M@60D numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=D8:3A:DD:14:3A:03 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=7e9efb31-d81e-40e6-b369-17d6be2a2543 imgfile=/volumio_current.sqsh bootpart=UUID=41F4-3F60 datapart=UUID=d86abd49-9ab6-4e7d-8df3-6405c27b7066 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
Jun 01 06:11:39 volumio startx[1797]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support)
Jun 01 06:11:39 volumio startx[1797]: Current version of pixman: 0.44.0
Jun 01 06:11:39 volumio startx[1797]:         Before reporting problems, check http://wiki.x.org
Jun 01 06:11:39 volumio startx[1797]:         to make sure that you have the latest version.
Jun 01 06:11:39 volumio startx[1797]: Markers: (--) probed, (**) from config file, (==) default setting,
Jun 01 06:11:39 volumio startx[1797]:         (++) from command line, (!!) notice, (II) informational,
Jun 01 06:11:39 volumio startx[1797]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Jun 01 06:11:39 volumio startx[1797]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jun  1 06:11:39 2026
Jun 01 06:11:39 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:39 volumio startx[1797]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Jun 01 06:11:39 volumio startx[1797]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Jun 01 06:11:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:11:39 volumio volumio5-onboarding[1572]: time=2026-06-01T06:11:39.350+07:00 level=INFO msg="system info for 2f9048567f8323d0f4b59eacf836ec78" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096
Jun 01 06:11:40 volumio volumio[1307]: error: MPD error: The expression evaluated to a falsy value:
Jun 01 06:11:40 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:40 volumio volumio[1307]: error: The expression evaluated to a falsy value:
Jun 01 06:11:40 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:40 volumio volumio[1307]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Jun 01 06:11:40 volumio volumio[1307]: info: touch_display: Volumio Kiosk started.
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:40 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:40 volumio volumio[1307]: info: Completed starting Core Plugins
Jun 01 06:11:40 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:40 volumio volumio[1307]: info: -----    MyVolumio plugins startup     ----
Jun 01 06:11:40 volumio volumio[1307]: info: -------------------------------------------
Jun 01 06:11:40 volumio volumio[1307]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 01 06:11:40 volumio volumio[1307]: info: MPD running with PID1587
Jun 01 06:11:40 volumio volumio[1307]:  ,establishing connection
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:40 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:11:40 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:40 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:11:40 volumio volumio[1307]: error: updateQueue error: null
Jun 01 06:11:40 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:40 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:41 volumio volumio[1307]: 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 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile
Jun 01 06:11:41 volumio volumio[1307]: info: touch_display: IframeKeyboardBridge extension installed successfully
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Jun 01 06:11:41 volumio volumio[1307]: info: Preparing to generate the ALSA configuration file
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jun 01 06:11:41 volumio volumio5-onboarding[1572]: time=2026-06-01T06:11:41.120+07:00 level=INFO msg="enabling local network discovery"
Jun 01 06:11:41 volumio volumio[1307]: info: touch_display: X display number found: 0
Jun 01 06:11:41 volumio volumio5-onboarding[1572]: time=2026-06-01T06:11:41.145+07:00 level=INFO msg="bootstrapping state" hasInternet=true
Jun 01 06:11:41 volumio volumio[1307]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Jun 01 06:11:41 volumio sudo[1835]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 01 06:11:41 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio volumio[1307]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Jun 01 06:11:41 volumio volumio[1307]: info: Reading ALSA contributions from plugins.
Jun 01 06:11:41 volumio volumio[1307]: error: updateQueue error: null
Jun 01 06:11:41 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:41 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:41 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:41 volumio sudo[1847]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 01 06:11:41 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jun 01 06:11:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jun 01 06:11:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio systemd[1]: shairport-sync.service: Consumed 1.651s CPU time.
Jun 01 06:11:41 volumio sudo[1839]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Jun 01 06:11:41 volumio sudo[1850]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 01 06:11:41 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio sudo[1842]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Jun 01 06:11:41 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio sudo[1852]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 01 06:11:41 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jun 01 06:11:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jun 01 06:11:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jun 01 06:11:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jun 01 06:11:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:41 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:41 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:11:41 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:41 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:41 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:11:41 volumio sudo[1913]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 01 06:11:41 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:41 volumio sudo[1926]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 01 06:11:41 volumio sudo[1926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:42 volumio volumio[1307]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Jun 01 06:11:42 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jun 01 06:11:42 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jun 01 06:11:42 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Jun 01 06:11:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:42 volumio sudo[1969]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 01 06:11:42 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:42 volumio sudo[1973]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 01 06:11:42 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Jun 01 06:11:42 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:42 volumio volumio[1307]: info: Shairport-Sync Started
Jun 01 06:11:42 volumio volumio[1307]: Error adding Membership: Error: addMembership EINVAL
Jun 01 06:11:42 volumio volumio[1307]: info: Shairport-Sync Started
Jun 01 06:11:42 volumio volumio[1307]: info: Shairport-Sync Started
Jun 01 06:11:42 volumio volumio[1307]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Jun 01 06:11:42 volumio volumio[1307]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Jun 01 06:11:42 volumio volumio[1307]: info: touch_display: X display number found: 0
Jun 01 06:11:42 volumio volumio[1307]: info: MPD Permissions set
Jun 01 06:11:42 volumio systemd[1]: mpd.service: Deactivated successfully.
Jun 01 06:11:42 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Jun 01 06:11:42 volumio systemd[1]: mpd.service: Consumed 4.309s CPU time.
Jun 01 06:11:42 volumio systemd[1]: mpd.socket: Deactivated successfully.
Jun 01 06:11:42 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:42 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Jun 01 06:11:42 volumio volumio[1307]: info: Shairport-Sync Started
Jun 01 06:11:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jun 01 06:11:43 volumio volumio[1307]: info: Asound.conf file unchanged, so no further update is needed
Jun 01 06:11:43 volumio volumio[1307]: info: Output device has changed, restarting MPD
Jun 01 06:11:43 volumio volumio[1307]: info: Output device has changed, restarting Shairport Sync
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:43 volumio sudo[2020]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 01 06:11:43 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:43 volumio sudo[2016]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 01 06:11:43 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jun 01 06:11:43 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:43 volumio sudo[2022]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 01 06:11:43 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:43 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:43 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:11:43 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:11:43 volumio systemd[1]: mpd.service: Deactivated successfully.
Jun 01 06:11:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Jun 01 06:11:43 volumio systemd[1]: mpd.socket: Deactivated successfully.
Jun 01 06:11:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Jun 01 06:11:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Jun 01 06:11:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Jun 01 06:11:43 volumio volumio[1307]: warn: peppy_screensaver: Failed to set MPD output: Error: Command failed: mpc enable 1
Jun 01 06:11:43 volumio volumio[1307]: MPD error: Connection reset by peer
Jun 01 06:11:43 volumio volumio[1307]: info: MPD Permissions set
Jun 01 06:11:43 volumio volumio[1307]: info: touch_display: Setting screensaver timeout to 30 seconds.
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio sudo[2035]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 01 06:11:43 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jun 01 06:11:43 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:11:43 volumio volumio[1307]: info: Starting Shairport Sync
Jun 01 06:11:43 volumio sudo[2052]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 01 06:11:43 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Jun 01 06:11:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Jun 01 06:11:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:43 volumio systemd[1]: shairport-sync.service: Consumed 1.206s CPU time.
Jun 01 06:11:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Jun 01 06:11:44 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:44 volumio volumio[1307]: info: Shairport-Sync Started
Jun 01 06:11:45 volumio mpd[2050]: 2026-06-01T06:11:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 01 06:11:46 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Jun 01 06:11:46 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:46 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:46 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:46 volumio volumio[1307]: error: MPD error: The expression evaluated to a falsy value:
Jun 01 06:11:46 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:46 volumio volumio[1307]: error: The expression evaluated to a falsy value:
Jun 01 06:11:46 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:46 volumio volumio[1307]: error: MPD error: The expression evaluated to a falsy value:
Jun 01 06:11:46 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:46 volumio volumio[1307]: error: The expression evaluated to a falsy value:
Jun 01 06:11:46 volumio volumio[1307]:   assert.ok(self.idling)
Jun 01 06:11:46 volumio volumio[1307]: error: updateQueue error: null
Jun 01 06:11:49 volumio volumio-remote-updater[747]: [2026-06-01 06:11:49] [connect] Successful connection
Jun 01 06:11:49 volumio volumio-remote-updater[747]: [2026-06-01 06:11:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780269109 101
Jun 01 06:11:49 volumio volumio[1307]: 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 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jun 01 06:11:49 volumio volumio[1307]: info: Adding plugin bluetooth to MyMusic Plugins
Jun 01 06:11:49 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Jun 01 06:11:49 volumio volumio[1307]: info: Adding plugin multiroom to MyMusic Plugins
Jun 01 06:11:49 volumio volumio[1307]: info: Adding plugin metavolumio to MyMusic Plugins
Jun 01 06:11:49 volumio volumio[1307]: info: Adding plugin cd_controller to MyMusic Plugins
Jun 01 06:11:50 volumio volumio[1307]: info: Adding plugin qobuzconnect to MyMusic Plugins
Jun 01 06:11:50 volumio volumio[1307]: info: Adding plugin smart_inputs to MyMusic Plugins
Jun 01 06:11:50 volumio volumio[1307]: info: Adding plugin tidalconnect to MyMusic Plugins
Jun 01 06:11:50 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jun 01 06:11:50 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Jun 01 06:11:50 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Jun 01 06:11:53 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3.
Jun 01 06:11:53 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:53 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Jun 01 06:11:53 volumio sudo[1926]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:53 volumio volumio[1307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jun 01 06:11:53 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jun 01 06:11:53 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:53 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:11:54 volumio volumio[1307]: info: Starting MyVolumio Remote Streaming Endpoints
Jun 01 06:11:54 volumio volumio[1307]: info: MyVolumio login type: Token
Jun 01 06:11:54 volumio volumio[1307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jun 01 06:11:54 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jun 01 06:11:58 volumio volumio[1307]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jun 01 06:11:58 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jun 01 06:11:58 volumio volumio[1307]: info: Streaming services startup
Jun 01 06:11:58 volumio volumio[1307]: info: Starting Streaming Daemon
Jun 01 06:11:58 volumio sudo[2207]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 01 06:11:58 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:11:58 volumio volumio[1307]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jun 01 06:11:58 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root
Jun 01 06:11:58 volumio volumio[1307]: error: Cannot start Volumio Streaming Daemon
Jun 01 06:11:58 volumio volumio[1307]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jun 01 06:11:58 volumio volumio[1307]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jun 01 06:11:58 volumio volumio[1307]: info: Upmpdcli Daemon Started
Jun 01 06:11:59 volumio volumio[1307]: error: MyVolumio Custom Token format not valid, refreshing it
Jun 01 06:12:02 volumio volumio[1307]: info: MyVolumio login type: Token
Jun 01 06:12:03 volumio volumio[1307]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jun 01 06:12:03 volumio volumio[1307]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jun 01 06:12:04 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:12:04 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:04 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 01 06:12:04 volumio volumio[1307]: info: MyVolumio token set successfully
Jun 01 06:12:04 volumio volumio[1307]: info: MYVOLUMIO: Adding device
Jun 01 06:12:04 volumio volumio[1307]: info: MYVOLUMIO: Evaluating Server
Jun 01 06:12:04 volumio volumio-remote-updater[747]: Test mode disabled
Jun 01 06:12:04 volumio volumio-remote-updater[747]: Alpha mode disabled
Jun 01 06:12:04 volumio volumio-remote-updater[747]: Alpha legacy test mode disabled
Jun 01 06:12:04 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jun 01 06:12:05 volumio volumio[1307]: info: MyVolumio status changed
Jun 01 06:12:05 volumio volumio[1307]: info: Streaming services startup
Jun 01 06:12:05 volumio volumio[1307]: info: Starting Streaming Daemon
Jun 01 06:12:05 volumio volumio[1307]: info: Removing browser output: myVolumio user plan is not superstar
Jun 01 06:12:05 volumio volumio[1307]: info: Removing audio output:
Jun 01 06:12:05 volumio volumio[1307]: info: Stoppping Tunnel 1
Jun 01 06:12:05 volumio volumio[1307]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
Fix for missing PWM backlight module
Fix for crash loop on Onboarding plugin
NEW ADDITIONS
Bump RPi/CM4 kernel to 6.12.74
","title":"Update v4.119","updateavailable":true}
Jun 01 06:12:05 volumio sudo[2262]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 01 06:12:05 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:05 volumio sudo[2264]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jun 01 06:12:05 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:05 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Jun 01 06:12:05 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:05 volumio volumio[1307]: info: Setting Geolocation for MyVolumio to as1
Jun 01 06:12:05 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:05 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:05 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:05 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:06 volumio volumio[1307]: info: Remote SSH Stopped
Jun 01 06:12:06 volumio volumio[1307]: error: Cannot start Volumio Streaming Daemon
Jun 01 06:12:06 volumio volumio[1307]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jun 01 06:12:06 volumio volumio[1307]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jun 01 06:12:06 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:06 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:06 volumio volumio[1307]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 01 06:12:06 volumio volumio[1307]: info: Updating MyVolumio device info
Jun 01 06:12:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:07 volumio volumio[1307]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 01 06:12:07 volumio volumio5-onboarding[1572]: time=2026-06-01T06:12:07.838+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory"
Jun 01 06:12:07 volumio volumio5-onboarding[1572]: time=2026-06-01T06:12:07.839+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory"
Jun 01 06:12:07 volumio volumio5-onboarding[1572]: time=2026-06-01T06:12:07.839+07:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory"
Jun 01 06:12:07 volumio volumio5-onboarding[1572]: time=2026-06-01T06:12:07.839+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory"
Jun 01 06:12:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:08 volumio volumio[1307]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Jun 01 06:12:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 01 06:12:09 volumio upmpdcli[2271]: writing RSA key
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 01 06:12:09 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:09 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:09 volumio volumio[1307]: info: Listing playlists
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:09 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:09 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:09 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jun 01 06:12:10 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:10 volumio volumio[1307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jun 01 06:12:10 volumio volumio[1307]: info: MYVOLUMIO: Adding device
Jun 01 06:12:10 volumio volumio[1307]: info: MYVOLUMIO: Evaluating Server
Jun 01 06:12:10 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:10 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:11 volumio volumio5-onboarding[1572]: failed to bootstrap state: failed to get logged user: could not get MyVolumio token: context deadline exceeded
Jun 01 06:12:11 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE
Jun 01 06:12:11 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'.
Jun 01 06:12:11 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1.
Jun 01 06:12:11 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server.
Jun 01 06:12:11 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Jun 01 06:12:11 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:11.456+07:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:11 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:11 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:11 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:11 volumio volumio[1307]: info: Setting Geolocation for MyVolumio to as1
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:11 volumio volumio[1307]: 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: 8
Jun 01 06:12:11 volumio volumio[1307]: 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: 8
Jun 01 06:12:11 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:11 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:11 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:11 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:11.759+07:00 level=INFO msg="system info for 2f9048567f8323d0f4b59eacf836ec78" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096
Jun 01 06:12:11 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:11 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:11 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jun 01 06:12:11 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:11.785+07:00 level=INFO msg="enabling local network discovery"
Jun 01 06:12:11 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:11.810+07:00 level=INFO msg="bootstrapping state" hasInternet=true
Jun 01 06:12:11 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:11 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:11 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:11 volumio volumio-remote-updater[747]: Test mode disabled
Jun 01 06:12:11 volumio volumio-remote-updater[747]: Alpha mode disabled
Jun 01 06:12:11 volumio volumio-remote-updater[747]: Alpha legacy test mode disabled
Jun 01 06:12:11 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jun 01 06:12:12 volumio volumio[1307]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 01 06:12:12 volumio volumio[1307]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
Fix for missing PWM backlight module
Fix for crash loop on Onboarding plugin
NEW ADDITIONS
Bump RPi/CM4 kernel to 6.12.74
","title":"Update v4.119","updateavailable":true}
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:12 volumio volumio[1307]: info: Updating MyVolumio device info
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:13 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:13.286+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory"
Jun 01 06:12:13 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:13.287+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory"
Jun 01 06:12:13 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:13.287+07:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory"
Jun 01 06:12:13 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:13.287+07:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory"
Jun 01 06:12:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:13 volumio volumio[1307]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 01 06:12:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 01 06:12:14 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:12:15 volumio volumio5-onboarding[2296]: time=2026-06-01T06:12:15.211+07:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=IhDVqSkwAnTFDbjBzS5dETqyJu82 tokenExpiry=2026-06-01T07:12:15.211+07:00
Jun 01 06:12:17 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jun 01 06:12:18 volumio volumio[1307]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jun 01 06:12:18 volumio volumio[1307]: info: Completed starting MyVolumio Plugin
Jun 01 06:12:18 volumio volumio[1307]:  [Metrics]  CommandRouter:  60s 5.42ms 
Jun 01 06:12:18 volumio volumio[1307]: info: CoreCommandRouter::volumiosetStartupVolume
Jun 01 06:12:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 01 06:12:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 01 06:12:18 volumio volumio[1307]: info: CoreCommandRouter::Close All Modals sent
Jun 01 06:12:18 volumio volumio[1307]: info: CoreCommandRouter::Close All Modals sent
Jun 01 06:12:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jun 01 06:12:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 01 06:12:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jun 01 06:12:23 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1
Jun 01 06:12:23 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd12210 00000000 00000000 0e000000 04028000
Jun 01 06:12:25 volumio volumio[1307]: info: BOOT COMPLETED
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 01 06:12:25 volumio volumio[1307]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jun 01 06:12:25 volumio volumio[1307]: info: Received Get System Version
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:12:25 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:25 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:25 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:25 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:32 volumio sudo[2348]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 01 06:12:32 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:32 volumio sudo[2350]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 01 06:12:32 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:32 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:32 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:32 volumio volumio[1307]: verbose: New Socket.io Connection to 192.168.2.38 from 192.168.2.34 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Jun 01 06:12:32 volumio sudo[2354]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 01 06:12:32 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:32 volumio sudo[2356]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 01 06:12:32 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 01 06:12:32 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:32 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root
Jun 01 06:12:32 volumio volumio[1307]: verbose: New Socket.io Connection to 192.168.2.38 from 192.168.2.34 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:34 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetQueue
Jun 01 06:12:34 volumio volumio[1307]: info: CoreStateMachine::getQueue
Jun 01 06:12:34 volumio volumio[1307]: info: CorePlayQueue::getQueue
Jun 01 06:12:34 volumio volumio[1307]: info: Listing playlists
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 01 06:12:34 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:34 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:34 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:34 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:34 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:34 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:34 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:34 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 01 06:12:36 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jun 01 06:12:37 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:37 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:37 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:37 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:37 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:37 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:37 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:37 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:38 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:38 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:38 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:38 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:38 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:38 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:38 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:38 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 01 06:12:39 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:39 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:39 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:39 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:39 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:42 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 01 06:12:51 volumio volumio[1307]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jun 01 06:12:51 volumio volumio[1307]: info: Received Get System Version
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 01 06:12:51 volumio volumio[1307]: info: Received Get System Info
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 01 06:12:51 volumio volumio[1307]: info: Discovery: Getting this device information
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState
Jun 01 06:12:51 volumio volumio[1307]: info: CorePlayQueue::getTrack 0
Jun 01 06:12:51 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 01 06:12:53 volumio volumio[1307]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jun 01 06:12:53 volumio volumio[1307]: info: CoreStateMachine::ClearQueue
Jun 01 06:12:53 volumio volumio[1307]: info: CoreStateMachine::stop
Jun 01 06:12:53 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 01 06:12:53 volumio volumio[1307]: info: CorePlayQueue::clearPlayQueue
Jun 01 06:12:53 volumio volumio[1307]: info: CorePlayQueue::saveQueue
Jun 01 06:12:53 volumio volumio[1307]: info: CoreCommandRouter::volumioPushQueue
Jun 01 06:12:53 volumio volumio[1307]: info: CoreStateMachine::addQueueItems
Jun 01 06:12:53 volumio volumio[1307]: info: CorePlayQueue::addQueueItems
Jun 01 06:12:53 volumio volumio[1307]: info: Preload queue cleared
Jun 01 06:12:53 volumio volumio[1307]: info: Adding Item to queue: music-library/NAS/Nas_Music/Audiophile/Best Audiophile Vocal (2014)
Jun 01 06:12:53 volumio volumio[1307]: info: Exploding uri music-library/NAS/Nas_Music/Audiophile/Best Audiophile Vocal (2014) in service mpd
Jun 01 06:12:53 volumio volumio[1307]: info: ALBUMART /albumart?cacheid=223&web=Delicatessen/Best%20Audiophile%20Vocal/extralarge&path=%2Fmnt%2FNAS%2FNas_Music%2FAudiophile%2FBest%20Audiophile%20Vocal%20(2014)%2FVarious%20Artists%20-%20Best%20Audiophile%20Vocal.cue&metadata=false
Jun 01 06:12:53 volumio volumio[1307]: info: URI /mnt/NAS/Nas_Music/Audiophile/Best Audiophile Vocal (2014)/Various Artists - Best Audiophile Vocal.cue
Jun 01 06:12:53 volumio volumio[1307]: info: ALBUMART /albumart?cacheid=223&web=Malene%20Mortensen/Best%20Audiophile%20Vocal/extralarge&path=%2Fmnt%2FNAS%2FNas_Music%2FAudiophile%2FBest%20Audiophile%20Vocal%20(2014)%2FVarious%20Artists%20-%20Best%20Audiophile%20Vocal.cue&metadata=false
Jun 01 06:12:53 volumio volumio[1307]: info: URI /mnt/NAS/Nas_Music/Audiophile/Best Audiophile Vocal (2014)/Various Artists - Best Audiophile Vocal.cue
Jun 01 06:12:53 volumio volumio[1307]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 01 06:12:53 volumio volumio[1307]: Error: Unable to resolve or reject the same promise twice
Jun 01 06:12:53 volumio volumio[1307]:     at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Jun 01 06:12:53 volumio volumio[1307]:     at /volumio/app/plugins/music_service/mpd/index.js:2582:21
Jun 01 06:12:53 volumio volumio[1307]:     at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Jun 01 06:12:53 volumio volumio[1307]:     at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Jun 01 06:12:53 volumio volumio[1307]:     at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Jun 01 06:12:53 volumio volumio[1307]:     at Socket.emit (node:events:514:28)
Jun 01 06:12:53 volumio volumio[1307]:     at addChunk (node:internal/streams/readable:343:12)
Jun 01 06:12:53 volumio volumio[1307]:     at readableAddChunk (node:internal/streams/readable:312:11)
Jun 01 06:12:53 volumio volumio[1307]:     at Readable.push (node:internal/streams/readable:253:10)
Jun 01 06:12:53 volumio volumio[1307]:     at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)
Jun 01 06:12:53 volumio volumio[1307]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 01 06:12:56 volumio sudo[2436]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 06:11'
Jun 01 06:12:56 volumio sudo[2436]: 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"