-- Logs begin at Sat 2025-05-31 21:14:53 +07, end at Sat 2025-05-31 21:42:13 +07. -- May 31 21:41:00 volumio volumio[1072]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/volumio/buster/amd64 May 31 21:41:00 volumio volumio[1072]: info: FusionDsp - Stopping FusionDsp service May 31 21:41:00 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:41:00 volumio volumio[1072]: TypeError: Cannot read property 'stop' of null May 31 21:41:00 volumio volumio[1072]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 31 21:41:00 volumio volumio[1072]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 31 21:41:00 volumio volumio[1072]: at /volumio/app/pluginmanager.js:956:12 May 31 21:41:00 volumio volumio[1072]: at ChildProcess.exithandler (child_process.js:374:7) May 31 21:41:00 volumio volumio[1072]: at ChildProcess.emit (events.js:400:28) May 31 21:41:00 volumio volumio[1072]: at maybeClose (internal/child_process.js:1088:16) May 31 21:41:00 volumio volumio[1072]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) May 31 21:41:00 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:41:00 volumio sudo[30180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 21:40 May 31 21:41:00 volumio sudo[30180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:00 volumio sudo[30180]: pam_unix(sudo:session): session closed for user root May 31 21:41:01 volumio go-librespot[27102]: time="2025-05-31T21:41:01+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 31 21:41:01 volumio volumio-remote-updater[665]: [2025-05-31 21:41:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 31 21:41:01 volumio volumio-remote-updater[665]: [2025-05-31 21:41:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 31 21:41:01 volumio go-librespot[27102]: time="2025-05-31T21:41:01+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 31 21:41:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 31 21:41:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 31 21:41:01 volumio systemd[1]: Started dynamicswap service. May 31 21:41:01 volumio systemd[1]: dynamicswap.service: Succeeded. May 31 21:41:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 31 21:41:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 31 21:41:01 volumio systemd[1]: Stopped Volumio Backend Module. May 31 21:41:01 volumio systemd[1]: Started Volumio Backend Module. May 31 21:41:01 volumio systemd[1]: Started dynamicswap service. May 31 21:41:01 volumio systemd[1]: dynamicswap.service: Succeeded. May 31 21:41:02 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:02 volumio volumio[30240]: info: ----- Volumio3 ---- May 31 21:41:02 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:02 volumio volumio[30240]: info: ----- System startup ---- May 31 21:41:02 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:03 volumio volumio[30240]: info: MYVOLUMIO Environment detected May 31 21:41:03 volumio volumio[30240]: info: Plugin folders cleanup May 31 21:41:03 volumio volumio[30240]: info: Scanning into folder /volumio/app/plugins/ May 31 21:41:03 volumio volumio[30240]: info: Scanning category audio_interface May 31 21:41:03 volumio volumio[30240]: info: Scanning category miscellanea May 31 21:41:03 volumio volumio[30240]: info: Scanning category music_service May 31 21:41:03 volumio volumio[30240]: info: Scanning category plugins.json May 31 21:41:03 volumio volumio[30240]: info: Scanning category system_controller May 31 21:41:03 volumio volumio[30240]: info: Scanning category user_interface May 31 21:41:03 volumio volumio[30240]: info: Scanning into folder /data/plugins/ May 31 21:41:03 volumio volumio[30240]: info: Scanning category audio_interface May 31 21:41:03 volumio volumio[30240]: info: Scanning category miscellanea May 31 21:41:03 volumio volumio[30240]: info: Scanning category music_service May 31 21:41:03 volumio volumio[30240]: info: Plugin folders cleanup completed May 31 21:41:03 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:03 volumio volumio[30240]: info: ----- Core plugins startup ---- May 31 21:41:03 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:03 volumio volumio[30240]: info: Loading plugins from folder /volumio/app/plugins/ May 31 21:41:03 volumio volumio[30240]: info: Adding plugin upnp to MyMusic Plugins May 31 21:41:03 volumio volumio[30240]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 21:41:03 volumio volumio[30240]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 21:41:03 volumio volumio[30240]: info: Loading plugins from folder /data/plugins/ May 31 21:41:03 volumio volumio[30240]: info: Loading plugin "system"... May 31 21:41:03 volumio volumio[30240]: info: Loading plugin "appearance"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "network"... May 31 21:41:04 volumio volumio[30240]: info: Refreshing Cached IP Addresses May 31 21:41:04 volumio sudo[30271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 21:41:04 volumio sudo[30271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:04 volumio sudo[30271]: pam_unix(sudo:session): session closed for user root May 31 21:41:04 volumio sudo[30274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 21:41:04 volumio sudo[30274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:04 volumio sudo[30274]: pam_unix(sudo:session): session closed for user root May 31 21:41:04 volumio volumio[30240]: info: Plugin peppy_screensaver is not enabled May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "services"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "alsa_controller"... May 31 21:41:04 volumio sudo[30284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 21:41:04 volumio sudo[30284]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:04 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "wizard"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "networkfs"... May 31 21:41:04 volumio volumio[30240]: info: Starting Udev Watcher for removable devices May 31 21:41:04 volumio volumio[30240]: info: Ignoring mount for partition: boot May 31 21:41:04 volumio volumio[30240]: info: Ignoring mount for partition: volumio May 31 21:41:04 volumio volumio[30240]: info: Ignoring mount for partition: volumio_data May 31 21:41:04 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "volumio_command_line_client"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "upnp"... May 31 21:41:04 volumio volumio[30240]: info: [1748702464502] Starting Upmpd Daemon May 31 21:41:04 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "my_music"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "mpd"... May 31 21:41:04 volumio volumio[30240]: info: Loading plugin "upnp_browser"... May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "alarm-clock"... May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "airplay_emulation"... May 31 21:41:05 volumio volumio[30240]: info: Starting Shairport Sync May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "last_100"... May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "webradio"... May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "i2s_dacs"... May 31 21:41:05 volumio volumio[30240]: info: I2S DAC not set, start Auto-detection May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "volumiodiscovery"... May 31 21:41:05 volumio volumio[30240]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 21:41:05 volumio volumio[30240]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:41:05 volumio volumio[30240]: *** WARNING *** For more information see May 31 21:41:05 volumio node[30240]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 21:41:05 volumio volumio[30240]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 21:41:05 volumio volumio[30240]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:41:05 volumio volumio[30240]: *** WARNING *** For more information see May 31 21:41:05 volumio node[30240]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:41:05 volumio node[30240]: *** WARNING *** For more information see May 31 21:41:05 volumio node[30240]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 21:41:05 volumio node[30240]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:41:05 volumio node[30240]: *** WARNING *** For more information see May 31 21:41:05 volumio volumio[30240]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 21:41:05 volumio volumio[30240]: info: Discovery: Started advertising with name: Volumio May 31 21:41:05 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:41:05 volumio volumio[30240]: info: Loading plugin "spop"... May 31 21:41:06 volumio volumio-remote-updater[665]: [2025-05-31 21:41:06] [connect] Successful connection May 31 21:41:06 volumio volumio[30240]: info: Loading plugin "youtube2"... May 31 21:41:08 volumio volumio[30240]: info: Loading plugin "ytcr"... May 31 21:41:09 volumio sudo[30284]: pam_unix(sudo:session): session closed for user root May 31 21:41:09 volumio volumio[30240]: info: Loading plugin "ytmusic"... May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "outputs"... May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "albumart"... May 31 21:41:12 volumio volumio[30240]: info: Plugin example_plugin is not enabled May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "inputs"... May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "updater_comm"... May 31 21:41:12 volumio volumio[30240]: Forking 1 albumart workers May 31 21:41:12 volumio volumio[30240]: info: Plugin mpdemulation is not enabled May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "rest_api"... May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "websocket"... May 31 21:41:12 volumio volumio[30240]: info: Starting Socket.io Server version 2.3.0 May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "fusiondsp"... May 31 21:41:12 volumio volumio[30240]: info: Applying required configuration parameters for plugin fusiondsp May 31 21:41:12 volumio volumio[30240]: info: Loading plugin "RoonBridge"... May 31 21:41:12 volumio volumio[30240]: Starting albumart workers May 31 21:41:13 volumio volumio[30240]: info: Applying required configuration parameters for plugin RoonBridge May 31 21:41:13 volumio volumio[30240]: info: Plugin podcast is not enabled May 31 21:41:13 volumio volumio[30240]: info: Loading i18n strings for locale vi May 31 21:41:13 volumio volumio[30240]: Updating browse sources language May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::initPlayerControls May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:41:13 volumio volumio[30240]: Express server listening on port 3000 May 31 21:41:13 volumio volumio[30240]: [Metrics] WebUI: 10s 946.52ms May 31 21:41:13 volumio volumio[30240]: info: Setting Device type: x86 May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::resetVolumioState May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::getcurrentVolume May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::volumioRetrievevolume May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:13 volumio volumio[30240]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 31 21:41:13 volumio volumio[30240]: info: Completed loading Core Plugins May 31 21:41:13 volumio volumio[30240]: info: Preparing to generate the ALSA configuration file May 31 21:41:13 volumio volumio[30240]: info: Volumio Network Manager: Network status updated: 2 May 31 21:41:13 volumio volumio[30240]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 31 21:41:13 volumio volumio[30240]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:41:13 volumio volumio[30240]: info: Reading ALSA contributions from plugins. May 31 21:41:13 volumio volumio[30240]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 31 21:41:13 volumio volumio[30240]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 31 21:41:13 volumio volumio[30240]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 21:41:13 volumio volumio[30240]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 31 21:41:13 volumio volumio[30240]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 31 21:41:13 volumio volumio[30240]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 21:41:13 volumio volumio[30240]: info: Reloading queue from file May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::setRepeat true single undefined May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:13 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::setRandom null May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:13 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:13 volumio volumio[30240]: info: VolumeController:: Volume=85 Mute =false May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:13 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:13 volumio volumio[30240]: info: CoreStateMachine::updateTrackBlock May 31 21:41:13 volumio volumio[30240]: info: CorePlayQueue::getTrackBlock May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::volumioRetrievevolume May 31 21:41:13 volumio volumio[30240]: info: Asound.conf file unchanged, so no further update is needed May 31 21:41:13 volumio volumio[30240]: info: Output device has changed, restarting MPD May 31 21:41:13 volumio sudo[30332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:41:13 volumio sudo[30332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:13 volumio sudo[30332]: pam_unix(sudo:session): session closed for user root May 31 21:41:13 volumio volumio[30240]: info: Output device has changed, restarting Shairport Sync May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:13 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:13 volumio sudo[30335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:41:13 volumio sudo[30335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:13 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:41:13 volumio systemd[1]: mpd.service: Succeeded. May 31 21:41:13 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:41:13 volumio volumio[30240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:41:13 volumio volumio[30240]: info: ___________ START PLUGINS ___________ May 31 21:41:14 volumio systemd[1]: Starting Music Player Daemon... May 31 21:41:14 volumio volumio[30240]: info: ControllerMpd::onStart: Initializing MPD May 31 21:41:14 volumio volumio[30240]: info: Creating MPD Configuration file May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:41:14 volumio sudo[30340]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:41:14 volumio sudo[30340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:14 volumio volumio[30240]: info: [1748702474115] CoreMusicLibrary::Adding element Máy chủ Media May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:14 volumio sudo[30340]: pam_unix(sudo:session): session closed for user root May 31 21:41:14 volumio sudo[30342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:41:14 volumio sudo[30342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:14 volumio sudo[30342]: pam_unix(sudo:session): session closed for user root May 31 21:41:14 volumio sudo[30344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:41:14 volumio sudo[30344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:14 volumio systemd[1]: mpd.service: Succeeded. May 31 21:41:14 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:41:14 volumio systemd[1]: Starting Music Player Daemon... May 31 21:41:14 volumio volumio[30240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:14 volumio sudo[30349]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:41:14 volumio sudo[30349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:14 volumio sudo[30349]: pam_unix(sudo:session): session closed for user root May 31 21:41:14 volumio volumio[30240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:14 volumio volumio[30240]: info: [1748702474342] CoreMusicLibrary::Adding element Last_100 May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:14 volumio volumio[30240]: info: [1748702474346] CoreMusicLibrary::Adding element Webradio May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:14 volumio volumio[30240]: info: Initializing BBC Radios May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:14 volumio volumio[30240]: info: Creating Spotify config file May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:14 volumio volumio[30240]: info: [1748702474667] CoreMusicLibrary::Adding element YouTube2 May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:14 volumio volumio[30240]: Cannot find translation for source YouTube2 May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:14 volumio volumio[30240]: info: [1748702474722] CoreMusicLibrary::Adding element YouTube Music May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:14 volumio volumio[30240]: Cannot find translation for source YouTube2 May 31 21:41:14 volumio volumio[30240]: Cannot find translation for source YouTube Music May 31 21:41:14 volumio volumio[30240]: info: Loading i18n strings for locale vi May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 31 21:41:14 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:14 volumio volumio[30240]: info: FusionDsp - mixtype--------------------- Hardware May 31 21:41:14 volumio volumio[30240]: info: Volumio Calling Home May 31 21:41:14 volumio sudo[30383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 31 21:41:14 volumio sudo[30383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:14 volumio sudo[30383]: pam_unix(sudo:session): session closed for user root May 31 21:41:14 volumio volumio[30240]: info: Preparing to generate the ALSA configuration file May 31 21:41:15 volumio sudo[30389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 21:41:15 volumio sudo[30389]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:15 volumio volumio[30240]: info: Discovery: adding 57db9a1b-0a0d-4b22-a611-ac9e54c71ec0 May 31 21:41:15 volumio volumio[30240]: info: Discovery: Found device Volumio May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:15 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:15 volumio sudo[30389]: pam_unix(sudo:session): session closed for user root May 31 21:41:15 volumio volumio[30240]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:41:15 volumio volumio[30240]: info: Reading ALSA contributions from plugins. May 31 21:41:15 volumio sudo[30391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 21:41:15 volumio sudo[30391]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:15 volumio volumio[30240]: info: MPD Permissions set May 31 21:41:15 volumio sudo[30391]: pam_unix(sudo:session): session closed for user root May 31 21:41:15 volumio volumio[30240]: info: Spotify config file written May 31 21:41:15 volumio volumio[30240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 31 21:41:15 volumio volumio[30240]: info: VolumeController:: Volume=85 Mute =false May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:15 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:15 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:15 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:15 volumio sudo[30396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 31 21:41:15 volumio sudo[30396]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio systemd[1]: Stopping go-librespot Daemon... May 31 21:41:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 31 21:41:15 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 31 21:41:15 volumio systemd[1]: Stopped go-librespot Daemon. May 31 21:41:15 volumio systemd[1]: Started go-librespot Daemon. May 31 21:41:15 volumio go-librespot[30399]: Librespot-go daemon starting... May 31 21:41:15 volumio sudo[30396]: pam_unix(sudo:session): session closed for user root May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 21:41:15 volumio go-librespot[30399]: time="2025-05-31T21:41:15+07:00" level=info msg="generated new device id: f45db10505c4c0ba00e2458b0a304a3ee7c6b0ed" May 31 21:41:15 volumio go-librespot[30399]: time="2025-05-31T21:41:15+07:00" level=debug msg="stored credentials found for c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 21:41:15 volumio mpd[30357]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:41:15 volumio mpd[30357]: output: No 'audio_output' defined in config file May 31 21:41:15 volumio mpd[30357]: couldn't open play stream: No such file or directory May 31 21:41:15 volumio mpd[30357]: sndio_output: Error opening default sndio device May 31 21:41:15 volumio mpd[30357]: output: Successfully detected a alsa audio device May 31 21:41:15 volumio mpd[30357]: zeroconf: No global port, disabling zeroconf May 31 21:41:15 volumio systemd[1]: Started Music Player Daemon. May 31 21:41:15 volumio sudo[30344]: pam_unix(sudo:session): session closed for user root May 31 21:41:15 volumio sudo[30335]: pam_unix(sudo:session): session closed for user root May 31 21:41:15 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:15 volumio go-librespot[30399]: time="2025-05-31T21:41:15+07:00" level=debug msg="obtained new client token: AABqqDCgLLXOp4ZmfvXiaRuCnF7dRq2i9PdZjn0a55uRZvdgTS5qBOdWg1W0l2ONiUhvx/+bGRisWFkV8WsF/lchDNJG1d7pZBsUSBbXDj9ATzITpn1sxbfzs1k/vWfmJWx7LYONE/W8mGIvaAmK2hhohwE/xBZkskqBVur29Tjse1pk5EdAEm/LdxjrUsBgrikjOWROAprnNwUY6hhKrQFFlBnX3iEg5c9x+mywv80CfvZYeoSvb79Wck4zga61" May 31 21:41:15 volumio volumio[30240]: info: Volumio called home May 31 21:41:15 volumio volumio[30240]: info: Starting Shairport Sync May 31 21:41:16 volumio volumio[30240]: info: Starting Shairport Sync May 31 21:41:16 volumio sudo[30423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:41:16 volumio sudo[30423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio volumio[30240]: info: Starting Shairport Sync May 31 21:41:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:41:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:41:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio go-librespot[30399]: time="2025-05-31T21:41:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" May 31 21:41:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio sudo[30426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:41:16 volumio sudo[30426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio sudo[30423]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:41:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:41:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio sudo[30426]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio sudo[30431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:41:16 volumio sudo[30431]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:41:16 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 31 21:41:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:41:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio go-librespot[30399]: time="2025-05-31T21:41:16+07:00" level=debug msg="completed keyexchange" May 31 21:41:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:41:16 volumio sudo[30431]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio volumio[30240]: error: MPD error: The expression evaluated to a falsy value: May 31 21:41:16 volumio volumio[30240]: assert.ok(self.idling) May 31 21:41:16 volumio volumio[30240]: error: The expression evaluated to a falsy value: May 31 21:41:16 volumio volumio[30240]: assert.ok(self.idling) May 31 21:41:16 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:16 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:16 volumio volumio[30240]: info: MPD running with PID30357 May 31 21:41:16 volumio volumio[30240]: ,establishing connection May 31 21:41:16 volumio volumio[30240]: error: updateQueue error: null May 31 21:41:16 volumio sudo[30438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 21:41:16 volumio sudo[30438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio volumio[30240]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 31 21:41:16 volumio sudo[30438]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio volumio[30240]: info: Shairport-Sync Started May 31 21:41:16 volumio volumio[30240]: Error adding Membership: Error: addMembership EINVAL May 31 21:41:16 volumio volumio[30240]: info: Shairport-Sync Started May 31 21:41:16 volumio volumio[30240]: info: Asound.conf file unchanged, so no further update is needed May 31 21:41:16 volumio volumio[30240]: info: Output device has changed, restarting MPD May 31 21:41:16 volumio sudo[30441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:41:16 volumio sudo[30441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio sudo[30441]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio volumio[30240]: info: Output device has changed, restarting Shairport Sync May 31 21:41:16 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:16 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:16 volumio sudo[30444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:41:16 volumio sudo[30444]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:41:16 volumio volumio[30240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:41:16 volumio systemd[1]: mpd.service: Succeeded. May 31 21:41:16 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:41:16 volumio volumio[30240]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 21:41:16 volumio volumio[30240]: SPOTIFY: BQC9vROM1PIKFJXN3EdIhGgb1PM27PMrBIOkY03z9NCbAzlBf9mZgX2pLYpkkzoLU6Ivxm9dkd1jdv8IFRZUJMWE2FezOBgzXzSV57nzsmmewMgPrWiM-bDYTwOhM1DxQR2sFaCVotpep_h4i9ohsFCDaGsr74ZiLe2phzA47BTlU_GyD8EZ6hf4608sLcyANuB2sIYEfuL5acVd-0pj_r6pu9gxMbyN7L6q3KnLE_pCMEod75QN_L9i0mQiOMemyalJCBRbvTzkTqIakCEMjFnp3h0zao4tFOH2-EghvvI0HLE May 31 21:41:16 volumio volumio[30240]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 21:41:16 volumio volumio[30240]: info: New Spotify access token = BQC9vROM1PIKFJXN3EdIhGgb1PM27PMrBIOkY03z9NCbAzlBf9mZgX2pLYpkkzoLU6Ivxm9dkd1jdv8IFRZUJMWE2FezOBgzXzSV57nzsmmewMgPrWiM-bDYTwOhM1DxQR2sFaCVotpep_h4i9ohsFCDaGsr74ZiLe2phzA47BTlU_GyD8EZ6hf4608sLcyANuB2sIYEfuL5acVd-0pj_r6pu9gxMbyN7L6q3KnLE_pCMEod75QN_L9i0mQiOMemyalJCBRbvTzkTqIakCEMjFnp3h0zao4tFOH2-EghvvI0HLE May 31 21:41:16 volumio volumio[30240]: info: Spotify credentials grant success - running version from March 24, 2019 May 31 21:41:16 volumio systemd[1]: Starting Music Player Daemon... May 31 21:41:16 volumio volumio[30240]: error: MPD error: Error: write EPIPE May 31 21:41:16 volumio volumio[30240]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 31 21:41:16 volumio volumio[30240]: info: Upmpdcli Daemon Started May 31 21:41:16 volumio go-librespot[30399]: time="2025-05-31T21:41:16+07:00" level=debug msg="completed challenge" May 31 21:41:16 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:41:16 volumio volumio[30240]: info: MPD Permissions set May 31 21:41:16 volumio sudo[30449]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:41:16 volumio sudo[30449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:16 volumio go-librespot[30399]: time="2025-05-31T21:41:16+07:00" level=debug msg="authenticated as c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:41:16 volumio sudo[30449]: pam_unix(sudo:session): session closed for user root May 31 21:41:16 volumio volumio[30240]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:41:16 volumio volumio[30240]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 31 21:41:16 volumio volumio[30240]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:41:16 volumio volumio[30240]: info: camilladsp service started and running in background, instance 1 May 31 21:41:16 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="authenticated as c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 31 21:41:17 volumio volumio[30240]: cannot open device 'volumioHw': Device or resource busy May 31 21:41:17 volumio volumio[30240]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 31 21:41:17 volumio volumio[30240]: cannot open device 'volumioHw': Device or resource busy May 31 21:41:17 volumio volumio[30240]: info: FusionDsp loaded May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:17 volumio volumio[30240]: info: FusionDsp - Reporting Fusion DSP Enabled May 31 21:41:17 volumio volumio[30240]: info: Adding Signal Path Element [object Object] May 31 21:41:17 volumio volumio[30240]: info: Adding fusiondspeq DSP Signal Path Element May 31 21:41:17 volumio volumio[30240]: info: FusionDsp - ---- installed callbackRead May 31 21:41:17 volumio volumio[30240]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 21:41:17 volumio sudo[30469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 31 21:41:17 volumio sudo[30469]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="dealer connection opened" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 31 21:41:17 volumio systemd[1]: Started FusionDsp Daemon. May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="received connection id: OTU0YzNjYTEtYjZlYS00MjIzLTkwODMtZTAxOTIyNTIwZDFhK2RlYWxlcit0Y3A6Ly8wYWM5NDk4My5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArOUU4NDdCQTE3RDNGRDMyQ0U2NzY1NTkxMTJBMDU0RTY1NUQ0NzMzRUFCREI5NzIzMTM0QjA1QzNBRDA3NUVFMg==" May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:17 volumio sudo[30469]: pam_unix(sudo:session): session closed for user root May 31 21:41:17 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:17 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:41:17 volumio volumio[30240]: info: Received Get System Info May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:17 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:17 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:17 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:17 volumio volumio[30240]: info: Listing playlists May 31 21:41:17 volumio volumio[30240]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio go-librespot[30399]: time="2025-05-31T21:41:17+07:00" level=debug msg="put connect state because NEW_DEVICE" May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 21:41:17 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:17 volumio volumio[30240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 31 21:41:17 volumio volumio[30240]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 31 21:41:17 volumio volumio[30240]: info: Starting Shairport Sync May 31 21:41:18 volumio sudo[30480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:41:18 volumio sudo[30480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:41:18 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:41:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:41:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:41:18 volumio sudo[30480]: pam_unix(sudo:session): session closed for user root May 31 21:41:18 volumio volumio[30240]: info: Shairport-Sync Started May 31 21:41:18 volumio volumio[30240]: SPOTIFY: User informations: {"country":"NG","display_name":"Spotify Premium","email":"thienhoanghn@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/c4qfj1i26zzpvfz4yd31pdqb6"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/c4qfj1i26zzpvfz4yd31pdqb6","id":"c4qfj1i26zzpvfz4yd31pdqb6","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85c5e0bf8dada7f6b411d2f145","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82c5e0bf8dada7f6b411d2f145","width":64}],"product":"premium","type":"user","uri":"spotify:user:c4qfj1i26zzpvfz4yd31pdqb6"} May 31 21:41:18 volumio volumio[30240]: info: Spotify Successfully logged in May 31 21:41:18 volumio volumio[30240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:41:18 volumio volumio[30240]: info: [1748702478265] CoreMusicLibrary::Adding element Spotify May 31 21:41:18 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:18 volumio volumio[30240]: Cannot find translation for source YouTube2 May 31 21:41:18 volumio volumio[30240]: Cannot find translation for source YouTube Music May 31 21:41:18 volumio volumio[30240]: Cannot find translation for source Spotify May 31 21:41:18 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:18 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:18 volumio volumio[30240]: info: FusionDsp - May 31 21:41:18 volumio volumio-remote-updater[665]: [2025-05-31 21:41:18] [connect] Successful connection May 31 21:41:18 volumio mpd[30453]: May 31 21:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:41:18 volumio systemd[1]: Started Music Player Daemon. May 31 21:41:18 volumio sudo[30444]: pam_unix(sudo:session): session closed for user root May 31 21:41:18 volumio volumio[30240]: info: FusionDsp - May 31 21:41:19 volumio volumio[30240]: info: go-librespot daemon successfully initialized May 31 21:41:19 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:19 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:19 volumio volumio-remote-updater[665]: [2025-05-31 21:41:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748702478 101 May 31 21:41:19 volumio volumio[30240]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 May 31 21:41:19 volumio volumio[30240]: error: updateQueue error: null May 31 21:41:19 volumio volumio[30240]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 31 21:41:19 volumio volumio[30240]: info: Completed starting Core Plugins May 31 21:41:19 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:19 volumio volumio[30240]: info: ----- MyVolumio plugins startup ---- May 31 21:41:19 volumio volumio[30240]: info: ------------------------------------------- May 31 21:41:19 volumio volumio[30240]: info: [MyVolumio PluginManager] Fetching plans data.... May 31 21:41:21 volumio volumio[30240]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 31 21:41:22 volumio volumio[30240]: info: Initializing connection to go-librespot Websocket May 31 21:41:22 volumio go-librespot[30399]: time="2025-05-31T21:41:22+07:00" level=debug msg="new websocket client" May 31 21:41:22 volumio volumio[30240]: info: Connection to go-librespot Websocket established May 31 21:41:22 volumio volumio[30240]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: Listing playlists May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetQueue May 31 21:41:22 volumio volumio[30240]: info: CoreStateMachine::getQueue May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getQueue May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: Listing playlists May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetQueue May 31 21:41:22 volumio volumio[30240]: info: CoreStateMachine::getQueue May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getQueue May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: Listing playlists May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetQueue May 31 21:41:22 volumio volumio[30240]: info: CoreStateMachine::getQueue May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getQueue May 31 21:41:22 volumio volumio[30240]: info: Received Get System Info May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:41:22 volumio volumio[30240]: info: Discovery: Getting this device information May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:41:22 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:22 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:23 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:24 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:25 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:26 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:27 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:28 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:29 volumio volumio[30240]: info: FusionDsp - May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - May 31 21:41:30 volumio volumio[30240]: info: Getting Spotify volume May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:30 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:31 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:32 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:32 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:32 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:32 volumio volumio[30240]: info: FusionDsp - undefined May 31 21:41:32 volumio volumio[30240]: info: CoreCommandRouter::volumioPlay May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::play index undefined May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:41:32 volumio volumio[30240]: info: CoreCommandRouter::volumioPlay May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::play index undefined May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:41:32 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::startPlaybackTimer May 31 21:41:32 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:32 volumio volumio[30240]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224S0jwsH7WYw%22%2C%22params%22%3A%22qgMVbm9pIHRpbmggeWV1IGtldCB0aHVjugMLCOzP9ZmmweqvswG6AwoI-YPV4rXTufMXugMKCLv_2_v_-KT5GboDDxINUkQ0UzBqd3NIN1dZd7oDCgi8jPLk9oWOxHe6AwoIipKzvr3wgKg3ugMKCO723__dzPfQKLoDCQiX5bemo7-7f7oDCgi_9s65ksj72Ca6AwoI17KX1Lb-8NxHugMKCNHlydKu7prYCLoDCgiuiI6KtZuz8Wy6AwsI37e5ndyx_5jfAboDCwiQ0KPa35DT7YIBugMKCPLL5dyNpLrgM7oDCwja9Me4tJ-ktrQBugMKCP6SkYOD2szBbboDCwjD34XSlubeq7sBugMLCN_y1L-ylMKd0AHyAwUNjg9pPQ%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22N%C6%A1i%20T%C3%ACnh%20Y%C3%AAu%20K%E1%BA%BFt%20Th%C3%BAc%20%5B%20lyric%20video%20%5D%20-%20B%C3%B9i%20Anh%20Tu%E1%BA%A5n%22%2C%22artist%22%3A%22Egg%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F4S0jwsH7WYw%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDzVwNS8MaTY22qhswv1IE5NC01wA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224S0jwsH7WYw%22%2C%22params%22%3A%22qgMVbm9pIHRpbmggeWV1IGtldCB0aHVjugMLCOzP9ZmmweqvswG6AwoI-YPV4rXTufMXugMKCLv_2_v_-KT5GboDDxINUkQ0UzBqd3NIN1dZd7oDCgi8jPLk9oWOxHe6AwoIipKzvr3wgKg3ugMKCO723__dzPfQKLoDCQiX5bemo7-7f7oDCgi_9s65ksj72Ca6AwoI17KX1Lb-8NxHugMKCNHlydKu7prYCLoDCgiuiI6KtZuz8Wy6AwsI37e5ndyx_5jfAboDCwiQ0KPa35DT7YIBugMKCPLL5dyNpLrgM7oDCwja9Me4tJ-ktrQBugMKCP6SkYOD2szBbboDCwjD34XSlubeq7sBugMLCN_y1L-ylMKd0AHyAwUNjg9pPQ%253D%253D%22%7D%7D%7D May 31 21:41:32 volumio volumio[30240]: info: [youtube2] InnertubeLoader: creating Innertube instance... May 31 21:41:32 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:32 volumio volumio[30240]: info: CoreStateMachine::startPlaybackTimer May 31 21:41:32 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:32 volumio volumio[30240]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224S0jwsH7WYw%22%2C%22params%22%3A%22qgMVbm9pIHRpbmggeWV1IGtldCB0aHVjugMLCOzP9ZmmweqvswG6AwoI-YPV4rXTufMXugMKCLv_2_v_-KT5GboDDxINUkQ0UzBqd3NIN1dZd7oDCgi8jPLk9oWOxHe6AwoIipKzvr3wgKg3ugMKCO723__dzPfQKLoDCQiX5bemo7-7f7oDCgi_9s65ksj72Ca6AwoI17KX1Lb-8NxHugMKCNHlydKu7prYCLoDCgiuiI6KtZuz8Wy6AwsI37e5ndyx_5jfAboDCwiQ0KPa35DT7YIBugMKCPLL5dyNpLrgM7oDCwja9Me4tJ-ktrQBugMKCP6SkYOD2szBbboDCwjD34XSlubeq7sBugMLCN_y1L-ylMKd0AHyAwUNjg9pPQ%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22N%C6%A1i%20T%C3%ACnh%20Y%C3%AAu%20K%E1%BA%BFt%20Th%C3%BAc%20%5B%20lyric%20video%20%5D%20-%20B%C3%B9i%20Anh%20Tu%E1%BA%A5n%22%2C%22artist%22%3A%22Egg%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F4S0jwsH7WYw%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDzVwNS8MaTY22qhswv1IE5NC01wA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224S0jwsH7WYw%22%2C%22params%22%3A%22qgMVbm9pIHRpbmggeWV1IGtldCB0aHVjugMLCOzP9ZmmweqvswG6AwoI-YPV4rXTufMXugMKCLv_2_v_-KT5GboDDxINUkQ0UzBqd3NIN1dZd7oDCgi8jPLk9oWOxHe6AwoIipKzvr3wgKg3ugMKCO723__dzPfQKLoDCQiX5bemo7-7f7oDCgi_9s65ksj72Ca6AwoI17KX1Lb-8NxHugMKCNHlydKu7prYCLoDCgiuiI6KtZuz8Wy6AwsI37e5ndyx_5jfAboDCwiQ0KPa35DT7YIBugMKCPLL5dyNpLrgM7oDCwja9Me4tJ-ktrQBugMKCP6SkYOD2szBbboDCwjD34XSlubeq7sBugMLCN_y1L-ylMKd0AHyAwUNjg9pPQ%253D%253D%22%7D%7D%7D May 31 21:41:32 volumio volumio[30240]: info: Spotify volume: 100 May 31 21:41:32 volumio volumio[30240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 31 21:41:32 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:32 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:32 volumio volumio[30240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 May 31 21:41:32 volumio volumio[30240]: SPOTIFY: SPOTIFY VOLUME 100 May 31 21:41:32 volumio volumio[30240]: SPOTIFY: VOLUMIO VOLUME 85 May 31 21:41:32 volumio volumio[30240]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 21:41:32 volumio volumio[30240]: info: Setting Spotify Volume from Volumio: 85 May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 31 21:41:32 volumio volumio[30240]: info: Adding plugin bluetooth to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin multiroom to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin metavolumio to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin cd_controller to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin qobuzconnect to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin smart_inputs to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: Adding plugin tidalconnect to MyMusic Plugins May 31 21:41:32 volumio volumio[30240]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 31 21:41:33 volumio volumio[30240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 31 21:41:33 volumio volumio[30240]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 31 21:41:33 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:33 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:33 volumio volumio[30240]: info: Starting MyVolumio Remote Streaming Endpoints May 31 21:41:33 volumio volumio[30240]: info: MyVolumio login type: Token May 31 21:41:33 volumio volumio[30240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 31 21:41:33 volumio volumio[30240]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 31 21:41:34 volumio volumio[30240]: info: Starting Streaming Service Transparent Proxy May 31 21:41:34 volumio volumio[30240]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 31 21:41:34 volumio volumio[30240]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 31 21:41:34 volumio volumio[30240]: info: Streaming services startup May 31 21:41:34 volumio volumio[30240]: info: Starting Streaming Daemon May 31 21:41:34 volumio sudo[30517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 21:41:34 volumio sudo[30517]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:34 volumio sudo[30517]: pam_unix(sudo:session): session closed for user root May 31 21:41:34 volumio volumio[30240]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 31 21:41:34 volumio volumio[30240]: SPOTIFY: SETTING SPOTIFY VOLUME 85 May 31 21:41:34 volumio volumio[30240]: info: Sending Spotify command with payload to local API: /player/volume May 31 21:41:34 volumio go-librespot[30399]: time="2025-05-31T21:41:34+07:00" level=debug msg="update volume to 55704/65535" May 31 21:41:34 volumio volumio[30240]: error: Cannot start Volumio Streaming Daemon May 31 21:41:34 volumio volumio[30240]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 21:41:34 volumio volumio[30240]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 21:41:34 volumio go-librespot[30399]: time="2025-05-31T21:41:34+07:00" level=debug msg="put connect state because VOLUME_CHANGED" May 31 21:41:34 volumio go-librespot[30399]: time="2025-05-31T21:41:34+07:00" level=trace msg="emitting websocket event: volume" May 31 21:41:34 volumio volumio[30240]: SPOTIFY: received: {"type":"volume","data":{"value":85,"max":100}} May 31 21:41:34 volumio volumio[30240]: SPOTIFY: RECEIVED SPOTIFY VOLUME 85 May 31 21:41:35 volumio volumio[30240]: STREAMING PROXY: Starting server on port 3245 May 31 21:41:35 volumio volumio[30240]: Node JS runtime: 14 May 31 21:41:35 volumio volumio[30240]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 21:41:41 volumio volumio[30240]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 31 21:41:41 volumio volumio[30240]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... May 31 21:41:41 volumio volumio[30240]: info: MyVolumio token set successfully May 31 21:41:41 volumio volumio[30240]: info: MYVOLUMIO: Adding device May 31 21:41:41 volumio volumio[30240]: info: MYVOLUMIO: Evaluating Server May 31 21:41:42 volumio volumio[30240]: info: MyVolumio status changed May 31 21:41:42 volumio volumio[30240]: info: Streaming services startup May 31 21:41:42 volumio volumio[30240]: info: Starting Streaming Daemon May 31 21:41:42 volumio volumio[30240]: info: Removing browser output: myVolumio user plan is not superstar May 31 21:41:42 volumio volumio[30240]: info: Removing audio output: May 31 21:41:42 volumio volumio[30240]: info: Stoppping Tunnel 1 May 31 21:41:42 volumio sudo[30558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 21:41:42 volumio sudo[30558]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:42 volumio sudo[30558]: pam_unix(sudo:session): session closed for user root May 31 21:41:42 volumio sudo[30561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 31 21:41:42 volumio sudo[30561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:42 volumio volumio[30240]: error: Cannot start Volumio Streaming Daemon May 31 21:41:42 volumio volumio[30240]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 21:41:42 volumio volumio[30240]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 21:41:42 volumio sudo[30561]: pam_unix(sudo:session): session closed for user root May 31 21:41:43 volumio volumio[30240]: info: Setting Geolocation for MyVolumio to as1 May 31 21:41:43 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:43 volumio volumio[30240]: info: Remote SSH Stopped May 31 21:41:43 volumio volumio[30240]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 31 21:41:43 volumio volumio[30240]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token May 31 21:41:43 volumio volumio[30240]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... May 31 21:41:44 volumio volumio[30240]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 21:41:44 volumio volumio[30240]: info: Updating MyVolumio device info May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:44 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:41:45 volumio volumio-remote-updater[665]: Test mode enabled May 31 21:41:45 volumio volumio-remote-updater[665]: No alpha test mode May 31 21:41:45 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 21:41:49 volumio volumio[30240]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 31 21:41:49 volumio volumio[30240]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds May 31 21:41:49 volumio volumio[30240]: info: MYVOLUMIO: Adding device May 31 21:41:49 volumio volumio[30240]: info: MYVOLUMIO: Evaluating Server May 31 21:41:49 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 21:41:49 volumio volumio[30240]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/volumio/buster/amd64 May 31 21:41:50 volumio volumio[30240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 21:41:50 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 21:41:50 volumio volumio[30240]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 21:41:50 volumio volumio[30240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 31 21:41:50 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:50 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:51 volumio volumio[30240]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/volumio/buster/amd64 May 31 21:41:51 volumio volumio[30240]: info: Setting Geolocation for MyVolumio to as1 May 31 21:41:51 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:51 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:51 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:51 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:51 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand stop took 11 milliseconds May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand clear May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand clear took 7 milliseconds May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-42u-i5ole.googlevideo.com/videoplayback?expire=1748724111&ei=LxU7aLDOEPKlssUP6L-ToQ4&ip=113.23.123.186&id=o-ABGzYKYENxVbbDGm9CG9hAt_pUJozS4eZ00uzU59UDqx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748702511%2C&mh=Qr&mm=31%2C26&mn=sn-42u-i5ole%2Csn-30a7rne6&ms=au%2Conr&mv=m&mvi=1&pcm2cms=yes&pl=23&rms=au%2Cau&initcwndbps=2907500&bui=AY1jyLNdbG__pEQrNa-Alad9fVdBumTgC_b1PIhGs2qDHbHpExiUS8i48bPdoSRPbzKnOIQzAS8_oXve&spc=l3OVKexo4DIGvJAbI3Hh5GdCp2Vl6uQll2_LLhhuWAjU-btaWqQKLHA51YPgHb2_QlI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=OTfhqWAG0RZE_1_HH62Vm48Q&rqh=1&gir=yes&clen=5356153&dur=318.041&lmt=1570086660388408&mt=1748701985&fvip=1&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5531432&n=4jKhny_d4iojJ4B8-H-_w8_ykQTtV7zlv-exLfsXs&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAOVQyup_3Ks5_gE_e7pJdZsqdb9pwdk-Rj20mxsU0oOKAiBp_wruyhs0h3kbMm1UHJXO_bDd6TncZzRjON2Re7RD7w%3D%3D&pot=MnifU-cHWPN36J9vOr-65-6JLY20tjyuM2J8CasYYqXQvTw0AyWlo6Nw0i36cWTI9cN8UHpT9oic4QfocQzOuPgpHGbw8tm0Zv7xu9dGMfX8Fg9HB1NQx6N3FyhyCKWbjKg7reMZKKNLVFgrF4SPMF8DOuGfqykHOog%3D&cver=2.20250222.10.00&t.YouTube" May 31 21:41:52 volumio volumio[30240]: error: updateQueue error: null May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 11ms May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand addid "https://rr1---sn-42u-i5ole.googlevideo.com/videoplayback?expire=1748724111&ei=LxU7aLDOEPKlssUP6L-ToQ4&ip=113.23.123.186&id=o-ABGzYKYENxVbbDGm9CG9hAt_pUJozS4eZ00uzU59UDqx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748702511%2C&mh=Qr&mm=31%2C26&mn=sn-42u-i5ole%2Csn-30a7rne6&ms=au%2Conr&mv=m&mvi=1&pcm2cms=yes&pl=23&rms=au%2Cau&initcwndbps=2907500&bui=AY1jyLNdbG__pEQrNa-Alad9fVdBumTgC_b1PIhGs2qDHbHpExiUS8i48bPdoSRPbzKnOIQzAS8_oXve&spc=l3OVKexo4DIGvJAbI3Hh5GdCp2Vl6uQll2_LLhhuWAjU-btaWqQKLHA51YPgHb2_QlI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=OTfhqWAG0RZE_1_HH62Vm48Q&rqh=1&gir=yes&clen=5356153&dur=318.041&lmt=1570086660388408&mt=1748701985&fvip=1&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5531432&n=4jKhny_d4iojJ4B8-H-_w8_ykQTtV7zlv-exLfsXs&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAOVQyup_3Ks5_gE_e7pJdZsqdb9pwdk-Rj20mxsU0oOKAiBp_wruyhs0h3kbMm1UHJXO_bDd6TncZzRjON2Re7RD7w%3D%3D&pot=MnifU-cHWPN36J9vOr-65-6JLY20tjyuM2J8CasYYqXQvTw0AyWlo6Nw0i36cWTI9cN8UHpT9oic4QfocQzOuPgpHGbw8tm0Zv7xu9dGMfX8Fg9HB1NQx6N3FyhyCKWbjKg7reMZKKNLVFgrF4SPMF8DOuGfqykHOog%3D&cver=2.20250222.10.00&t.YouTube" took 7 milliseconds May 31 21:41:52 volumio volumio[30240]: verbose: MPD COMMAND [object Object] May 31 21:41:52 volumio volumio[30240]: verbose: MPD COMMAND [object Object] May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 52ms May 31 21:41:52 volumio volumio[30240]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand play May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 7ms May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand play took 6 milliseconds May 31 21:41:52 volumio volumio[30240]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces state update: player May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand stop took 5 milliseconds May 31 21:41:52 volumio volumio[30240]: info: ControllerMpd::getState May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand status May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand clear May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand status took 5 milliseconds May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand clear took 4 milliseconds May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::parseState May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-42u-i5ole.googlevideo.com/videoplayback?expire=1748724111&ei=LxU7aI3QEJWCjuMPk927gQY&ip=113.23.123.186&id=o-AH9Kn5O7atIy1pfwmzihcJ6ecvjqfCOWo_LjDlQ-P6El&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748702511%2C&mh=Qr&mm=31%2C26&mn=sn-42u-i5ole%2Csn-30a7rne6&ms=au%2Conr&mv=m&mvi=1&pcm2cms=yes&pl=23&rms=au%2Cau&initcwndbps=2907500&bui=AY1jyLNuLmfuM3eCiR06wldfhg-7Gu3hjbWN5tGpJpamBQ1lNwcfnpt9C6XCrdndD1AJvkcbeMdz-yr8&spc=l3OVKWSDANjZ-i1-h45DnFdtZqUImgYXhmEzvDzOx7B-nCTgzL2XWzqh4lpzwv2NUCg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nI7xWzS2XwXXi1D45fZ5U40Q&rqh=1&gir=yes&clen=5356153&dur=318.041&lmt=1570086660388408&mt=1748701985&fvip=1&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5531432&n=4jKhny_d4iojJ4B8-H-_w8_Djvi94BgsSSHdUzW5Y&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgOdTrLm2DjPRKD_KuDsK3lbY95Uc0JY2DpbFzbiltK3ICIQC-UiN6ka9GghiO-qcCNVp-EBF2sxwaUOyw7VENBI5pfw%3D%3D&pot=MnifU-cHWPN36J9vOr-65-6JLY20tjyuM2J8CasYYqXQvTw0AyWlo6Nw0i36cWTI9cN8UHpT9oic4QfocQzOuPgpHGbw8tm0Zv7xu9dGMfX8Fg9HB1NQx6N3FyhyCKWbjKg7reMZKKNLVFgrF4SPMF8DOuGfqykHOog%3D&cver=2.20250222.10.00&t.YouTube" May 31 21:41:52 volumio volumio[30240]: error: updateQueue error: null May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 8ms May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand addid "https://rr1---sn-42u-i5ole.googlevideo.com/videoplayback?expire=1748724111&ei=LxU7aI3QEJWCjuMPk927gQY&ip=113.23.123.186&id=o-AH9Kn5O7atIy1pfwmzihcJ6ecvjqfCOWo_LjDlQ-P6El&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748702511%2C&mh=Qr&mm=31%2C26&mn=sn-42u-i5ole%2Csn-30a7rne6&ms=au%2Conr&mv=m&mvi=1&pcm2cms=yes&pl=23&rms=au%2Cau&initcwndbps=2907500&bui=AY1jyLNuLmfuM3eCiR06wldfhg-7Gu3hjbWN5tGpJpamBQ1lNwcfnpt9C6XCrdndD1AJvkcbeMdz-yr8&spc=l3OVKWSDANjZ-i1-h45DnFdtZqUImgYXhmEzvDzOx7B-nCTgzL2XWzqh4lpzwv2NUCg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nI7xWzS2XwXXi1D45fZ5U40Q&rqh=1&gir=yes&clen=5356153&dur=318.041&lmt=1570086660388408&mt=1748701985&fvip=1&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5531432&n=4jKhny_d4iojJ4B8-H-_w8_Djvi94BgsSSHdUzW5Y&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgOdTrLm2DjPRKD_KuDsK3lbY95Uc0JY2DpbFzbiltK3ICIQC-UiN6ka9GghiO-qcCNVp-EBF2sxwaUOyw7VENBI5pfw%3D%3D&pot=MnifU-cHWPN36J9vOr-65-6JLY20tjyuM2J8CasYYqXQvTw0AyWlo6Nw0i36cWTI9cN8UHpT9oic4QfocQzOuPgpHGbw8tm0Zv7xu9dGMfX8Fg9HB1NQx6N3FyhyCKWbjKg7reMZKKNLVFgrF4SPMF8DOuGfqykHOog%3D&cver=2.20250222.10.00&t.YouTube" took 3 milliseconds May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::parseTrackInfo May 31 21:41:52 volumio volumio[30240]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 31 21:41:52 volumio volumio[30240]: verbose: MPD COMMAND [object Object] May 31 21:41:52 volumio volumio[30240]: verbose: MPD COMMAND [object Object] May 31 21:41:52 volumio volumio[30240]: verbose: MPD COMMAND [object Object] May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 15ms May 31 21:41:52 volumio volumio[30240]: info: May 31 21:41:52 volumio volumio[30240]: ---------------------------- MPD announces system playlist update May 31 21:41:52 volumio volumio[30240]: info: Ignoring MPD Status Update May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 7ms May 31 21:41:52 volumio volumio[30240]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 21:41:52 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand play May 31 21:41:52 volumio volumio[30240]: info: ------------------------------ 4ms May 31 21:41:52 volumio volumio[30240]: info: sendMpdCommand play took 4 milliseconds May 31 21:41:52 volumio volumio[30240]: info: CoreCommandRouter::volumioGetState May 31 21:41:52 volumio volumio[30240]: info: Updating MyVolumio device info May 31 21:41:52 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:52 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:53 volumio volumio[30240]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/volumio/buster/amd64 May 31 21:41:53 volumio volumio[30240]: info: FusionDsp - Stopping FusionDsp service May 31 21:41:53 volumio volumio[30240]: info: camilladsp stopping service pid 30459... May 31 21:41:53 volumio volumio[30240]: info: camilladsp service terminated, instance 1 May 31 21:41:53 volumio sudo[30606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 31 21:41:53 volumio sudo[30606]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:53 volumio systemd[1]: Stopping FusionDsp Daemon... May 31 21:41:53 volumio volumio[30472]: Backend configuration: May 31 21:41:53 volumio volumio[30472]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 31 21:41:53 volumio volumio[30472]: active_config_txt: null May 31 21:41:53 volumio volumio[30472]: camilla_host: 127.0.0.1 May 31 21:41:53 volumio volumio[30472]: camilla_port: 9876 May 31 21:41:53 volumio volumio[30472]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 31 21:41:53 volumio volumio[30472]: config_dir: /data/configuration/audio_interface/fusiondsp May 31 21:41:53 volumio volumio[30472]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 31 21:41:53 volumio volumio[30472]: log_file: /tmp/camilladsp.log May 31 21:41:53 volumio volumio[30472]: on_get_active_config: null May 31 21:41:53 volumio volumio[30472]: on_set_active_config: null May 31 21:41:53 volumio volumio[30472]: port: 5011 May 31 21:41:53 volumio volumio[30472]: supported_capture_types: null May 31 21:41:53 volumio volumio[30472]: supported_playback_types: null May 31 21:41:53 volumio volumio[30472]: update_config_symlink: false May 31 21:41:53 volumio volumio[30472]: update_config_txt: false May 31 21:41:53 volumio volumio[30472]: ======== Running on http://0.0.0.0:5011 ======== May 31 21:41:53 volumio volumio[30472]: (Press CTRL+C to quit) May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::volumioGetBrowseSources May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:53 volumio volumio[30240]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 21:41:53 volumio volumio[30240]: info: May 31 21:41:53 volumio volumio[30240]: ---------------------------- MPD announces state update: player May 31 21:41:53 volumio volumio[30240]: info: ControllerMpd::getState May 31 21:41:53 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand status May 31 21:41:53 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 21:41:53 volumio volumio[30240]: info: sendMpdCommand status took 44 milliseconds May 31 21:41:53 volumio volumio[30240]: verbose: ControllerMpd::parseState May 31 21:41:53 volumio volumio[30240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:41:53 volumio systemd[1]: fusiondsp.service: Succeeded. May 31 21:41:53 volumio systemd[1]: Stopped FusionDsp Daemon. May 31 21:41:53 volumio sudo[30606]: pam_unix(sudo:session): session closed for user root May 31 21:41:53 volumio volumio[30240]: info: sendMpdCommand clearerror took 149 milliseconds May 31 21:41:53 volumio volumio[30240]: info: sendMpdCommand playlistinfo took 143 milliseconds May 31 21:41:53 volumio volumio[30240]: verbose: ControllerMpd::parseTrackInfo May 31 21:41:53 volumio volumio[30240]: info: ControllerMpd::pushState May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::servicePushState May 31 21:41:53 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:41:53 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:54 volumio volumio[30240]: info: CorePlayQueue::getTrack 0 May 31 21:41:54 volumio volumio[30240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nơi Tình Yêu Kết Thúc [ lyric video ] - Bùi Anh Tuấn","artist":"Egg","album":"YouTube","uri":"https://rr1---sn-42u-i5ole.googlevideo.com/videoplayback?expire=1748724111&ei=LxU7aI3QEJWCjuMPk927gQY&ip=113.23.123.186&id=o-AH9Kn5O7atIy1pfwmzihcJ6ecvjqfCOWo_LjDlQ-P6El&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748702511%2C&mh=Qr&mm=31%2C26&mn=sn-42u-i5ole%2Csn-30a7rne6&ms=au%2Conr&mv=m&mvi=1&pcm2cms=yes&pl=23&rms=au%2Cau&initcwndbps=2907500&bui=AY1jyLNuLmfuM3eCiR06wldfhg-7Gu3hjbWN5tGpJpamBQ1lNwcfnpt9C6XCrdndD1AJvkcbeMdz-yr8&spc=l3OVKWSDANjZ-i1-h45DnFdtZqUImgYXhmEzvDzOx7B-nCTgzL2XWzqh4lpzwv2NUCg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nI7xWzS2XwXXi1D45fZ5U40Q&rqh=1&gir=yes&clen=5356153&dur=318.041&lmt=1570086660388408&mt=1748701985&fvip=1&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5531432&n=4jKhny_d4iojJ4B8-H-_w8_Djvi94BgsSSHdUzW5Y&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgOdTrLm2DjPRKD_KuDsK3lbY95Uc0JY2DpbFzbiltK3ICIQC-UiN6ka9GghiO-qcCNVp-EBF2sxwaUOyw7VENBI5pfw%3D%3D&pot=MnifU-cHWPN36J9vOr-65-6JLY20tjyuM2J8CasYYqXQvTw0AyWlo6Nw0i36cWTI9cN8UHpT9oic4QfocQzOuPgpHGbw8tm0Zv7xu9dGMfX8Fg9HB1NQx6N3FyhyCKWbjKg7reMZKKNLVFgrF4SPMF8DOuGfqykHOog%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 31 21:41:54 volumio volumio[30240]: verbose: CURRENT POSITION 0 May 31 21:41:54 volumio volumio[30240]: info: CoreStateMachine::syncState stateService stop May 31 21:41:54 volumio volumio[30240]: info: CoreStateMachine::syncState currentStatus stop May 31 21:41:54 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:54 volumio volumio[30240]: info: No code May 31 21:41:54 volumio volumio[30240]: info: CoreStateMachine::pushState May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::volumioPushState May 31 21:41:54 volumio volumio[30240]: info: ------------------------------ 355ms May 31 21:41:54 volumio volumio[30240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 May 31 21:41:54 volumio volumio[30240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 May 31 21:41:54 volumio volumio[30240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 May 31 21:41:54 volumio volumio[30240]: info: FusionDsp - Reporting Fusion DSP Disabled May 31 21:41:54 volumio volumio[30240]: info: Removing fusiondspeq DSP Signal Path Element May 31 21:41:54 volumio volumio[30240]: info: Check plugin dependencies May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:54 volumio volumio[30240]: info: Rename folder May 31 21:41:54 volumio volumio[30240]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 31 21:41:54 volumio volumio[30240]: info: Completed starting MyVolumio Plugin May 31 21:41:54 volumio volumio[30240]: [Metrics] CommandRouter: 52s 92.70ms May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::volumiosetStartupVolume May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::Close All Modals sent May 31 21:41:54 volumio volumio[30240]: info: CoreCommandRouter::Close All Modals sent May 31 21:41:54 volumio volumio[30240]: info: Move to category May 31 21:41:54 volumio volumio[30240]: info: Folder /tmp/downloaded_plugin.zip removed May 31 21:41:54 volumio volumio[30240]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 31 21:41:54 volumio volumio[30240]: info: FusionDsp - ---- read samplerate from file: 44100 May 31 21:41:54 volumio volumio[30240]: error: FusionDsp - TypeError: Cannot read property 'stop' of null May 31 21:41:55 volumio volumio[30240]: info: Checking if install.sh is present May 31 21:41:55 volumio volumio[30240]: info: Executing install.sh May 31 21:41:55 volumio volumio[30240]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/volumio/buster/amd64 May 31 21:41:55 volumio volumio[30240]: info: FusionDsp - Stopping FusionDsp service May 31 21:41:55 volumio volumio[30240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:41:55 volumio sudo[30638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 31 21:41:55 volumio volumio[30240]: TypeError: Cannot read property 'stop' of null May 31 21:41:55 volumio volumio[30240]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 31 21:41:55 volumio volumio[30240]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 31 21:41:55 volumio volumio[30240]: at /volumio/app/pluginmanager.js:956:12 May 31 21:41:55 volumio volumio[30240]: at ChildProcess.exithandler (child_process.js:374:7) May 31 21:41:55 volumio volumio[30240]: at ChildProcess.emit (events.js:400:28) May 31 21:41:55 volumio volumio[30240]: at maybeClose (internal/child_process.js:1088:16) May 31 21:41:55 volumio volumio[30240]: at Socket. (internal/child_process.js:446:11) May 31 21:41:55 volumio volumio[30240]: at Socket.emit (events.js:400:28) May 31 21:41:55 volumio volumio[30240]: at Pipe. (net.js:686:12) May 31 21:41:55 volumio volumio[30240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:41:55 volumio sudo[30638]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:56 volumio sudo[30671]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 31 21:41:56 volumio sudo[30671]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:56 volumio sudo[30679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 21:40 May 31 21:41:56 volumio sudo[30679]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:56 volumio sudo[30679]: pam_unix(sudo:session): session closed for user root May 31 21:41:56 volumio go-librespot[30399]: time="2025-05-31T21:41:56+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 31 21:41:56 volumio volumio-remote-updater[665]: [2025-05-31 21:41:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 31 21:41:56 volumio volumio-remote-updater[665]: [2025-05-31 21:41:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 31 21:41:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 31 21:41:56 volumio sudo[30671]: pam_unix(sudo:session): session closed for user root May 31 21:41:56 volumio sudo[30638]: pam_unix(sudo:session): session closed for user root May 31 21:41:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 31 21:41:56 volumio systemd[1]: Started dynamicswap service. May 31 21:41:56 volumio systemd[1]: dynamicswap.service: Succeeded. May 31 21:41:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 31 21:41:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 31 21:41:56 volumio systemd[1]: Stopped Volumio Backend Module. May 31 21:41:56 volumio systemd[1]: Started Volumio Backend Module. May 31 21:41:56 volumio systemd[1]: Started dynamicswap service. May 31 21:41:56 volumio systemd[1]: dynamicswap.service: Succeeded. May 31 21:41:57 volumio volumio[30689]: info: ------------------------------------------- May 31 21:41:57 volumio volumio[30689]: info: ----- Volumio3 ---- May 31 21:41:57 volumio volumio[30689]: info: ------------------------------------------- May 31 21:41:57 volumio volumio[30689]: info: ----- System startup ---- May 31 21:41:57 volumio volumio[30689]: info: ------------------------------------------- May 31 21:41:58 volumio volumio[30689]: info: MYVOLUMIO Environment detected May 31 21:41:58 volumio volumio[30689]: info: Plugin folders cleanup May 31 21:41:58 volumio volumio[30689]: info: Scanning into folder /volumio/app/plugins/ May 31 21:41:58 volumio volumio[30689]: info: Scanning category audio_interface May 31 21:41:58 volumio volumio[30689]: info: Scanning category miscellanea May 31 21:41:58 volumio volumio[30689]: info: Scanning category music_service May 31 21:41:58 volumio volumio[30689]: info: Scanning category plugins.json May 31 21:41:58 volumio volumio[30689]: info: Scanning category system_controller May 31 21:41:58 volumio volumio[30689]: info: Scanning category user_interface May 31 21:41:58 volumio volumio[30689]: info: Scanning into folder /data/plugins/ May 31 21:41:58 volumio volumio[30689]: info: Scanning category audio_interface May 31 21:41:58 volumio volumio[30689]: info: Scanning category miscellanea May 31 21:41:58 volumio volumio[30689]: info: Scanning category music_service May 31 21:41:58 volumio volumio[30689]: info: Plugin folders cleanup completed May 31 21:41:58 volumio volumio[30689]: info: ------------------------------------------- May 31 21:41:58 volumio volumio[30689]: info: ----- Core plugins startup ---- May 31 21:41:58 volumio volumio[30689]: info: ------------------------------------------- May 31 21:41:58 volumio volumio[30689]: info: Loading plugins from folder /volumio/app/plugins/ May 31 21:41:58 volumio volumio[30689]: info: Adding plugin upnp to MyMusic Plugins May 31 21:41:58 volumio volumio[30689]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 21:41:58 volumio volumio[30689]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 21:41:58 volumio volumio[30689]: info: Loading plugins from folder /data/plugins/ May 31 21:41:58 volumio volumio[30689]: info: Loading plugin "system"... May 31 21:41:58 volumio volumio[30689]: info: Loading plugin "appearance"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "network"... May 31 21:41:59 volumio volumio[30689]: info: Refreshing Cached IP Addresses May 31 21:41:59 volumio sudo[30716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 21:41:59 volumio sudo[30716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:59 volumio sudo[30716]: pam_unix(sudo:session): session closed for user root May 31 21:41:59 volumio sudo[30719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 21:41:59 volumio sudo[30719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:59 volumio sudo[30719]: pam_unix(sudo:session): session closed for user root May 31 21:41:59 volumio volumio[30689]: info: Plugin peppy_screensaver is not enabled May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "services"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "alsa_controller"... May 31 21:41:59 volumio sudo[30734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 21:41:59 volumio sudo[30734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:41:59 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "wizard"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "networkfs"... May 31 21:41:59 volumio volumio[30689]: info: Starting Udev Watcher for removable devices May 31 21:41:59 volumio volumio[30689]: info: Ignoring mount for partition: boot May 31 21:41:59 volumio volumio[30689]: info: Ignoring mount for partition: volumio May 31 21:41:59 volumio volumio[30689]: info: Ignoring mount for partition: volumio_data May 31 21:41:59 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "volumio_command_line_client"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "upnp"... May 31 21:41:59 volumio volumio[30689]: info: [1748702519683] Starting Upmpd Daemon May 31 21:41:59 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "my_music"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "mpd"... May 31 21:41:59 volumio volumio[30689]: info: Loading plugin "upnp_browser"... May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "alarm-clock"... May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "airplay_emulation"... May 31 21:42:00 volumio volumio[30689]: info: Starting Shairport Sync May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "last_100"... May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "webradio"... May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "i2s_dacs"... May 31 21:42:00 volumio volumio[30689]: info: I2S DAC not set, start Auto-detection May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "volumiodiscovery"... May 31 21:42:00 volumio volumio[30689]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 21:42:00 volumio volumio[30689]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:42:00 volumio volumio[30689]: *** WARNING *** For more information see May 31 21:42:00 volumio volumio[30689]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 21:42:00 volumio volumio[30689]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:42:00 volumio volumio[30689]: *** WARNING *** For more information see May 31 21:42:00 volumio node[30689]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 21:42:00 volumio node[30689]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:42:00 volumio node[30689]: *** WARNING *** For more information see May 31 21:42:00 volumio node[30689]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 21:42:00 volumio node[30689]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 21:42:00 volumio node[30689]: *** WARNING *** For more information see May 31 21:42:00 volumio volumio[30689]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 21:42:00 volumio volumio[30689]: info: Discovery: Started advertising with name: Volumio May 31 21:42:00 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 21:42:00 volumio volumio[30689]: info: Loading plugin "spop"... May 31 21:42:01 volumio volumio-remote-updater[665]: [2025-05-31 21:42:01] [connect] Successful connection May 31 21:42:01 volumio volumio[30689]: info: Loading plugin "youtube2"... May 31 21:42:03 volumio volumio[30689]: info: Loading plugin "ytcr"... May 31 21:42:04 volumio sudo[30734]: pam_unix(sudo:session): session closed for user root May 31 21:42:05 volumio volumio[30689]: info: Loading plugin "ytmusic"... May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "outputs"... May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "albumart"... May 31 21:42:07 volumio volumio[30689]: info: Plugin example_plugin is not enabled May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "inputs"... May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "updater_comm"... May 31 21:42:07 volumio volumio[30689]: info: Plugin mpdemulation is not enabled May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "rest_api"... May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "websocket"... May 31 21:42:07 volumio volumio[30689]: Forking 1 albumart workers May 31 21:42:07 volumio volumio[30689]: info: Starting Socket.io Server version 2.3.0 May 31 21:42:07 volumio volumio[30689]: info: Loading plugin "fusiondsp"... May 31 21:42:07 volumio volumio[30689]: info: Applying required configuration parameters for plugin fusiondsp May 31 21:42:08 volumio volumio[30689]: info: Loading plugin "RoonBridge"... May 31 21:42:08 volumio volumio[30689]: Starting albumart workers May 31 21:42:08 volumio volumio[30689]: info: Applying required configuration parameters for plugin RoonBridge May 31 21:42:08 volumio volumio[30689]: info: Plugin podcast is not enabled May 31 21:42:08 volumio volumio[30689]: info: Loading i18n strings for locale vi May 31 21:42:08 volumio volumio[30689]: Updating browse sources language May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::initPlayerControls May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:42:08 volumio volumio[30689]: Express server listening on port 3000 May 31 21:42:08 volumio volumio[30689]: [Metrics] WebUI: 10s 969.86ms May 31 21:42:08 volumio volumio[30689]: info: Setting Device type: x86 May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::resetVolumioState May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::getcurrentVolume May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::volumioRetrievevolume May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:08 volumio volumio[30689]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 31 21:42:08 volumio volumio[30689]: info: Completed loading Core Plugins May 31 21:42:08 volumio volumio[30689]: info: Preparing to generate the ALSA configuration file May 31 21:42:08 volumio volumio[30689]: info: Volumio Network Manager: Network status updated: 2 May 31 21:42:08 volumio volumio[30689]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 31 21:42:08 volumio volumio[30689]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:42:08 volumio volumio[30689]: info: Reading ALSA contributions from plugins. May 31 21:42:08 volumio volumio[30689]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 31 21:42:08 volumio volumio[30689]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 31 21:42:08 volumio volumio[30689]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 21:42:08 volumio volumio[30689]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 31 21:42:08 volumio volumio[30689]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 31 21:42:08 volumio volumio[30689]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 21:42:08 volumio volumio[30689]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 31 21:42:08 volumio volumio[30689]: info: Reloading queue from file May 31 21:42:08 volumio volumio[30689]: info: VolumeController:: Volume=85 Mute =false May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::pushState May 31 21:42:08 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::volumioPushState May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::updateTrackBlock May 31 21:42:08 volumio volumio[30689]: info: CorePlayQueue::getTrackBlock May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::volumioRetrievevolume May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::setRepeat true single undefined May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::pushState May 31 21:42:08 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::volumioPushState May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::setRandom null May 31 21:42:08 volumio volumio[30689]: info: CoreStateMachine::pushState May 31 21:42:08 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:08 volumio volumio[30689]: info: CoreCommandRouter::volumioPushState May 31 21:42:09 volumio volumio[30689]: info: Asound.conf file unchanged, so no further update is needed May 31 21:42:09 volumio volumio[30689]: info: Output device has changed, restarting MPD May 31 21:42:09 volumio sudo[30826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:42:09 volumio sudo[30826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio sudo[30826]: pam_unix(sudo:session): session closed for user root May 31 21:42:09 volumio volumio[30689]: info: Output device has changed, restarting Shairport Sync May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:09 volumio sudo[30829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:42:09 volumio sudo[30829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:42:09 volumio systemd[1]: mpd.service: Succeeded. May 31 21:42:09 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:42:09 volumio volumio[30689]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:42:09 volumio volumio[30689]: info: ___________ START PLUGINS ___________ May 31 21:42:09 volumio systemd[1]: Starting Music Player Daemon... May 31 21:42:09 volumio volumio[30689]: info: ControllerMpd::onStart: Initializing MPD May 31 21:42:09 volumio volumio[30689]: info: Creating MPD Configuration file May 31 21:42:09 volumio sudo[30834]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:42:09 volumio sudo[30834]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:42:09 volumio sudo[30834]: pam_unix(sudo:session): session closed for user root May 31 21:42:09 volumio sudo[30836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:42:09 volumio sudo[30836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio volumio[30689]: info: [1748702529345] CoreMusicLibrary::Adding element Máy chủ Media May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:09 volumio sudo[30838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:42:09 volumio sudo[30838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio sudo[30836]: pam_unix(sudo:session): session closed for user root May 31 21:42:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 21:42:09 volumio systemd[1]: mpd.service: Succeeded. May 31 21:42:09 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:09 volumio systemd[1]: Starting Music Player Daemon... May 31 21:42:09 volumio volumio[30689]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:09 volumio sudo[30843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:42:09 volumio sudo[30843]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:09 volumio sudo[30843]: pam_unix(sudo:session): session closed for user root May 31 21:42:09 volumio volumio[30689]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:42:09 volumio volumio[30689]: info: [1748702529602] CoreMusicLibrary::Adding element Last_100 May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:42:09 volumio volumio[30689]: info: [1748702529605] CoreMusicLibrary::Adding element Webradio May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:42:09 volumio volumio[30689]: info: Initializing BBC Radios May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: Creating Spotify config file May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:42:09 volumio volumio[30689]: info: [1748702529894] CoreMusicLibrary::Adding element YouTube2 May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:09 volumio volumio[30689]: Cannot find translation for source YouTube2 May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 21:42:09 volumio volumio[30689]: info: [1748702529958] CoreMusicLibrary::Adding element YouTube Music May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:09 volumio volumio[30689]: Cannot find translation for source YouTube2 May 31 21:42:09 volumio volumio[30689]: Cannot find translation for source YouTube Music May 31 21:42:09 volumio volumio[30689]: info: Loading i18n strings for locale vi May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 31 21:42:09 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:09 volumio volumio[30689]: info: FusionDsp - mixtype--------------------- Hardware May 31 21:42:10 volumio volumio[30689]: info: Volumio Calling Home May 31 21:42:10 volumio sudo[30877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 31 21:42:10 volumio sudo[30877]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:10 volumio sudo[30877]: pam_unix(sudo:session): session closed for user root May 31 21:42:10 volumio volumio[30689]: info: Preparing to generate the ALSA configuration file May 31 21:42:10 volumio volumio[30689]: info: Discovery: adding 57db9a1b-0a0d-4b22-a611-ac9e54c71ec0 May 31 21:42:10 volumio volumio[30689]: info: Discovery: Found device Volumio May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:10 volumio sudo[30883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 21:42:10 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:10 volumio sudo[30883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:10 volumio volumio[30689]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:42:10 volumio volumio[30689]: info: Reading ALSA contributions from plugins. May 31 21:42:10 volumio sudo[30885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 21:42:10 volumio sudo[30883]: pam_unix(sudo:session): session closed for user root May 31 21:42:10 volumio sudo[30885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:10 volumio volumio[30689]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 31 21:42:10 volumio volumio[30689]: info: MPD Permissions set May 31 21:42:10 volumio volumio[30689]: info: MPD Permissions set May 31 21:42:10 volumio volumio[30689]: info: VolumeController:: Volume=85 Mute =false May 31 21:42:10 volumio sudo[30885]: pam_unix(sudo:session): session closed for user root May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:10 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:10 volumio volumio[30689]: info: CoreStateMachine::pushState May 31 21:42:10 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::volumioPushState May 31 21:42:10 volumio volumio[30689]: info: Spotify config file written May 31 21:42:10 volumio volumio[30689]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio sudo[30889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 31 21:42:10 volumio sudo[30889]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:10 volumio systemd[1]: Stopping go-librespot Daemon... May 31 21:42:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 31 21:42:10 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 31 21:42:10 volumio systemd[1]: Stopped go-librespot Daemon. May 31 21:42:10 volumio systemd[1]: Started go-librespot Daemon. May 31 21:42:10 volumio go-librespot[30894]: Librespot-go daemon starting... May 31 21:42:10 volumio sudo[30889]: pam_unix(sudo:session): session closed for user root May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 21:42:10 volumio go-librespot[30894]: time="2025-05-31T21:42:10+07:00" level=info msg="generated new device id: 1a9b056c749ff14a12c6910f0dc5c130f691d25d" May 31 21:42:10 volumio go-librespot[30894]: time="2025-05-31T21:42:10+07:00" level=debug msg="stored credentials found for c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:10 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 21:42:11 volumio mpd[30856]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:42:11 volumio mpd[30856]: output: No 'audio_output' defined in config file May 31 21:42:11 volumio mpd[30856]: couldn't open play stream: No such file or directory May 31 21:42:11 volumio mpd[30856]: sndio_output: Error opening default sndio device May 31 21:42:11 volumio mpd[30856]: output: Successfully detected a alsa audio device May 31 21:42:11 volumio mpd[30856]: zeroconf: No global port, disabling zeroconf May 31 21:42:11 volumio systemd[1]: Started Music Player Daemon. May 31 21:42:11 volumio sudo[30829]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio sudo[30838]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:11 volumio go-librespot[30894]: time="2025-05-31T21:42:11+07:00" level=debug msg="obtained new client token: AAD9WHXgFUHNIqq/JnNX1NJM1pKI9SKC0dJhgE+BsxlVHE+G40TxoGqeJq6HRfQhSzfWspikuqmQ55hFrNGUmlVgh6z0/OUV6Myxyw3Z3TmBQHXbzqtM1jFoz57n99rd+SsGaxfU4pxqupRN4QCPgMpYy9cvZedomEAWYPZXsrRmnsf7LU13i7YZkexyMxm/C3TFtI176ZxvXwO77BKNzI4X+Oq+rAp7GUh7ZQ+Wxn2SMGIB8omLUZiJCaichg==" May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:42:11 volumio volumio[30689]: info: Volumio called home May 31 21:42:11 volumio go-librespot[30894]: time="2025-05-31T21:42:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:11 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:11 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:42:11 volumio volumio[30689]: info: Received Get System Info May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:42:11 volumio volumio[30689]: info: Discovery: Getting this device information May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:11 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:11 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:11 volumio volumio[30689]: info: Listing playlists May 31 21:42:11 volumio volumio[30689]: info: Starting Shairport Sync May 31 21:42:11 volumio volumio[30689]: info: Starting Shairport Sync May 31 21:42:11 volumio sudo[30916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:42:11 volumio sudo[30916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:11 volumio go-librespot[30894]: time="2025-05-31T21:42:11+07:00" level=debug msg="completed keyexchange" May 31 21:42:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:42:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:42:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio volumio[30689]: info: Starting Shairport Sync May 31 21:42:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio sudo[30916]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio sudo[30920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:42:11 volumio sudo[30920]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:42:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:42:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio sudo[30920]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio sudo[30925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:42:11 volumio sudo[30925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:42:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:42:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:42:11 volumio sudo[30925]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio volumio[30689]: error: MPD error: The expression evaluated to a falsy value: May 31 21:42:11 volumio volumio[30689]: assert.ok(self.idling) May 31 21:42:11 volumio volumio[30689]: error: The expression evaluated to a falsy value: May 31 21:42:11 volumio volumio[30689]: assert.ok(self.idling) May 31 21:42:11 volumio volumio[30689]: info: CoreCommandRouter::volumioGetState May 31 21:42:11 volumio volumio[30689]: info: CorePlayQueue::getTrack 0 May 31 21:42:11 volumio volumio[30689]: info: MPD running with PID30856 May 31 21:42:11 volumio volumio[30689]: ,establishing connection May 31 21:42:11 volumio sudo[30933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 21:42:11 volumio volumio[30689]: error: updateQueue error: null May 31 21:42:11 volumio sudo[30933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:11 volumio sudo[30933]: pam_unix(sudo:session): session closed for user root May 31 21:42:11 volumio volumio[30689]: info: Shairport-Sync Started May 31 21:42:11 volumio volumio[30689]: Error adding Membership: Error: addMembership EINVAL May 31 21:42:11 volumio volumio[30689]: info: Shairport-Sync Started May 31 21:42:11 volumio volumio[30689]: info: Shairport-Sync Started May 31 21:42:11 volumio volumio[30689]: info: Asound.conf file unchanged, so no further update is needed May 31 21:42:11 volumio volumio[30689]: info: Output device has changed, restarting MPD May 31 21:42:11 volumio go-librespot[30894]: time="2025-05-31T21:42:11+07:00" level=debug msg="completed challenge" May 31 21:42:12 volumio volumio[30689]: info: Output device has changed, restarting Shairport Sync May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="authenticated as c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:42:12 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:12 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:42:12 volumio sudo[30937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:42:12 volumio sudo[30937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:12 volumio sudo[30939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:42:12 volumio sudo[30937]: pam_unix(sudo:session): session closed for user root May 31 21:42:12 volumio sudo[30939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:12 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:42:12 volumio volumio[30689]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:42:12 volumio systemd[1]: mpd.service: Succeeded. May 31 21:42:12 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:42:12 volumio volumio[30689]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 21:42:12 volumio volumio[30689]: SPOTIFY: BQD5oXOgCYcheTe74rEpbgrNh9Dt7Bl893kATJWTvZz-Jo-Fv8ufMiP6OFWuhkqfVRZTtVp6WNqw_PBYYJYzLS58J5Unmwo-KQqXmjSTpeEvvEWLQd5mC5N2x6e12mQeBUcgd3S_N3KkmrkpTXAleBNR7VCoX-D3qq4FCb97XHEnxJPkm--qMwxqdkEaivdSKEd428GRF6P5aYnrYpeZGqHUlWZlcuC-mkYaRxDseEj3CBHgc4S6tpj1-bEGM7Olo6wNOjsrQCLS3sVGDAwUbyPUXjed4NoQJ4HC8c871QqiLlE May 31 21:42:12 volumio volumio[30689]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 21:42:12 volumio volumio[30689]: info: New Spotify access token = BQD5oXOgCYcheTe74rEpbgrNh9Dt7Bl893kATJWTvZz-Jo-Fv8ufMiP6OFWuhkqfVRZTtVp6WNqw_PBYYJYzLS58J5Unmwo-KQqXmjSTpeEvvEWLQd5mC5N2x6e12mQeBUcgd3S_N3KkmrkpTXAleBNR7VCoX-D3qq4FCb97XHEnxJPkm--qMwxqdkEaivdSKEd428GRF6P5aYnrYpeZGqHUlWZlcuC-mkYaRxDseEj3CBHgc4S6tpj1-bEGM7Olo6wNOjsrQCLS3sVGDAwUbyPUXjed4NoQJ4HC8c871QqiLlE May 31 21:42:12 volumio volumio[30689]: info: Spotify credentials grant success - running version from March 24, 2019 May 31 21:42:12 volumio systemd[1]: Starting Music Player Daemon... May 31 21:42:12 volumio volumio[30689]: error: MPD error: Error: write EPIPE May 31 21:42:12 volumio volumio[30689]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 31 21:42:12 volumio volumio[30689]: info: Upmpdcli Daemon Started May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="authenticated as c4qfj1i26zzpvfz4yd31pdqb6" May 31 21:42:12 volumio volumio[30689]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:42:12 volumio volumio[30689]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 31 21:42:12 volumio volumio[30689]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 31 21:42:12 volumio volumio[30689]: info: camilladsp service started and running in background, instance 1 May 31 21:42:12 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:42:12 volumio sudo[30945]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:42:12 volumio sudo[30945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:12 volumio sudo[30945]: pam_unix(sudo:session): session closed for user root May 31 21:42:12 volumio volumio[30689]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 31 21:42:12 volumio volumio[30689]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 31 21:42:12 volumio volumio[30689]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 31 21:42:12 volumio volumio[30689]: info: FusionDsp loaded May 31 21:42:12 volumio volumio[30689]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:42:12 volumio sudo[30958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 31 21:42:12 volumio sudo[30958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="dealer connection opened" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="received connection id: OGM0MjNiNzAtZmUxNy00MjJjLWE1ZTctZDc3OTkwYzI0MjY1K2RlYWxlcit0Y3A6Ly8wYWM5NDlhMC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArRUJBQ0ZBNTdCNThGRTA3OUMzODE0Nzk1MTkzMzhCMUNGRTRBNjY2QTJBNUQ0MjZBQjQ1MzlENzA3NjI3NEFGOQ==" May 31 21:42:12 volumio volumio[30689]: info: FusionDsp - Reporting Fusion DSP Enabled May 31 21:42:12 volumio volumio[30689]: info: Adding Signal Path Element [object Object] May 31 21:42:12 volumio volumio[30689]: info: Adding fusiondspeq DSP Signal Path Element May 31 21:42:12 volumio volumio[30689]: info: FusionDsp - ---- installed callbackRead May 31 21:42:12 volumio volumio[30689]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:42:12 volumio systemd[1]: Started FusionDsp Daemon. May 31 21:42:12 volumio volumio[30689]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 31 21:42:12 volumio volumio[30689]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) May 31 21:42:12 volumio volumio[30689]: at onErrorNT (internal/child_process.js:472:16) May 31 21:42:12 volumio volumio[30689]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 31 21:42:12 volumio volumio[30689]: at runNextTicks (internal/process/task_queues.js:64:3) May 31 21:42:12 volumio volumio[30689]: at listOnTimeout (internal/timers.js:526:9) May 31 21:42:12 volumio volumio[30689]: at processTimers (internal/timers.js:500:7) { May 31 21:42:12 volumio volumio[30689]: errno: -2, May 31 21:42:12 volumio volumio[30689]: code: 'ENOENT', May 31 21:42:12 volumio volumio[30689]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 31 21:42:12 volumio volumio[30689]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 31 21:42:12 volumio volumio[30689]: spawnargs: [ May 31 21:42:12 volumio volumio[30689]: '-p', May 31 21:42:12 volumio volumio[30689]: 9876, May 31 21:42:12 volumio volumio[30689]: '-o', May 31 21:42:12 volumio volumio[30689]: '/tmp/camilladsp.log', May 31 21:42:12 volumio volumio[30689]: '-l', May 31 21:42:12 volumio volumio[30689]: 'warn', May 31 21:42:12 volumio volumio[30689]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 31 21:42:12 volumio volumio[30689]: ] May 31 21:42:12 volumio volumio[30689]: } May 31 21:42:12 volumio volumio[30689]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:42:12 volumio sudo[30958]: pam_unix(sudo:session): session closed for user root May 31 21:42:12 volumio volumio[30960]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory May 31 21:42:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT May 31 21:42:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 31 21:42:12 volumio go-librespot[30894]: time="2025-05-31T21:42:12+07:00" level=debug msg="put connect state because NEW_DEVICE" May 31 21:42:13 volumio sudo[30972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 21:41 May 31 21:42:13 volumio sudo[30972]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"