Mar 28 16:55:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 8 Mar 28 16:55:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:08 volumio volumio[1307]: info: Prefetching next song Mar 28 16:55:08 volumio volumio[1307]: info: [1774713308816] ControllerQobuz::prefetch Mar 28 16:55:09 volumio volumio[1307]: info: getStreamUrl took 263 milliseconds Mar 28 16:55:09 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=8371867&eid=200813233&fmt=27&profile=raw&app_id=539451548&cid=3358587&etsp=1774716909&hmac=zTIXD7HSXeZzQFEQBWGfgoxXebI" Mar 28 16:55:09 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8371867&eid=200813233&fmt=27&profile=raw&app_id=539451548&cid=3358587&etsp=1774716909&hmac=zTIXD7HSXeZzQFEQBWGfgoxXebI" Mar 28 16:55:09 volumio volumio[1307]: info: Mar 28 16:55:09 volumio volumio[1307]: ---------------------------- MPD announces system playlist update Mar 28 16:55:09 volumio volumio[1307]: info: Ignoring MPD Status Update Mar 28 16:55:09 volumio volumio[1307]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8371867&eid=200813233&fmt=27&profile=raw&app_id=539451548&cid=3358587&etsp=1774716909&hmac=zTIXD7HSXeZzQFEQBWGfgoxXebI" took 1 milliseconds Mar 28 16:55:09 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 16:55:09 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 28 16:55:09 volumio volumio[1307]: info: ------------------------------ 0ms Mar 28 16:55:09 volumio volumio[1307]: info: sendMpdCommand consume 1 took 0 milliseconds Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::startPlaybackTimer Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: Mar 28 16:55:13 volumio volumio[1307]: ---------------------------- MPD announces system playlist update Mar 28 16:55:13 volumio volumio[1307]: info: Ignoring MPD Status Update Mar 28 16:55:13 volumio volumio[1307]: info: Mar 28 16:55:13 volumio volumio[1307]: ---------------------------- MPD announces state update: player Mar 28 16:55:13 volumio volumio[1307]: info: ControllerMpd::getState Mar 28 16:55:13 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Mar 28 16:55:13 volumio volumio[1307]: info: ------------------------------ 0ms Mar 28 16:55:13 volumio volumio[1307]: info: sendMpdCommand status took 0 milliseconds Mar 28 16:55:13 volumio volumio[1307]: verbose: ControllerMpd::parseState Mar 28 16:55:13 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 16:55:13 volumio volumio[1307]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 16:55:13 volumio volumio[1307]: verbose: ControllerMpd::parseTrackInfo Mar 28 16:55:13 volumio volumio[1307]: info: ControllerMpd::pushState Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2952 Kbps","isStreaming":false,"title":"file?uid=8371867&eid=200813233&fmt=27&profile=raw&app_id=539451548&cid=3358587&etsp=1774716909&hmac=zTIXD7HSXeZzQFEQBWGfgoxXebI","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8371867&eid=200813233&fmt=27&profile=raw&app_id=539451548&cid=3358587&etsp=1774716909&hmac=zTIXD7HSXeZzQFEQBWGfgoxXebI","trackType":"qobuz"} Mar 28 16:55:13 volumio volumio[1307]: verbose: CURRENT POSITION 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::syncState stateService play Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::syncState currentStatus play Mar 28 16:55:13 volumio volumio[1307]: info: Received an update from plugin. extracting info from payload Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 28 16:55:13 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Mar 28 16:55:13 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:13 volumio volumio[1307]: info: ------------------------------ 7ms Mar 28 16:55:21 volumio volumio[1307]: verbose: New Socket.io Connection to volumio.local from 192.168.1.82 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Mar 28 16:55:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 9 Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::volumioGetQueue Mar 28 16:55:21 volumio volumio[1307]: info: CoreStateMachine::getQueue Mar 28 16:55:21 volumio volumio[1307]: info: CorePlayQueue::getQueue Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Mar 28 16:55:21 volumio volumio[1307]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Mar 28 16:55:21 volumio volumio[1307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Mar 28 16:55:21 volumio volumio[1307]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Mar 28 16:55:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Mar 28 16:56:19 volumio volumio[1307]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 16:56:19 volumio volumio[1307]: TypeError: Cannot read properties of null (reading 'slice') Mar 28 16:56:19 volumio volumio[1307]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 16:56:19 volumio volumio[1307]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 16:56:19 volumio volumio[1307]: at Object.onceWrapper (node:events:629:26) Mar 28 16:56:19 volumio volumio[1307]: at ClientRequest.emit (node:events:514:28) Mar 28 16:56:19 volumio volumio[1307]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 28 16:56:19 volumio volumio[1307]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 28 16:56:19 volumio volumio[1307]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 28 16:56:19 volumio volumio[1307]: at TLSSocket.emit (node:events:514:28) Mar 28 16:56:19 volumio volumio[1307]: at addChunk (node:internal/streams/readable:343:12) Mar 28 16:56:19 volumio volumio[1307]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 28 16:56:19 volumio volumio[1307]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 16:56:19 volumio volumio[1307]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 16:56:19 volumio volumio[1307]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 16:56:19 volumio sudo[18348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 16:55' Mar 28 16:56:19 volumio sudo[18348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:19 volumio sudo[18348]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:19 volumio volumio-remote-updater[853]: [2026-03-28 16:56:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 28 16:56:19 volumio volumio-remote-updater[853]: [2026-03-28 16:56:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 28 16:56:19 volumio qobuz-connect[2347]: 20260328 16:56:19.561 [2347.2347] INFO SampleApp: Socket closed Mar 28 16:56:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 28 16:56:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 28 16:56:19 volumio systemd[1]: volumio.service: Consumed 1min 29.638s CPU time. Mar 28 16:56:19 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 28 16:56:19 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 28 16:56:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 28 16:56:19 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 28 16:56:19 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 28 16:56:19 volumio systemd[1]: volumio.service: Consumed 1min 29.638s CPU time. Mar 28 16:56:19 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Mar 28 16:56:19 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 28 16:56:20 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:20 volumio volumio[18400]: info: ----- Volumio3 ---- Mar 28 16:56:20 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:20 volumio volumio[18400]: info: ----- System startup ---- Mar 28 16:56:20 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:20 volumio volumio[18400]: info: MYVOLUMIO Environment detected Mar 28 16:56:20 volumio volumio[18400]: info: Plugin folders cleanup Mar 28 16:56:20 volumio volumio[18400]: info: Scanning into folder /volumio/app/plugins/ Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category audio_interface Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category miscellanea Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category music_service Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category plugins.json Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category system_controller Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category user_interface Mar 28 16:56:20 volumio volumio[18400]: info: Scanning into folder /data/plugins/ Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category music_service Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category system_controller Mar 28 16:56:20 volumio volumio[18400]: info: Scanning category system_hardware Mar 28 16:56:20 volumio volumio[18400]: info: Plugin folders cleanup completed Mar 28 16:56:20 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:20 volumio volumio[18400]: info: ----- Core plugins startup ---- Mar 28 16:56:20 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugins from folder /volumio/app/plugins/ Mar 28 16:56:20 volumio volumio[18400]: info: Adding plugin upnp to MyMusic Plugins Mar 28 16:56:20 volumio volumio[18400]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 28 16:56:20 volumio volumio[18400]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugins from folder /data/plugins/ Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "system"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "appearance"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "network"... Mar 28 16:56:20 volumio volumio[18400]: info: Refreshing Cached IP Addresses Mar 28 16:56:20 volumio sudo[18428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 16:56:20 volumio sudo[18428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:20 volumio sudo[18430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 16:56:20 volumio sudo[18428]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:20 volumio sudo[18430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "services"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "alsa_controller"... Mar 28 16:56:20 volumio sudo[18430]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:20 volumio sudo[18440]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 28 16:56:20 volumio sudo[18440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:20 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "wizard"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "networkfs"... Mar 28 16:56:20 volumio volumio[18400]: info: Starting Udev Watcher for removable devices Mar 28 16:56:20 volumio volumio[18400]: info: Ignoring mount for partition: boot Mar 28 16:56:20 volumio volumio[18400]: info: Ignoring mount for partition: volumio Mar 28 16:56:20 volumio volumio[18400]: info: Ignoring mount for partition: volumio_data Mar 28 16:56:20 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "volumio_command_line_client"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "upnp"... Mar 28 16:56:20 volumio volumio[18400]: info: [1774713380481] Starting Upmpd Daemon Mar 28 16:56:20 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "my_music"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "mpd"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "upnp_browser"... Mar 28 16:56:20 volumio volumio[18400]: info: Starting UPNP Browser Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "alarm-clock"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "airplay_emulation"... Mar 28 16:56:20 volumio volumio[18400]: info: Starting Shairport Sync Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "last_100"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "webradio"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "i2s_dacs"... Mar 28 16:56:20 volumio volumio[18400]: info: I2S DAC not set, start Auto-detection Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "volumiodiscovery"... Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** For more information see Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 16:56:20 volumio volumio[18400]: *** WARNING *** For more information see Mar 28 16:56:20 volumio node[18400]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 16:56:20 volumio node[18400]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 16:56:20 volumio node[18400]: *** WARNING *** For more information see Mar 28 16:56:20 volumio node[18400]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 28 16:56:20 volumio node[18400]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 16:56:20 volumio node[18400]: *** WARNING *** For more information see Mar 28 16:56:20 volumio volumio[18400]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 28 16:56:20 volumio volumio[18400]: info: Discovery: Started advertising with name: Volumio Mar 28 16:56:20 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 16:56:20 volumio volumio[18400]: info: Plugin spop is not enabled Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "outputs"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "albumart"... Mar 28 16:56:20 volumio volumio[18400]: info: Plugin example_plugin is not enabled Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "inputs"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "updater_comm"... Mar 28 16:56:20 volumio volumio[18400]: info: Plugin mpdemulation is not enabled Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "rest_api"... Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "websocket"... Mar 28 16:56:20 volumio volumio[18400]: info: Starting Socket.io Server version 1.7.4 Mar 28 16:56:20 volumio volumio[18400]: info: Plugin backup_restore is not enabled Mar 28 16:56:20 volumio volumio[18400]: info: Plugin Bluetoothremote is not enabled Mar 28 16:56:20 volumio volumio[18400]: info: Loading plugin "music_services_shield"... Mar 28 16:56:20 volumio volumio[18464]: Forking 3 albumart workers Mar 28 16:56:21 volumio volumio[18400]: info: Applying required configuration parameters for plugin music_services_shield Mar 28 16:56:21 volumio volumio[18400]: info: Loading i18n strings for locale it Mar 28 16:56:21 volumio volumio[18400]: Updating browse sources language Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::initPlayerControls Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: Express server listening on port 3000 Mar 28 16:56:21 volumio volumio[18400]: [Metrics] WebUI: 1s 228.73ms Mar 28 16:56:21 volumio volumio[18400]: info: Setting Device type: x86 Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::resetVolumioState Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::getcurrentVolume Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 28 16:56:21 volumio volumio[18400]: info: Completed loading Core Plugins Mar 28 16:56:21 volumio volumio[18400]: info: Preparing to generate the ALSA configuration file Mar 28 16:56:21 volumio volumio[18400]: info: Volumio Network Manager: Network status updated: 1 Mar 28 16:56:21 volumio volumio[18400]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 28 16:56:21 volumio volumio[18400]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 28 16:56:21 volumio volumio[18400]: verbose: New Socket.io Connection to volumio.local from 192.168.1.82 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 28 16:56:21 volumio volumio[18476]: Starting albumart workers Mar 28 16:56:21 volumio volumio[18400]: info: Reloading queue from file Mar 28 16:56:21 volumio volumio[18400]: verbose: New Socket.io Connection to 192.168.1.188 from 192.168.1.53 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::setRepeat false single undefined Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::pushState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioPushState Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::setRandom false Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::pushState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioPushState Mar 28 16:56:21 volumio volumio[18400]: info: Asound.conf file unchanged, so no further update is needed Mar 28 16:56:21 volumio volumio[18400]: info: Output device has changed, restarting MPD Mar 28 16:56:21 volumio volumio[18474]: Starting albumart workers Mar 28 16:56:21 volumio volumio[18400]: info: Output device has changed, restarting Shairport Sync Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio sudo[18512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 16:56:21 volumio sudo[18512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio sudo[18512]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio volumio[18475]: Starting albumart workers Mar 28 16:56:21 volumio sudo[18513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 16:56:21 volumio sudo[18513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio volumio[18400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 16:56:21 volumio volumio[18400]: info: ___________ START PLUGINS ___________ Mar 28 16:56:21 volumio volumio[18400]: info: ControllerMpd::onStart: Initializing MPD Mar 28 16:56:21 volumio volumio[18400]: info: Creating MPD Configuration file Mar 28 16:56:21 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 28 16:56:21 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 28 16:56:21 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 28 16:56:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 16:56:21 volumio sudo[18521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 16:56:21 volumio volumio[18400]: info: [1774713381343] CoreMusicLibrary::Adding element Server multimediali Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:21 volumio sudo[18521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio volumio[18400]: info: UPNP Browser: Client initialized successfully Mar 28 16:56:21 volumio sudo[18524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 16:56:21 volumio sudo[18521]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 16:56:21 volumio sudo[18524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 16:56:21 volumio systemd[1]: mpd.service: Consumed 30min 12.750s CPU time. Mar 28 16:56:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 16:56:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 16:56:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 16:56:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 16:56:21 volumio volumio[18400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 16:56:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 16:56:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 16:56:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 16:56:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 16:56:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 16:56:21 volumio volumio[18400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 16:56:21 volumio volumio[18400]: info: [1774713381414] CoreMusicLibrary::Adding element Last_100 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 16:56:21 volumio volumio[18400]: info: [1774713381415] CoreMusicLibrary::Adding element Webradio Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 16:56:21 volumio volumio[18400]: info: Initializing BBC Radios Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio sudo[18537]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 16:56:21 volumio sudo[18537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 16:56:21 volumio sudo[18537]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio volumio[18400]: info: Volumio Calling Home Mar 28 16:56:21 volumio sudo[18604]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 28 16:56:21 volumio sudo[18604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio volumio[18400]: info: VolumeController:: Volume=100 Mute =false Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::pushState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioPushState Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::updateTrackBlock Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrackBlock Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 16:56:21 volumio volumio[18400]: info: MPD Permissions set Mar 28 16:56:21 volumio volumio[18400]: info: MPD Permissions set Mar 28 16:56:21 volumio volumio[18400]: info: VolumeController:: Volume=100 Mute =false Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::pushState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioPushState Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 28 16:56:21 volumio volumio[18400]: info: Starting Shairport Sync Mar 28 16:56:21 volumio volumio[18400]: info: Starting Shairport Sync Mar 28 16:56:21 volumio volumio[18400]: info: Starting Shairport Sync Mar 28 16:56:21 volumio sudo[18643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 16:56:21 volumio sudo[18643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio sudo[18645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 16:56:21 volumio sudo[18645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio sudo[18647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 16:56:21 volumio sudo[18647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:21 volumio volumio[18400]: info: Volumio called home Mar 28 16:56:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 16:56:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 16:56:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 16:56:21 volumio systemd[1]: shairport-sync.service: Consumed 14.407s CPU time. Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetQueue Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::getQueue Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getQueue Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Mar 28 16:56:21 volumio volumio[18400]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Mar 28 16:56:21 volumio volumio[18400]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Mar 28 16:56:21 volumio volumio[18400]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Mar 28 16:56:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 16:56:21 volumio sudo[18643]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio sudo[18645]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio sudo[18647]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: Shairport-Sync Started Mar 28 16:56:21 volumio volumio[18400]: Error adding Membership: Error: addMembership EINVAL Mar 28 16:56:21 volumio volumio[18400]: info: Shairport-Sync Started Mar 28 16:56:21 volumio volumio[18400]: info: Shairport-Sync Started Mar 28 16:56:21 volumio volumio[18400]: info: Discovery: adding 8efd9a97-a74f-429f-820b-1252ee9d1ebe Mar 28 16:56:21 volumio volumio[18400]: info: Discovery: Found device Volumio Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: Discovery: this is already registered, 8efd9a97-a74f-429f-820b-1252ee9d1ebe Mar 28 16:56:21 volumio volumio[18400]: info: Discovery: Found device Volumio Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:21 volumio volumio[18400]: info: Listing playlists Mar 28 16:56:21 volumio volumio[18400]: info: CoreCommandRouter::volumioGetQueue Mar 28 16:56:21 volumio volumio[18400]: info: CoreStateMachine::getQueue Mar 28 16:56:21 volumio volumio[18400]: info: CorePlayQueue::getQueue Mar 28 16:56:21 volumio mpd[18556]: 2026-03-28T16:56:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 16:56:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 16:56:22 volumio sudo[18524]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:22 volumio sudo[18513]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:22 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 28 16:56:22 volumio volumio[18400]: error: MPD error: The expression evaluated to a falsy value: Mar 28 16:56:22 volumio volumio[18400]: assert.ok(self.idling) Mar 28 16:56:22 volumio volumio[18400]: error: The expression evaluated to a falsy value: Mar 28 16:56:22 volumio volumio[18400]: assert.ok(self.idling) Mar 28 16:56:22 volumio volumio[18400]: info: MPD running with PID18556 Mar 28 16:56:22 volumio volumio[18400]: ,establishing connection Mar 28 16:56:22 volumio volumio[18400]: error: updateQueue error: null Mar 28 16:56:22 volumio volumio[18400]: error: updateQueue error: null Mar 28 16:56:22 volumio volumio[18400]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 28 16:56:22 volumio sudo[18604]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:22 volumio volumio[18400]: info: Completed starting Core Plugins Mar 28 16:56:22 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:22 volumio volumio[18400]: info: ----- MyVolumio plugins startup ---- Mar 28 16:56:22 volumio volumio[18400]: info: ------------------------------------------- Mar 28 16:56:22 volumio volumio[18400]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 28 16:56:22 volumio volumio[18400]: info: CoreCommandRouter::volumioGetState Mar 28 16:56:22 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:22 volumio bash[18700]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 28 16:56:22 volumio bash[18700]: dmesg(1) may have more information after failed mount system call. Mar 28 16:56:22 volumio sudo[18698]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 28 16:56:22 volumio sudo[18698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:22 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 16:56:22 volumio volumio[18400]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 16:56:22 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 16:56:22 volumio volumio[18400]: info: Listing playlists Mar 28 16:56:22 volumio bash[18720]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 28 16:56:22 volumio bash[18720]: dmesg(1) may have more information after failed mount system call. Mar 28 16:56:22 volumio sudo[18440]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:22 volumio bash[18726]: mount: /cpusets: unknown filesystem type 'cpuset'. Mar 28 16:56:22 volumio bash[18726]: dmesg(1) may have more information after failed mount system call. Mar 28 16:56:22 volumio bash[18724]: cset: **> mount of cpuset filesystem failed, do you have permission? Mar 28 16:56:22 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 28 16:56:22 volumio systemd[1]: Reloading. Mar 28 16:56:22 volumio systemd-gpt-auto-generator[18743]: EFI loader partition unknown, exiting. Mar 28 16:56:22 volumio systemd-gpt-auto-generator[18743]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Mar 28 16:56:22 volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Mar 28 16:56:22 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 28 16:56:22 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 28 16:56:22 volumio sudo[18698]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:22 volumio volumio[18400]: info: succeeded Mar 28 16:56:24 volumio volumio-remote-updater[853]: [2026-03-28 16:56:24] [connect] Successful connection Mar 28 16:56:24 volumio volumio-remote-updater[853]: [2026-03-28 16:56:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774713384 101 Mar 28 16:56:24 volumio volumio[18400]: 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: 6 Mar 28 16:56:27 volumio volumio[18400]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 28 16:56:29 volumio volumio[18400]: info: CoreCommandRouter::volumioPlay Mar 28 16:56:29 volumio volumio[18400]: info: CoreStateMachine::play index undefined Mar 28 16:56:29 volumio volumio[18400]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 16:56:29 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:29 volumio volumio[18400]: info: CoreStateMachine::startPlaybackTimer Mar 28 16:56:29 volumio volumio[18400]: info: CorePlayQueue::getTrack 0 Mar 28 16:56:30 volumio sudo[18780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 16:56:30 volumio sudo[18780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:30 volumio sudo[18782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 16:56:30 volumio sudo[18780]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:30 volumio sudo[18782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:30 volumio sudo[18782]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:30 volumio sudo[18785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 28 16:56:30 volumio sudo[18785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:30 volumio sudo[18785]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:30 volumio volumio[18400]: info: Upmpdcli Daemon Started Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin bluetooth to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin multiroom to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin metavolumio to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin cd_controller to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 28 16:56:30 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 28 16:56:31 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:31 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 16:56:31 volumio volumio[18400]: info: Starting MyVolumio Remote Streaming Endpoints Mar 28 16:56:31 volumio volumio[18400]: info: MyVolumio login type: Token Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 28 16:56:31 volumio volumio[18400]: info: Streaming services startup Mar 28 16:56:31 volumio volumio[18400]: info: Starting Streaming Daemon Mar 28 16:56:31 volumio sudo[18790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 16:56:31 volumio sudo[18790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:31 volumio volumio[18400]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 28 16:56:31 volumio sudo[18790]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:31 volumio volumio[18400]: error: Cannot start Volumio Streaming Daemon Mar 28 16:56:31 volumio volumio[18400]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 28 16:56:31 volumio volumio[18400]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 28 16:56:31 volumio volumio[18400]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 28 16:56:32 volumio volumio[18400]: info: MyVolumio token set successfully Mar 28 16:56:32 volumio volumio[18400]: info: MYVOLUMIO: Adding device Mar 28 16:56:32 volumio volumio[18400]: info: MYVOLUMIO: Evaluating Server Mar 28 16:56:32 volumio volumio[18400]: info: MyVolumio Plan changed: premium Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 28 16:56:32 volumio volumio[18400]: info: Removing browser output: myVolumio user plan is not superstar Mar 28 16:56:32 volumio volumio[18400]: info: Removing audio output: Mar 28 16:56:32 volumio volumio[18400]: info: MYVOLUMIO: Adding device Mar 28 16:56:32 volumio volumio[18400]: info: MYVOLUMIO: Evaluating Server Mar 28 16:56:32 volumio volumio[18400]: info: Remote config written successfully Mar 28 16:56:32 volumio volumio[18400]: info: Starting Tunnel 1 Mar 28 16:56:32 volumio volumio[18400]: info: Starting Tunnel Connection Checker Mar 28 16:56:32 volumio volumio[18400]: info: Completed starting MyVolumio Plugin Mar 28 16:56:32 volumio volumio[18400]: info: MYVolumio Device enabled Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 28 16:56:32 volumio volumio[18400]: info: MyVolumio status changed Mar 28 16:56:32 volumio volumio[18400]: info: Streaming services startup Mar 28 16:56:32 volumio volumio[18400]: info: Starting Streaming Daemon Mar 28 16:56:32 volumio sudo[18837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 16:56:32 volumio sudo[18837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio volumio[18400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 28 16:56:32 volumio sudo[18837]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio volumio[18400]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 28 16:56:32 volumio sudo[18842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 28 16:56:32 volumio sudo[18842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio sudo[18842]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio volumio[18400]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 28 16:56:32 volumio volumio[18400]: info: MRS: MultiRoom plugin initialized Mar 28 16:56:32 volumio volumio[18400]: info: MRS: STOPPING SNAPCLIENT Mar 28 16:56:32 volumio volumio[18400]: info: MRS: Snap server stop Mar 28 16:56:32 volumio volumio[18400]: info: MRS: STOPPING volumioStreaming Mar 28 16:56:32 volumio sudo[18859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 28 16:56:32 volumio sudo[18859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 28 16:56:32 volumio sudo[18863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 28 16:56:32 volumio sudo[18863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio sudo[18865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 28 16:56:32 volumio sudo[18865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio sudo[18859]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio sudo[18866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 28 16:56:32 volumio sudo[18866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 16:56:32 volumio sudo[18866]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 28 16:56:32 volumio sudo[18865]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 28 16:56:32 volumio sudo[18863]: pam_unix(sudo:session): session closed for user root Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 28 16:56:32 volumio volumio[18400]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 28 16:56:32 volumio volumio[18400]: info: Preparing to generate the ALSA configuration file Mar 28 16:56:32 volumio volumio[18400]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 16:56:32 volumio volumio[18400]: [Error: 40BCD999A87F0000:error:03000082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:1005: Mar 28 16:56:32 volumio volumio[18400]: ] { Mar 28 16:56:32 volumio volumio[18400]: library: 'digital envelope routines', Mar 28 16:56:32 volumio volumio[18400]: reason: 'invalid key length', Mar 28 16:56:32 volumio volumio[18400]: code: 'ERR_SSL_INVALID_KEY_LENGTH' Mar 28 16:56:32 volumio volumio[18400]: } Mar 28 16:56:32 volumio volumio[18400]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 16:56:32 volumio sudo[18884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 16:55' Mar 28 16:56:32 volumio sudo[18884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"