Mar 24 12:30:00 volumio volumio[1190]: info: MYVOLUMIO Environment detected Mar 24 12:30:00 volumio volumio[1190]: info: Plugin folders cleanup Mar 24 12:30:00 volumio volumio[1190]: info: Scanning into folder /volumio/app/plugins/ Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category audio_interface Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category miscellanea Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category music_service Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category plugins.json Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category system_controller Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category user_interface Mar 24 12:30:00 volumio volumio[1190]: info: Scanning into folder /data/plugins/ Mar 24 12:30:00 volumio volumio[1190]: info: Scanning category user_interface Mar 24 12:30:00 volumio volumio[1190]: info: Plugin folders cleanup completed Mar 24 12:30:00 volumio volumio[1190]: info: ------------------------------------------- Mar 24 12:30:00 volumio volumio[1190]: info: ----- Core plugins startup ---- Mar 24 12:30:00 volumio volumio[1190]: info: ------------------------------------------- Mar 24 12:30:00 volumio volumio[1190]: info: Loading plugins from folder /volumio/app/plugins/ Mar 24 12:30:00 volumio volumio[1190]: info: Adding plugin upnp to MyMusic Plugins Mar 24 12:30:00 volumio volumio[1190]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 24 12:30:00 volumio volumio[1190]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 24 12:30:00 volumio volumio[1190]: info: Loading plugins from folder /data/plugins/ Mar 24 12:30:00 volumio volumio[1190]: info: Loading plugin "system"... Mar 24 12:30:00 volumio volumio[1190]: info: Loading plugin "appearance"... Mar 24 12:30:00 volumio ntpd[907]: IO: Listen normally on 3 eth0 192.168.0.132:123 Mar 24 12:30:00 volumio ntpd[907]: IO: new interface(s) found: waking up resolver Mar 24 12:30:00 volumio ntpd[907]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 24 12:30:00 volumio ntpd[907]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 24 12:30:00 volumio ntpd[907]: DNS: Pool taking: 37.221.242.99 Mar 24 12:30:00 volumio ntpd[907]: DNS: Pool taking: 188.124.59.142 Mar 24 12:30:00 volumio ntpd[907]: DNS: Pool taking: 176.118.170.235 Mar 24 12:30:00 volumio ntpd[907]: DNS: Pool taking: 93.99.104.53 Mar 24 12:30:00 volumio ntpd[907]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "network"... Mar 24 12:30:01 volumio volumio[1190]: info: Refreshing Cached IP Addresses Mar 24 12:30:01 volumio sudo[1336]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 12:30:01 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:01 volumio sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 12:30:01 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:01 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "services"... Mar 24 12:30:01 volumio ntpd[907]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 24 12:30:01 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "volumio5onboarding"... Mar 24 12:30:01 volumio sudo[1352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 24 12:30:01 volumio ntpd[907]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 195.133.18.24 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 81.27.192.20 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 162.159.200.1 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 85.163.168.227 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 2001:15e8:110:7d00::82a Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 2001:67c:d74:66::71be Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 2a01:5e0:36:c9d4::1 Mar 24 12:30:01 volumio ntpd[907]: DNS: Pool taking: 2001:470:6f:483::101 Mar 24 12:30:01 volumio ntpd[907]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "alsa_controller"... Mar 24 12:30:01 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:01 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "wizard"... Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "networkfs"... Mar 24 12:30:01 volumio volumio[1190]: info: Starting Udev Watcher for removable devices Mar 24 12:30:01 volumio sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=roman,password=Patolog@1964,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.109/music /mnt/NAS/NAS Mar 24 12:30:01 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:01 volumio volumio[1190]: info: Ignoring mount for partition: boot Mar 24 12:30:01 volumio volumio[1190]: info: Ignoring mount for partition: volumio Mar 24 12:30:01 volumio volumio[1190]: info: Ignoring mount for partition: volumio_data Mar 24 12:30:01 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "volumio_command_line_client"... Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "upnp"... Mar 24 12:30:01 volumio volumio[1190]: info: [1774351801904] Starting Upmpd Daemon Mar 24 12:30:01 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "my_music"... Mar 24 12:30:01 volumio volumio[1190]: info: Loading plugin "mpd"... Mar 24 12:30:01 volumio kernel: netfs: FS-Cache loaded Mar 24 12:30:02 volumio kernel: Key type cifs.spnego registered Mar 24 12:30:02 volumio kernel: Key type cifs.idmap registered Mar 24 12:30:02 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. Mar 24 12:30:02 volumio kernel: CIFS: Attempting to mount //192.168.0.109/music Mar 24 12:30:02 volumio volumio[1190]: info: Loading plugin "upnp_browser"... Mar 24 12:30:02 volumio volumio-remote-updater[698]: [2026-03-24 12:30:02] [connect] Successful connection Mar 24 12:30:02 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:02 volumio ntpd[907]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 24 12:30:03 volumio volumio[1190]: info: Starting UPNP Browser Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "alarm-clock"... Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "airplay_emulation"... Mar 24 12:30:03 volumio volumio[1190]: info: Starting Shairport Sync Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "last_100"... Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "webradio"... Mar 24 12:30:03 volumio ntpd[907]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 194.182.80.44 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 194.182.84.241 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 46.28.110.153 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 78.108.102.237 Mar 24 12:30:03 volumio ntpd[907]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "i2s_dacs"... Mar 24 12:30:03 volumio volumio[1190]: info: I2S DAC not set, start Auto-detection Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "volumiodiscovery"... Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** For more information see Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 12:30:03 volumio volumio[1190]: *** WARNING *** For more information see Mar 24 12:30:03 volumio node[1190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 12:30:03 volumio node[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 12:30:03 volumio node[1190]: *** WARNING *** For more information see Mar 24 12:30:03 volumio node[1190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 12:30:03 volumio node[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 12:30:03 volumio node[1190]: *** WARNING *** For more information see Mar 24 12:30:03 volumio volumio[1190]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 24 12:30:03 volumio volumio[1190]: info: Discovery: Started advertising with name: Volumio Mar 24 12:30:03 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "outputs"... Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "albumart"... Mar 24 12:30:03 volumio volumio[1190]: info: Plugin example_plugin is not enabled Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "inputs"... Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "updater_comm"... Mar 24 12:30:03 volumio ntpd[907]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 24 12:30:03 volumio ntpd[907]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 162.159.200.123 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 109.224.72.175 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 5.1.56.123 Mar 24 12:30:03 volumio ntpd[907]: DNS: Pool taking: 89.203.251.72 Mar 24 12:30:03 volumio ntpd[907]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Mar 24 12:30:03 volumio volumio[1190]: info: Plugin mpdemulation is not enabled Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "rest_api"... Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "websocket"... Mar 24 12:30:03 volumio volumio[1190]: info: Starting Socket.io Server version 1.7.4 Mar 24 12:30:03 volumio volumio[1190]: info: Loading plugin "touch_display"... Mar 24 12:30:04 volumio volumio[1190]: info: Applying required configuration parameters for plugin touch_display Mar 24 12:30:04 volumio volumio[1415]: Forking 3 albumart workers Mar 24 12:30:04 volumio volumio[1190]: info: Loading i18n strings for locale cs Mar 24 12:30:04 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 12:30:04 volumio volumio[1190]: Updating browse sources language Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:04 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::initPlayerControls Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: Express server listening on port 3000 Mar 24 12:30:04 volumio volumio[1190]: [Metrics] WebUI: 5s 719.93ms Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::resetVolumioState Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::getcurrentVolume Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:04 volumio volumio[1190]: info: Cannot read play queue from file Mar 24 12:30:04 volumio volumio[1190]: info: Volumio Network Manager: Network status updated: 1 Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.0.132 from 192.168.0.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 24 12:30:04 volumio volumio[1190]: Unable to parse: Mar 24 12:30:04 volumio volumio[1190]: Simple mixer control 'PCM',0 Mar 24 12:30:04 volumio volumio[1190]: Capabilities: pswitch pswitch-joined Mar 24 12:30:04 volumio volumio[1190]: Playback channels: Mono Mar 24 12:30:04 volumio volumio[1190]: Mono: Playback [on] Mar 24 12:30:04 volumio volumio[1190]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.0.132 from 192.168.0.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.0.132 from 192.168.0.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 24 12:30:04 volumio volumio[1190]: Unable to parse: Mar 24 12:30:04 volumio volumio[1190]: Simple mixer control 'PCM',0 Mar 24 12:30:04 volumio volumio[1190]: Capabilities: pswitch pswitch-joined Mar 24 12:30:04 volumio volumio[1190]: Playback channels: Mono Mar 24 12:30:04 volumio volumio[1190]: Mono: Playback [on] Mar 24 12:30:04 volumio volumio[1190]: info: VolumeController:: Volume=undefined Mute =false Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::setRepeat null single undefined Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::setRandom null Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 24 12:30:04 volumio volumio[1190]: info: Setting Device type: Raspberry PI Mar 24 12:30:04 volumio volumio-remote-updater[698]: [2026-03-24 12:30:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774351802 101 Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Mar 24 12:30:04 volumio volumio[1190]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 24 12:30:04 volumio volumio[1190]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Mar 24 12:30:04 volumio volumio[1190]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.0.132 from 192.168.0.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 24 12:30:04 volumio volumio[1190]: Unable to parse: Mar 24 12:30:04 volumio volumio[1190]: Simple mixer control 'PCM',0 Mar 24 12:30:04 volumio volumio[1190]: Capabilities: pswitch pswitch-joined Mar 24 12:30:04 volumio volumio[1190]: Playback channels: Mono Mar 24 12:30:04 volumio volumio[1190]: Mono: Playback [on] Mar 24 12:30:04 volumio volumio[1190]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Mar 24 12:30:04 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 24 12:30:04 volumio volumio[1190]: info: Discovery: adding a9cb9cfb-e101-4f3a-95a7-a81d4ecc1ef8 Mar 24 12:30:04 volumio volumio[1190]: info: Discovery: Found device Volumio Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1190]: info: Discovery: this is already registered, a9cb9cfb-e101-4f3a-95a7-a81d4ecc1ef8 Mar 24 12:30:04 volumio volumio[1190]: info: Discovery: Found device Volumio Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1426]: Starting albumart workers Mar 24 12:30:04 volumio volumio[1427]: Starting albumart workers Mar 24 12:30:04 volumio volumio[1190]: Unable to parse: Mar 24 12:30:04 volumio volumio[1190]: Simple mixer control 'PCM',0 Mar 24 12:30:04 volumio volumio[1190]: Capabilities: pswitch pswitch-joined Mar 24 12:30:04 volumio volumio[1190]: Playback channels: Mono Mar 24 12:30:04 volumio volumio[1190]: Mono: Playback [on] Mar 24 12:30:04 volumio volumio[1190]: info: VolumeController:: Volume=undefined Mute =false Mar 24 12:30:04 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 24 12:30:04 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:04 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 24 12:30:04 volumio volumio[1190]: info: Completed loading Core Plugins Mar 24 12:30:04 volumio volumio[1190]: info: Preparing to generate the ALSA configuration file Mar 24 12:30:05 volumio volumio[1190]: info: Asound.conf file unchanged, so no further update is needed Mar 24 12:30:05 volumio volumio[1190]: info: Output device has changed, restarting MPD Mar 24 12:30:05 volumio volumio[1428]: Starting albumart workers Mar 24 12:30:05 volumio volumio[1190]: info: Output device has changed, restarting Shairport Sync Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:05 volumio sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 12:30:05 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 12:30:05 volumio volumio[1190]: info: ___________ START PLUGINS ___________ Mar 24 12:30:05 volumio sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 12:30:05 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio volumio[1190]: info: ControllerMpd::onStart: Initializing MPD Mar 24 12:30:05 volumio volumio[1190]: info: Creating MPD Configuration file Mar 24 12:30:05 volumio sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 24 12:30:05 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 12:30:05 volumio volumio[1190]: info: [1774351805178] CoreMusicLibrary::Adding element Média servery Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:05 volumio sudo[1488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 12:30:05 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 12:30:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 12:30:05 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio volumio[1190]: info: UPNP Browser: Client initialized successfully Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:05 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 12:30:05 volumio sudo[1490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 12:30:05 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Mar 24 12:30:05 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 12:30:05 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 12:30:05 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 24 12:30:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 12:30:05 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 24 12:30:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 12:30:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 12:30:05 volumio volumio[1190]: info: [1774351805375] CoreMusicLibrary::Adding element Last_100 Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 12:30:05 volumio volumio[1190]: info: [1774351805378] CoreMusicLibrary::Adding element Webradio Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 12:30:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:05 volumio upmpdcli[1499]: Could not open config: /tmp/upmpdcli.conf Mar 24 12:30:05 volumio volumio[1190]: info: Initializing BBC Radios Mar 24 12:30:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 12:30:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:05 volumio volumio[1190]: info: Loading i18n strings for locale cs Mar 24 12:30:05 volumio sudo[1505]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 12:30:05 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 12:30:05 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 12:30:05 volumio sudo[1525]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 24 12:30:05 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:05 volumio volumio[1190]: info: Volumio Calling Home Mar 24 12:30:05 volumio sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 24 12:30:05 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:05.777+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 12:30:05 volumio sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 24 12:30:05 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 24 12:30:05 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 24 12:30:05 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:05 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:05 volumio systemd[1]: Reloading. Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: No backlight interface detected. Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 24 12:30:06 volumio volumio[1190]: info: MPD Permissions set Mar 24 12:30:06 volumio volumio[1190]: info: MPD Permissions set Mar 24 12:30:06 volumio volumio[1190]: 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: 7 Mar 24 12:30:06 volumio volumio[1190]: info: Volumio called home Mar 24 12:30:06 volumio volumio[1190]: 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: 7 Mar 24 12:30:06 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:06 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:06 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:06 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:06.341+01:00 level=INFO msg="system info for c204afb47c8786315669c6f05b5c210b" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 12:30:06 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:06.361+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 12:30:06 volumio sudo[1582]: 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/ Mar 24 12:30:06 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:06 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:06 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:06 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:06 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:06 volumio volumio-remote-updater[698]: Test mode disabled Mar 24 12:30:06 volumio volumio-remote-updater[698]: Alpha mode disabled Mar 24 12:30:06 volumio volumio-remote-updater[698]: Alpha legacy test mode disabled Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 24 12:30:06 volumio sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 24 12:30:06 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:06 volumio volumio[1190]: info: Starting Shairport Sync Mar 24 12:30:06 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:06 volumio sudo[1603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 24 12:30:06 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:06 volumio volumio[1190]: info: Starting Shairport Sync Mar 24 12:30:06 volumio volumio[1190]: info: Starting Shairport Sync Mar 24 12:30:06 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:06 volumio volumio[1190]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 12:30:06 volumio sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 12:30:06 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 24 12:30:06 volumio volumio[1190]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:30:06 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:06.698+01: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" Mar 24 12:30:06 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:06.700+01: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" Mar 24 12:30:06 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:06.701+01: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" Mar 24 12:30:06 volumio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 12:30:06 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:30:07 volumio ntpd[907]: CLOCK: time stepped by 0.484237 Mar 24 12:30:07 volumio ntpd[907]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 24 12:30:07 volumio sudo[1607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 12:30:07 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:30:07 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:07 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:07 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:07 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 12:30:07 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 12:30:07 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:07.255+01:00 level=INFO msg="enabling local network discovery" Mar 24 12:30:07 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:07.278+01:00 level=INFO msg="enabling BLE discovery" Mar 24 12:30:07 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:07 volumio volumio[1190]: info: touch_display: systemctl daemon-reload succeeded. Mar 24 12:30:07 volumio sudo[1619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 24 12:30:07 volumio sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:07 volumio systemd[1]: Reloading. Mar 24 12:30:08 volumio volumio5-onboarding[1495]: time=2026-03-24T12:30:08.269+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 12:30:08 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 24 12:30:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 12:30:08 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 24 12:30:08 volumio sudo[1619]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 12:30:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 12:30:08 volumio systemd[1]: shairport-sync.service: Consumed 1.760s CPU time. Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: Volumio Kiosk started. Mar 24 12:30:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 12:30:08 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:08 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:08 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:08 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:08 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:08 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:08 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:08 volumio volumio[1190]: info: Shairport-Sync Started Mar 24 12:30:08 volumio volumio[1190]: Error adding Membership: Error: addMembership EINVAL Mar 24 12:30:08 volumio volumio[1190]: info: Shairport-Sync Started Mar 24 12:30:08 volumio volumio[1190]: info: Shairport-Sync Started Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: X display number found: Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: X display number found: 0 Mar 24 12:30:08 volumio startx[1679]: X.Org X Server 1.21.1.7 Mar 24 12:30:08 volumio startx[1679]: X Protocol Version 11, Revision 0 Mar 24 12:30:08 volumio startx[1679]: Current Operating System: Linux volumio 6.12.47-v8+ #1904 SMP PREEMPT Mon Sep 15 13:10:52 BST 2025 aarch64 Mar 24 12:30:08 volumio startx[1679]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1280 bcm2708_fb.fbheight=400 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:3F:C7:1B 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=14aa58b3-7c1f-46d2-b4f1-3f56bb2e4191 imgfile=/volumio_current.sqsh bootpart=UUID=5149-F647 datapart=UUID=0ee32d5b-7bbc-42b2-916c-34f329fe6377 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 Mar 24 12:30:08 volumio startx[1679]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 24 12:30:08 volumio startx[1679]: Current version of pixman: 0.44.0 Mar 24 12:30:08 volumio startx[1679]: Before reporting problems, check http://wiki.x.org Mar 24 12:30:08 volumio startx[1679]: to make sure that you have the latest version. Mar 24 12:30:08 volumio startx[1679]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 24 12:30:08 volumio startx[1679]: (++) from command line, (!!) notice, (II) informational, Mar 24 12:30:08 volumio startx[1679]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 24 12:30:08 volumio startx[1679]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Mar 24 12:30:08 2026 Mar 24 12:30:08 volumio volumio[1190]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 24 12:30:08 volumio startx[1679]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 24 12:30:08 volumio startx[1679]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 24 12:30:09 volumio volumio[1190]: info: touch_display: X display number found: 0 Mar 24 12:30:09 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.0.132 from 192.168.0.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:09 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:09 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 12:30:09 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:09 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:09 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:09 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:09 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:09 volumio volumio[1190]: info: Listing playlists Mar 24 12:30:10 volumio mpd[1529]: 2026-03-24T12:30:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 12:30:11 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:11 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:11 volumio volumio[1190]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 12:30:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 12:30:11 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:11 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:11 volumio volumio[1190]: info: Completed starting Core Plugins Mar 24 12:30:11 volumio volumio[1190]: info: ------------------------------------------- Mar 24 12:30:11 volumio volumio[1190]: info: ----- MyVolumio plugins startup ---- Mar 24 12:30:11 volumio volumio[1190]: info: ------------------------------------------- Mar 24 12:30:11 volumio volumio[1190]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 24 12:30:11 volumio volumio[1190]: error: MPD error: The expression evaluated to a falsy value: Mar 24 12:30:11 volumio volumio[1190]: assert.ok(self.idling) Mar 24 12:30:11 volumio volumio[1190]: error: The expression evaluated to a falsy value: Mar 24 12:30:11 volumio volumio[1190]: assert.ok(self.idling) Mar 24 12:30:11 volumio volumio[1190]: info: MPD running with PID1529 Mar 24 12:30:11 volumio volumio[1190]: ,establishing connection Mar 24 12:30:11 volumio volumio[1190]: error: updateQueue error: null Mar 24 12:30:11 volumio volumio[1190]: error: updateQueue error: null Mar 24 12:30:12 volumio sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 12:30:12 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:12 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:12 volumio sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 12:30:12 volumio sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:12 volumio sudo[1778]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:12 volumio sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 12:30:12 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:16 volumio volumio[1190]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 24 12:30:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin bluetooth to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin multiroom to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin metavolumio to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin cd_controller to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 24 12:30:20 volumio volumio[1190]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 24 12:30:21 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 24 12:30:21 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 12:30:21 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 12:30:21 volumio sudo[1781]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:21 volumio volumio[1190]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 24 12:30:21 volumio volumio[1190]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 24 12:30:21 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:21 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:21 volumio volumio[1190]: info: Starting MyVolumio Remote Streaming Endpoints Mar 24 12:30:21 volumio volumio[1190]: info: MyVolumio login type: Token Mar 24 12:30:21 volumio volumio[1190]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 24 12:30:21 volumio volumio[1190]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 24 12:30:22 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 24 12:30:22 volumio volumio[1190]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 24 12:30:22 volumio volumio[1190]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 24 12:30:22 volumio volumio[1190]: info: Streaming services startup Mar 24 12:30:22 volumio volumio[1190]: info: Starting Streaming Daemon Mar 24 12:30:22 volumio sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 12:30:22 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:22 volumio volumio[1190]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 24 12:30:22 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:22 volumio volumio[1190]: error: Cannot start Volumio Streaming Daemon Mar 24 12:30:22 volumio volumio[1190]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 12:30:22 volumio volumio[1190]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 12:30:22 volumio volumio[1190]: info: Upmpdcli Daemon Started Mar 24 12:30:23 volumio volumio[1190]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 24 12:30:24 volumio volumio[1190]: info: MyVolumio token set successfully Mar 24 12:30:24 volumio volumio[1190]: info: MYVOLUMIO: Adding device Mar 24 12:30:24 volumio volumio[1190]: info: MYVOLUMIO: Evaluating Server Mar 24 12:30:24 volumio volumio[1190]: info: MyVolumio status changed Mar 24 12:30:24 volumio volumio[1190]: info: Streaming services startup Mar 24 12:30:24 volumio volumio[1190]: info: Starting Streaming Daemon Mar 24 12:30:24 volumio volumio[1190]: info: Removing browser output: myVolumio user plan is not superstar Mar 24 12:30:24 volumio volumio[1190]: info: Removing audio output: Mar 24 12:30:24 volumio volumio[1190]: info: Stoppping Tunnel 1 Mar 24 12:30:24 volumio sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 12:30:24 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:24 volumio sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 24 12:30:24 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 12:30:24 volumio volumio[1190]: 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/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 24 12:30:24 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 volumio volumio[1190]: error: Cannot start Volumio Streaming Daemon Mar 24 12:30:24 volumio volumio[1190]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 12:30:24 volumio volumio[1190]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 12:30:24 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. Mar 24 12:30:24 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. Mar 24 12:30:24 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Mar 24 12:30:24 volumio volumio[1190]: info: Remote SSH Stopped Mar 24 12:30:24 volumio volumio[1190]: info: Setting Geolocation for MyVolumio to eu4 Mar 24 12:30:24 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:24 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:24 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:25 volumio volumio[1190]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 12:30:25 volumio volumio[1190]: info: Updating MyVolumio device info Mar 24 12:30:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:26 volumio volumio[1190]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 12:30:26 volumio volumio[1190]: 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/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:27 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 12:30:27 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:27 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:27 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:27 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:27 volumio volumio[1190]: info: Listing playlists Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:30:27 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:27 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:27 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:27 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 12:30:29 volumio volumio[1190]: info: MYVOLUMIO: Adding device Mar 24 12:30:29 volumio volumio[1190]: info: MYVOLUMIO: Evaluating Server Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:30:29 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:29 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:29 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:29 volumio volumio[1190]: info: Setting Geolocation for MyVolumio to eu4 Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:29 volumio volumio[1190]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 12:30:30 volumio volumio[1190]: info: Updating MyVolumio device info Mar 24 12:30:30 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:30 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:30 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:30 volumio volumio[1190]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 12:30:34 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 24 12:30:35 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 12:30:35 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 12:30:36 volumio volumio-remote-updater[698]: Test mode disabled Mar 24 12:30:36 volumio volumio-remote-updater[698]: Alpha mode disabled Mar 24 12:30:36 volumio volumio-remote-updater[698]: Alpha legacy test mode disabled Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 24 12:30:36 volumio volumio[1190]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 12:30:36 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 24 12:30:36 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:36 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 24 12:30:37 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 24 12:30:37 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 24 12:30:39 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 12:30:39 volumio volumio[1190]: info: CURURI: music-library Mar 24 12:30:39 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:30:40 volumio upmpdcli[2034]: writing RSA key Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 12:30:41 volumio volumio[1190]: info: CURURI: music-library/NAS Mar 24 12:30:41 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 12:30:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 12:30:42 volumio volumio[1190]: info: CURURI: music-library/NAS/NAS Mar 24 12:30:42 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:30:42 volumio volumio[1190]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 24 12:30:42 volumio volumio[1190]: info: Completed starting MyVolumio Plugin Mar 24 12:30:42 volumio volumio[1190]: [Metrics] CommandRouter: 42s 739.84ms Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::volumiosetStartupVolume Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::Close All Modals sent Mar 24 12:30:42 volumio volumio[1190]: info: CoreCommandRouter::Close All Modals sent Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 12:30:43 volumio volumio[1190]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 24 12:30:43 volumio volumio[1190]: info: Received Get System Version Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 12:30:43 volumio volumio[1190]: info: Received Get System Info Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:30:43 volumio volumio[1190]: info: Discovery: Getting this device information Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 24 12:30:43 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 24 12:30:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:30:49 volumio volumio[1190]: info: BOOT COMPLETED Mar 24 12:30:57 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 12:30:57 volumio volumio[1190]: info: CURURI: music-library/NAS/NAS/WAV Mar 24 12:30:57 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:31:00 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:31:00 volumio volumio[1190]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 12:31:00 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Mar 24 12:31:00 volumio volumio[1190]: info: CoreStateMachine::stop Mar 24 12:31:00 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 12:31:00 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Mar 24 12:31:00 volumio volumio[1190]: info: CorePlayQueue::saveQueue Mar 24 12:31:00 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Mar 24 12:31:00 volumio volumio[1190]: info: CoreStateMachine::addQueueItems Mar 24 12:31:00 volumio volumio[1190]: info: CorePlayQueue::addQueueItems Mar 24 12:31:00 volumio volumio[1190]: info: Preload queue cleared Mar 24 12:31:00 volumio volumio[1190]: info: Adding Item to queue: music-library/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack] Mar 24 12:31:00 volumio volumio[1190]: info: Exploding uri music-library/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack] in service mpd Mar 24 12:31:00 volumio volumio[1190]: info: ALBUMART /albumart?cacheid=687&web=Cretu%20And%20Thiers%20/School's%20Out%20(12''%20Single)/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FWAV%2FCretu%20And%20Thiers%20-%20School's%20Out%20(1987)%20(12''%20Single)%5BWavPack%5D%2FCretu%20And%20Thiers%20-%20School's%20Out%20(12''%20Single)%20(Side%20A).wv&metadata=false Mar 24 12:31:00 volumio volumio[1190]: info: URI /mnt/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack]/Cretu And Thiers - School's Out (12'' Single) (Side A).wv Mar 24 12:31:00 volumio volumio[1190]: info: ALBUMART /albumart?cacheid=687&web=Cretu%20And%20Thiers/School's%20Out%20(12''%20Single)/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FWAV%2FCretu%20And%20Thiers%20-%20School's%20Out%20(1987)%20(12''%20Single)%5BWavPack%5D%2FCretu%20And%20Thiers%20-%20School's%20Out%20(12''%20Single)%20(Side%20B).wv&metadata=false Mar 24 12:31:00 volumio volumio[1190]: info: URI /mnt/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack]/Cretu And Thiers - School's Out (12'' Single) (Side B).wv Mar 24 12:31:00 volumio volumio[1190]: info: ALBUMART /albumart?cacheid=687&web=Cretu%20And%20Thiers/School's%20Out%20(12''%20Single)%20(Virgin%20608%20853%2C%20Germany)/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FWAV%2FCretu%20And%20Thiers%20-%20School's%20Out%20(1987)%20(12''%20Single)%5BWavPack%5D%2FCretu%20And%20Thiers%20-%20School's%20Out%20(12''%20Single).cue&metadata=false Mar 24 12:31:00 volumio volumio[1190]: info: URI /mnt/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack]/Cretu And Thiers - School's Out (12'' Single).cue Mar 24 12:31:00 volumio volumio[1190]: info: ALBUMART /albumart?cacheid=687&web=Cretu%20And%20Thiers/School's%20Out%20(12''%20Single)%20(Virgin%20608%20853%2C%20Germany)/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FWAV%2FCretu%20And%20Thiers%20-%20School's%20Out%20(1987)%20(12''%20Single)%5BWavPack%5D%2FCretu%20And%20Thiers%20-%20School's%20Out%20(12''%20Single).cue&metadata=false Mar 24 12:31:00 volumio volumio[1190]: info: URI /mnt/NAS/NAS/WAV/Cretu And Thiers - School's Out (1987) (12'' Single)[WavPack]/Cretu And Thiers - School's Out (12'' Single).cue Mar 24 12:31:00 volumio volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 12:31:00 volumio volumio[1190]: Error: Unable to resolve or reject the same promise twice Mar 24 12:31:00 volumio volumio[1190]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 24 12:31:00 volumio volumio[1190]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 24 12:31:00 volumio volumio[1190]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 24 12:31:00 volumio volumio[1190]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 24 12:31:00 volumio volumio[1190]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 24 12:31:00 volumio volumio[1190]: at Socket.emit (node:events:514:28) Mar 24 12:31:00 volumio volumio[1190]: at addChunk (node:internal/streams/readable:343:12) Mar 24 12:31:00 volumio volumio[1190]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 24 12:31:00 volumio volumio[1190]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 12:31:00 volumio volumio[1190]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 12:31:00 volumio volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 12:31:00 volumio sudo[2104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 12:30' Mar 24 12:31:00 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"