-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Tue 2025-05-06 21:14:11 EDT. -- May 06 21:13:42 volumio-office systemd-timedated[972]: Changed local time to Tue May 6 21:13:42 2025 May 06 21:13:42 volumio-office sudo[969]: pam_unix(sudo:session): session closed for user root May 06 21:13:42 volumio-office volumio-time-update[609]: volumio-time-update-util: System time updated successfully. May 06 21:13:42 volumio-office systemd[1]: Started Volumio Time Update Utility. May 06 21:13:42 volumio-office systemd[1]: Reached target Multi-User System. May 06 21:13:42 volumio-office systemd[1]: Reached target Graphical Interface. May 06 21:13:42 volumio-office systemd[1]: Starting Update UTMP about System Runlevel Changes... May 06 21:13:42 volumio-office systemd[1]: Starting Daily apt download activities... May 06 21:13:42 volumio-office systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 06 21:13:42 volumio-office systemd[1]: Started Update UTMP about System Runlevel Changes. May 06 21:13:43 volumio-office volumio-remote-updater[589]: [2025-05-06 21:13:43] [connect] Successful connection May 06 21:13:44 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:13:44 volumio-office volumio[938]: info: ----- Volumio3 ---- May 06 21:13:44 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:13:44 volumio-office volumio[938]: info: ----- System startup ---- May 06 21:13:44 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:13:46 volumio-office volumio[938]: info: MYVOLUMIO Environment detected May 06 21:13:47 volumio-office volumio[938]: info: Plugin folders cleanup May 06 21:13:47 volumio-office volumio[938]: info: Scanning into folder /volumio/app/plugins/ May 06 21:13:47 volumio-office volumio[938]: info: Scanning category audio_interface May 06 21:13:47 volumio-office volumio[938]: info: Scanning category miscellanea May 06 21:13:47 volumio-office volumio[938]: info: Scanning category music_service May 06 21:13:47 volumio-office volumio[938]: info: Scanning category plugins.json May 06 21:13:47 volumio-office volumio[938]: info: Scanning category system_controller May 06 21:13:47 volumio-office volumio[938]: info: Scanning category user_interface May 06 21:13:47 volumio-office volumio[938]: info: Scanning into folder /data/plugins/ May 06 21:13:47 volumio-office volumio[938]: info: Scanning category audio_interface May 06 21:13:47 volumio-office volumio[938]: info: Scanning category music_service May 06 21:13:47 volumio-office volumio[938]: info: Plugin folders cleanup completed May 06 21:13:47 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:13:47 volumio-office volumio[938]: info: ----- Core plugins startup ---- May 06 21:13:47 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:13:47 volumio-office volumio[938]: info: Loading plugins from folder /volumio/app/plugins/ May 06 21:13:47 volumio-office volumio[938]: info: Adding plugin upnp to MyMusic Plugins May 06 21:13:47 volumio-office volumio[938]: info: Adding plugin airplay_emulation to MyMusic Plugins May 06 21:13:47 volumio-office volumio[938]: info: Adding plugin upnp_browser to MyMusic Plugins May 06 21:13:47 volumio-office volumio[938]: info: Loading plugins from folder /data/plugins/ May 06 21:13:47 volumio-office volumio[938]: info: Loading plugin "system"... May 06 21:13:47 volumio-office systemd[1]: apt-daily.service: Succeeded. May 06 21:13:47 volumio-office systemd[1]: Started Daily apt download activities. May 06 21:13:47 volumio-office volumio[938]: info: Loading plugin "appearance"... May 06 21:13:47 volumio-office systemd[1]: Starting Daily apt upgrade and clean activities... May 06 21:13:50 volumio-office volumio[938]: info: Loading plugin "network"... May 06 21:13:50 volumio-office volumio[938]: info: Refreshing Cached IP Addresses May 06 21:13:50 volumio-office sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 21:13:50 volumio-office sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:13:50 volumio-office sudo[1077]: pam_unix(sudo:session): session closed for user root May 06 21:13:50 volumio-office sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 21:13:50 volumio-office sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:13:50 volumio-office sudo[1080]: pam_unix(sudo:session): session closed for user root May 06 21:13:50 volumio-office volumio[938]: info: Loading plugin "services"... May 06 21:13:50 volumio-office volumio[938]: info: Loading plugin "alsa_controller"... May 06 21:13:50 volumio-office sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 06 21:13:50 volumio-office sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:13:50 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 21:13:50 volumio-office volumio[938]: info: Loading plugin "wizard"... May 06 21:13:50 volumio-office volumio[938]: info: Loading plugin "networkfs"... May 06 21:13:50 volumio-office systemd[1]: apt-daily-upgrade.service: Succeeded. May 06 21:13:50 volumio-office systemd[1]: Started Daily apt upgrade and clean activities. May 06 21:13:50 volumio-office systemd[1]: Startup finished in 16.001s (kernel) + 29.247s (userspace) = 45.249s. May 06 21:13:50 volumio-office volumio[938]: info: Starting Udev Watcher for removable devices May 06 21:13:50 volumio-office sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=music,password=Lebateau61!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.190/Music /mnt/NAS/NAS May 06 21:13:50 volumio-office sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:13:51 volumio-office volumio[938]: info: Ignoring mount for partition: boot May 06 21:13:51 volumio-office volumio[938]: info: Ignoring mount for partition: volumio May 06 21:13:51 volumio-office volumio[938]: info: Ignoring mount for partition: volumio_data May 06 21:13:51 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 21:13:51 volumio-office volumio[938]: info: Loading plugin "volumio_command_line_client"... May 06 21:13:51 volumio-office volumio[938]: info: Loading plugin "upnp"... May 06 21:13:51 volumio-office volumio[938]: info: [1746580431061] Starting Upmpd Daemon May 06 21:13:51 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 21:13:51 volumio-office volumio[938]: info: Loading plugin "my_music"... May 06 21:13:51 volumio-office volumio[938]: info: Loading plugin "mpd"... May 06 21:13:51 volumio-office sudo[1091]: pam_unix(sudo:session): session closed for user root May 06 21:13:51 volumio-office kernel: Key type cifs.spnego registered May 06 21:13:51 volumio-office kernel: Key type cifs.idmap registered May 06 21:13:51 volumio-office kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 06 21:13:51 volumio-office kernel: CIFS: Attempting to mount //192.168.1.190/Music May 06 21:13:52 volumio-office volumio[938]: info: Loading plugin "upnp_browser"... May 06 21:13:52 volumio-office volumio[938]: info: Loading plugin "alarm-clock"... May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "airplay_emulation"... May 06 21:13:53 volumio-office volumio[938]: info: Starting Shairport Sync May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "last_100"... May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "webradio"... May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "i2s_dacs"... May 06 21:13:53 volumio-office volumio[938]: info: I2S DAC not set, start Auto-detection May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "volumiodiscovery"... May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** For more information see May 06 21:13:53 volumio-office node[938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 21:13:53 volumio-office volumio[938]: *** WARNING *** For more information see May 06 21:13:53 volumio-office node[938]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 21:13:53 volumio-office node[938]: *** WARNING *** For more information see May 06 21:13:53 volumio-office node[938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 21:13:53 volumio-office node[938]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 21:13:53 volumio-office node[938]: *** WARNING *** For more information see May 06 21:13:53 volumio-office volumio[938]: info: Applying required configuration parameters for plugin volumiodiscovery May 06 21:13:53 volumio-office volumio[938]: info: Discovery: Started advertising with name: Volumio-Office May 06 21:13:53 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 21:13:53 volumio-office volumio[938]: info: Loading plugin "spop"... May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "outputs"... May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "albumart"... May 06 21:13:57 volumio-office volumio[938]: info: Plugin example_plugin is not enabled May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "inputs"... May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "updater_comm"... May 06 21:13:57 volumio-office systemd[1]: systemd-fsckd.service: Succeeded. May 06 21:13:57 volumio-office volumio[938]: info: Plugin mpdemulation is not enabled May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "rest_api"... May 06 21:13:57 volumio-office kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 06 21:13:57 volumio-office kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 06 21:13:57 volumio-office sudo[1121]: pam_unix(sudo:session): session closed for user root May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "websocket"... May 06 21:13:57 volumio-office volumio[938]: info: Starting Socket.io Server version 2.3.0 May 06 21:13:57 volumio-office volumio[938]: info: Loading plugin "fusiondsp"... May 06 21:13:57 volumio-office volumio[938]: Forking 3 albumart workers May 06 21:13:58 volumio-office volumio[938]: info: Applying required configuration parameters for plugin fusiondsp May 06 21:13:58 volumio-office volumio[938]: info: Loading plugin "radio_paradise"... May 06 21:13:58 volumio-office volumio-remote-updater[589]: [2025-05-06 21:13:58] [connect] Successful connection May 06 21:13:59 volumio-office volumio[938]: info: Applying required configuration parameters for plugin radio_paradise May 06 21:13:59 volumio-office volumio[938]: info: [1746580439463] [RadioParadise] API delay: 5 May 06 21:13:59 volumio-office volumio[938]: info: Loading i18n strings for locale en May 06 21:13:59 volumio-office volumio[938]: Updating browse sources language May 06 21:13:59 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:13:59 volumio-office volumio[938]: Starting albumart workers May 06 21:13:59 volumio-office volumio[938]: Starting albumart workers May 06 21:13:59 volumio-office volumio[938]: Starting albumart workers May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::initPlayerControls May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 21:14:00 volumio-office volumio[938]: Express server listening on port 3000 May 06 21:14:00 volumio-office volumio[938]: [Metrics] WebUI: 17s 628.10ms May 06 21:14:00 volumio-office volumio[938]: info: CoreStateMachine::resetVolumioState May 06 21:14:00 volumio-office volumio[938]: info: CoreStateMachine::getcurrentVolume May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::volumioRetrievevolume May 06 21:14:00 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:00 volumio-office volumio[938]: info: Volumio Network Manager: Network status updated: 1 May 06 21:14:00 volumio-office volumio[938]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds May 06 21:14:01 volumio-office volumio[938]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.52 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 06 21:14:01 volumio-office volumio[938]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.52 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 06 21:14:01 volumio-office volumio[938]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.52 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 06 21:14:01 volumio-office volumio-remote-updater[589]: [2025-05-06 21:14:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746580438 101 May 06 21:14:01 volumio-office volumio[938]: 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: 4 May 06 21:14:01 volumio-office sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 21:14:01 volumio-office sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:01 volumio-office sudo[1213]: pam_unix(sudo:session): session closed for user root May 06 21:14:01 volumio-office volumio[938]: info: VolumeController:: Volume=100 Mute =false May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 21:14:01 volumio-office sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:01 volumio-office sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::updateTrackBlock May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrackBlock May 06 21:14:01 volumio-office sudo[1215]: pam_unix(sudo:session): session closed for user root May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioRetrievevolume May 06 21:14:01 volumio-office volumio[938]: info: Reloading queue from file May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::setRepeat null single undefined May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::setRandom null May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:01 volumio-office volumio[938]: info: Setting Device type: Raspberry PI May 06 21:14:01 volumio-office volumio[938]: info: Discovery: adding e4a8280f-6f31-4f76-8cab-f21e5fedef13 May 06 21:14:01 volumio-office volumio[938]: info: Discovery: Found device Volumio-Office May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioGetState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 06 21:14:01 volumio-office volumio[938]: info: VolumeController:: Volume=100 Mute =false May 06 21:14:01 volumio-office sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:01 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:01 volumio-office volumio[938]: info: Discovery: this is already registered, e4a8280f-6f31-4f76-8cab-f21e5fedef13 May 06 21:14:01 volumio-office volumio[938]: info: Discovery: Found device Volumio-Office May 06 21:14:01 volumio-office volumio[938]: info: CoreCommandRouter::volumioGetState May 06 21:14:01 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:01 volumio-office volumio[938]: info: Completed loading Core Plugins May 06 21:14:01 volumio-office volumio[938]: info: Preparing to generate the ALSA configuration file May 06 21:14:01 volumio-office volumio[938]: info: Discovery: adding 78d6fd98-0e01-4dea-b428-fb6fd220e8f2 May 06 21:14:01 volumio-office volumio[938]: info: Discovery: Found device Volumio-Livingroom May 06 21:14:01 volumio-office systemd[1]: Started UPnP Renderer front-end to MPD. May 06 21:14:01 volumio-office volumio[938]: info: Discovery: Connecting to remote: 192.168.1.52 May 06 21:14:01 volumio-office volumio[938]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 06 21:14:01 volumio-office volumio[938]: info: Reading ALSA contributions from plugins. May 06 21:14:01 volumio-office sudo[1228]: pam_unix(sudo:session): session closed for user root May 06 21:14:01 volumio-office volumio[938]: info: Upmpdcli Daemon Started May 06 21:14:01 volumio-office volumio[938]: info: Discovery: Connected to remote: 192.168.1.52 May 06 21:14:02 volumio-office volumio[938]: info: Asound.conf file unchanged, so no further update is needed May 06 21:14:02 volumio-office volumio[938]: info: Output device has changed, restarting MPD May 06 21:14:02 volumio-office volumio[938]: info: Output device has changed, restarting Shairport Sync May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:02 volumio-office sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 21:14:02 volumio-office sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:02 volumio-office sudo[1233]: pam_unix(sudo:session): session closed for user root May 06 21:14:02 volumio-office sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 21:14:02 volumio-office sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:02 volumio-office systemd[1]: Stopping Music Player Daemon... May 06 21:14:02 volumio-office volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 21:14:02 volumio-office volumio[938]: info: ___________ START PLUGINS ___________ May 06 21:14:02 volumio-office volumio[938]: info: ControllerMpd::onStart: Initializing MPD May 06 21:14:02 volumio-office volumio[938]: info: Creating MPD Configuration file May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 21:14:02 volumio-office volumio[938]: info: [1746580442252] CoreMusicLibrary::Adding element Media Servers May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:14:02 volumio-office sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 21:14:02 volumio-office sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:02 volumio-office sudo[1242]: pam_unix(sudo:session): session closed for user root May 06 21:14:02 volumio-office sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 21:14:02 volumio-office sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:02 volumio-office systemd[1]: mpd.service: Succeeded. May 06 21:14:02 volumio-office systemd[1]: Stopped Music Player Daemon. May 06 21:14:02 volumio-office volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:02 volumio-office systemd[1]: Starting Music Player Daemon... May 06 21:14:02 volumio-office volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 21:14:02 volumio-office volumio[938]: info: [1746580442552] CoreMusicLibrary::Adding element Last_100 May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 21:14:02 volumio-office volumio[938]: info: [1746580442559] CoreMusicLibrary::Adding element Webradio May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 21:14:02 volumio-office volumio[938]: info: Initializing BBC Radios May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: Creating Spotify config file May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:02 volumio-office sudo[1250]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 06 21:14:02 volumio-office sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:02 volumio-office sudo[1250]: pam_unix(sudo:session): session closed for user root May 06 21:14:02 volumio-office volumio[938]: info: Loading i18n strings for locale en May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:02 volumio-office volumio[938]: info: FusionDsp - mixtype--------------------- Hardware May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 21:14:02 volumio-office volumio[938]: info: [1746580442955] CoreMusicLibrary::Adding element Radio Paradise May 06 21:14:02 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:14:02 volumio-office volumio[938]: Cannot find translation for source Radio Paradise May 06 21:14:02 volumio-office volumio[938]: info: Volumio Calling Home May 06 21:14:03 volumio-office volumio[938]: info: Preparing to generate the ALSA configuration file May 06 21:14:03 volumio-office volumio[938]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 06 21:14:03 volumio-office volumio[938]: info: Reading ALSA contributions from plugins. May 06 21:14:03 volumio-office volumio[938]: error: Upnp client error: Error: This socket has been ended by the other party May 06 21:14:03 volumio-office volumio[938]: info: MPD Permissions set May 06 21:14:03 volumio-office volumio[938]: info: MPD Permissions set May 06 21:14:03 volumio-office volumio[938]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.52 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 06 21:14:03 volumio-office volumio[938]: info: Volumio called home May 06 21:14:03 volumio-office volumio[938]: info: Spotify config file written May 06 21:14:03 volumio-office sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 06 21:14:03 volumio-office sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:03 volumio-office volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office systemd[1]: Started go-librespot Daemon. May 06 21:14:03 volumio-office go-librespot[1272]: Librespot-go daemon starting... May 06 21:14:03 volumio-office sudo[1270]: pam_unix(sudo:session): session closed for user root May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:03 volumio-office volumio[938]: info: No need to fix Spotify hosts May 06 21:14:03 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 06 21:14:03 volumio-office volumio[938]: info: Starting Shairport Sync May 06 21:14:03 volumio-office volumio[938]: info: Starting Shairport Sync May 06 21:14:03 volumio-office volumio[938]: info: Starting Shairport Sync May 06 21:14:04 volumio-office sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 21:14:04 volumio-office sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:04 volumio-office sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 21:14:04 volumio-office sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:04 volumio-office volumio[938]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 06 21:14:04 volumio-office volumio[938]: SPOTIFY: BQA1Ovwx_B3Vm-w-JUJa3Ota4AgtMozmr82rwcho42L-z2Q3V0m-QeoPf9UxMSR0gsz92nVYC6m306oqiVxUfXGifdu_XdeQSd27GxX3XgESERQCQcCFlsy1ttyJGzioqK0VQMi8Itn3N4I7WfX8y1LSfwl52HC1UwYjquHbs3dfJL6i6Y8jAciVsTFZm_Bnm3seFFzcTwEtsWsmyQZMdeeJQrSOpulLQbMUbGS_kLuepYDp4Lc0-G1hiQNDIlcPQZxZttWw0X8ldmfxHaKRaU8hjjP-sEeQr-E97Cc3BlVC611mLUw May 06 21:14:04 volumio-office volumio[938]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 06 21:14:04 volumio-office volumio[938]: info: New Spotify access token = BQA1Ovwx_B3Vm-w-JUJa3Ota4AgtMozmr82rwcho42L-z2Q3V0m-QeoPf9UxMSR0gsz92nVYC6m306oqiVxUfXGifdu_XdeQSd27GxX3XgESERQCQcCFlsy1ttyJGzioqK0VQMi8Itn3N4I7WfX8y1LSfwl52HC1UwYjquHbs3dfJL6i6Y8jAciVsTFZm_Bnm3seFFzcTwEtsWsmyQZMdeeJQrSOpulLQbMUbGS_kLuepYDp4Lc0-G1hiQNDIlcPQZxZttWw0X8ldmfxHaKRaU8hjjP-sEeQr-E97Cc3BlVC611mLUw May 06 21:14:04 volumio-office volumio[938]: info: Spotify credentials grant success - running version from March 24, 2019 May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=info msg="generated new device id: 4116e773495aa60085c9bbaaa006d59543490def" May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=debug msg="stored credentials found for 315cgi6elqcmp6n3ayawwyap3psy" May 06 21:14:04 volumio-office sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 21:14:04 volumio-office sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:04 volumio-office systemd[1]: systemd-hostnamed.service: Succeeded. May 06 21:14:04 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 21:14:04 volumio-office systemd[1]: shairport-sync.service: Succeeded. May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::volumioGetState May 06 21:14:04 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:04 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::volumioGetState May 06 21:14:04 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:04 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 21:14:04 volumio-office sudo[1288]: pam_unix(sudo:session): session closed for user root May 06 21:14:04 volumio-office sudo[1290]: pam_unix(sudo:session): session closed for user root May 06 21:14:04 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 21:14:04 volumio-office volumio[938]: info: Asound.conf file unchanged, so no further update is needed May 06 21:14:04 volumio-office systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 06 21:14:04 volumio-office volumio[938]: info: Output device has changed, restarting MPD May 06 21:14:04 volumio-office systemd[1]: shairport-sync.service: Succeeded. May 06 21:14:04 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 21:14:04 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 21:14:04 volumio-office sudo[1293]: pam_unix(sudo:session): session closed for user root May 06 21:14:04 volumio-office volumio[938]: info: Output device has changed, restarting Shairport Sync May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:04 volumio-office sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 21:14:04 volumio-office sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:04 volumio-office sudo[1304]: pam_unix(sudo:session): session closed for user root May 06 21:14:04 volumio-office sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 21:14:04 volumio-office sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:04 volumio-office volumio[938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 21:14:04 volumio-office systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 06 21:14:04 volumio-office systemd[1]: mpd.service: Succeeded. May 06 21:14:04 volumio-office systemd[1]: Stopped Music Player Daemon. May 06 21:14:04 volumio-office volumio[938]: info: Shairport-Sync Started May 06 21:14:04 volumio-office volumio[938]: Error adding Membership: Error: addMembership EINVAL May 06 21:14:04 volumio-office volumio[938]: info: Shairport-Sync Started May 06 21:14:04 volumio-office systemd[1]: Starting Music Player Daemon... May 06 21:14:04 volumio-office volumio[938]: info: MPD Permissions set May 06 21:14:04 volumio-office volumio[938]: info: Shairport-Sync Started May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 21:14:04 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:04 volumio-office go-librespot[1272]: time="2025-05-06T21:14:04-04:00" level=debug msg="zeroconf server listening on port 39057" May 06 21:14:05 volumio-office sudo[1314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 06 21:14:05 volumio-office sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:05 volumio-office sudo[1314]: pam_unix(sudo:session): session closed for user root May 06 21:14:05 volumio-office volumio[938]: info: camilladsp spawned new process with pid 1328, instance 1, run: true May 06 21:14:05 volumio-office volumio[938]: info: camilladsp service started and running in background, instance 1 May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=debug msg="obtained new client token: AABVLXsEGG+qgaTJ3WFp/yFFaqawyyWEkql/ZXA3aox85NFIO57md1YxPBMG0zK5KbLpoP5U67uAzyvCrIFdLA0CtdLIDAfAD510lzsxAVtyWyBp9HTntRGbxKo1tLOiANmpfPSA7iC+I1HyfCYiPrb5Tfg8LWR4PQnIQU4ZLM2HLQ5EZEqIUDBzJn1R62+1/GPNNmq4ocA2et7YK/0woPGuaH2qS/lHp6is80cpVkTcHGyv4EClVbsV+2NYPQ==" May 06 21:14:05 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=info msg="connected to ap-gue1.spotify.com:4070" May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 <-AAAAAAAAAAAAA May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp loaded May 06 21:14:05 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=debug msg="completed keyexchange" May 06 21:14:05 volumio-office sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 06 21:14:05 volumio-office sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - Reporting Fusion DSP Enabled May 06 21:14:05 volumio-office volumio[938]: info: Adding Signal Path Element [object Object] May 06 21:14:05 volumio-office volumio[938]: info: Adding fusiondspeq DSP Signal Path Element May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - ---- installed callbackRead May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - Effects disabled May 06 21:14:05 volumio-office volumio[938]: info: Starting Shairport Sync May 06 21:14:05 volumio-office systemd[1]: Started FusionDsp Daemon. May 06 21:14:05 volumio-office sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 21:14:05 volumio-office volumio[938]: 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 06 21:14:05 volumio-office sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:05 volumio-office volumio[938]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 06 21:14:05 volumio-office sudo[1334]: pam_unix(sudo:session): session closed for user root May 06 21:14:05 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 21:14:05 volumio-office systemd[1]: shairport-sync.service: Succeeded. May 06 21:14:05 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 21:14:05 volumio-office volumio[938]: SPOTIFY: User informations: {"country":"US","display_name":"Pontus Rydin","email":"pontus.rydin@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/315cgi6elqcmp6n3ayawwyap3psy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/315cgi6elqcmp6n3ayawwyap3psy","id":"315cgi6elqcmp6n3ayawwyap3psy","images":[],"product":"premium","type":"user","uri":"spotify:user:315cgi6elqcmp6n3ayawwyap3psy"} May 06 21:14:05 volumio-office volumio[938]: info: Spotify Successfully logged in May 06 21:14:05 volumio-office volumio[938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 21:14:05 volumio-office volumio[938]: info: [1746580445669] CoreMusicLibrary::Adding element Spotify May 06 21:14:05 volumio-office volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 21:14:05 volumio-office volumio[938]: Cannot find translation for source Radio Paradise May 06 21:14:05 volumio-office volumio[938]: Cannot find translation for source Spotify May 06 21:14:05 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 21:14:05 volumio-office sudo[1343]: pam_unix(sudo:session): session closed for user root May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=debug msg="completed challenge" May 06 21:14:05 volumio-office volumio[938]: info: Shairport-Sync Started May 06 21:14:05 volumio-office sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=music,password=Lebateau61!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.190/Music /mnt/NAS/NAS May 06 21:14:05 volumio-office sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=debug msg="authenticated as 315cgi6elqcmp6n3ayawwyap3psy" May 06 21:14:05 volumio-office kernel: CIFS: Attempting to mount //192.168.1.190/Music May 06 21:14:05 volumio-office go-librespot[1272]: time="2025-05-06T21:14:05-04:00" level=debug msg="authenticated as 315cgi6elqcmp6n3ayawwyap3psy" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="dealer connection opened" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="initializing zeroconf session, username: 315cgi6elqcmp6n3ayawwyap3psy" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="autoplay enabled: false" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="received connection id: ZDkxZTU1OGItMWI0NC00N2I1LTg2MjMtMTU5OWNhZDRkZDgxK2RlYWxlcit0Y3A6Ly8wYWNiZmFhMy5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArRjUxRkFFRkRCMkM2MjI1NkNGRkQwQjI4RjEyMjRFNTMwOEYxQjE3Q0FDNDY5N0M5Nzc5NDVCMUFENUU3QzQzQg==" May 06 21:14:06 volumio-office go-librespot[1272]: time="2025-05-06T21:14:06-04:00" level=debug msg="put connect state because NEW_DEVICE" May 06 21:14:06 volumio-office volumio[938]: info: go-librespot daemon successfully initialized May 06 21:14:08 volumio-office mpd[1327]: May 06 21:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 21:14:08 volumio-office systemd[1]: Started Music Player Daemon. May 06 21:14:08 volumio-office volumio[938]: Upnp client error: Error: This socket has been ended by the other party May 06 21:14:08 volumio-office sudo[1244]: pam_unix(sudo:session): session closed for user root May 06 21:14:08 volumio-office sudo[1306]: pam_unix(sudo:session): session closed for user root May 06 21:14:08 volumio-office sudo[1235]: pam_unix(sudo:session): session closed for user root May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="handling transfer player command from d4dba6f49883101922dcd50f7d62022af0146c67" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="resolved context of track" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="loading track (paused: false, position: 1648910ms)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office volumio[938]: info: Completed starting Core Plugins May 06 21:14:08 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:14:08 volumio-office volumio[938]: info: ----- MyVolumio plugins startup ---- May 06 21:14:08 volumio-office volumio[938]: info: ------------------------------------------- May 06 21:14:08 volumio-office volumio[938]: info: [MyVolumio PluginManager] Fetching plans data.... May 06 21:14:08 volumio-office volumio[938]: info: MPD running with PID1327 May 06 21:14:08 volumio-office volumio[938]: ,establishing connection May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=trace msg="emitting websocket event: will_play" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="selected format OGG_VORBIS_320 (734be0ca778de108ad0d0097b3b1d793bbf7b92c)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="requested aes key for file 734be0ca778de108ad0d0097b3b1d793bbf7b92c, gid: 5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office volumio[938]: error: MPD error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: MPD error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: MPD error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: The expression evaluated to a falsy value: May 06 21:14:08 volumio-office volumio[938]: assert.ok(self.idling) May 06 21:14:08 volumio-office volumio[938]: error: updateQueue error: null May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1387" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1141" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="fetched first chunk of 22, total size is 11429566 bytes" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:08 volumio-office go-librespot[1272]: time="2025-05-06T21:14:08-04:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="fetched chunk 21/21, size: 419518" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="seek to 302413ms (diff: 155ms, samples: 13336413, bytes: 11420134)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate, raw: May 06 21:14:09 volumio-office volumio[938]: error: FusionDsp - invalid sample rate May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate from file: 44100 May 06 21:14:09 volumio-office volumio[938]: info: camilladsp stopping service pid 1328... May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="created new output device" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=info msg="loaded track \"Smalltown Boy\" (paused: false, position: 1648910ms, duration: 302413ms, prefetched: false)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="cleared closed output device" May 06 21:14:09 volumio-office volumio[938]: info: camilladsp service terminated, instance 1 May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - Effects disabled May 06 21:14:09 volumio-office volumio[938]: info: camilladsp service started and running in background, instance 1 May 06 21:14:09 volumio-office volumio[938]: info: camilladsp spawned new process with pid 1362, instance 1, run: true May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="scheduling prefetch in 272s" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="emitting websocket event: metadata" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="emitting websocket event: active" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="sending successful reply for dealer request" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="emitting websocket event: playing" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="emitting websocket event: not_playing" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="emitting websocket event: will_play" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="selected format OGG_VORBIS_320 (734be0ca778de108ad0d0097b3b1d793bbf7b92c)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="requested aes key for file 734be0ca778de108ad0d0097b3b1d793bbf7b92c, gid: 5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office volumio[938]: info: Initializing connection to go-librespot Websocket May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="new websocket client" May 06 21:14:09 volumio-office volumio[938]: info: Connection to go-librespot Websocket established May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="fetched first chunk of 22, total size is 11429566 bytes" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate, raw: May 06 21:14:09 volumio-office volumio[938]: error: FusionDsp - invalid sample rate May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - ---- read samplerate from file: 44100 May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=debug msg="created new output device" May 06 21:14:09 volumio-office go-librespot[1272]: time="2025-05-06T21:14:09-04:00" level=info msg="loaded track \"Smalltown Boy\" (paused: true, position: 0ms, duration: 302413ms, prefetched: false)" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - Effects disabled May 06 21:14:09 volumio-office volumio[938]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:10 volumio-office volumio[938]: info: camilladsp spawned new process with pid 1371, instance 1, run: true May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=trace msg="emitting websocket event: metadata" May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=trace msg="emitting websocket event: stopped" May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5vmRQ3zELMLUQPo2FLQ76x","name":"Smalltown Boy","artist_names":["Bronski Beat"],"album_name":"The Age of Consent","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022ec4aea38b44eba9756767fa","position":0,"duration":302413,"release_date":"year:1984 month:10 day:15","track_number":6,"disc_number":1}} May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"track"}} May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: PUSH STATE SPOTIFY May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: {"status":"stop","service":"spop","title":"Smalltown Boy","artist":"Bronski Beat","album":"The Age of Consent","albumart":"https://i.scdn.co/image/ab67616d00001e022ec4aea38b44eba9756767fa","uri":"spotify:track:5vmRQ3zELMLUQPo2FLQ76x","trackType":"spotify","seek":0,"duration":302,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 06 21:14:10 volumio-office volumio[938]: info: CoreCommandRouter::servicePushState May 06 21:14:10 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:10 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:10 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:10 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:10 volumio-office volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received spop May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"track"}} May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: PUSH STATE SPOTIFY May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: {"status":"stop","service":"spop","title":"Smalltown Boy","artist":"Bronski Beat","album":"The Age of Consent","albumart":"https://i.scdn.co/image/ab67616d00001e022ec4aea38b44eba9756767fa","uri":"spotify:track:5vmRQ3zELMLUQPo2FLQ76x","trackType":"spotify","seek":0,"duration":302,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 06 21:14:10 volumio-office volumio[938]: info: CoreCommandRouter::servicePushState May 06 21:14:10 volumio-office volumio[938]: info: CoreStateMachine::pushState May 06 21:14:10 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:10 volumio-office volumio[938]: info: CoreCommandRouter::volumioPushState May 06 21:14:10 volumio-office volumio[938]: info: CorePlayQueue::getTrack 0 May 06 21:14:10 volumio-office volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received spop May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:5vmRQ3zELMLUQPo2FLQ76x" May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 06 21:14:10 volumio-office go-librespot[1272]: time="2025-05-06T21:14:10-04:00" level=trace msg="emitting websocket event: paused" May 06 21:14:10 volumio-office volumio[938]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5vmRQ3zELMLUQPo2FLQ76x","play_origin":"track"}} May 06 21:14:10 volumio-office volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 21:14:10 volumio-office volumio[938]: TypeError: Cannot read property 'service' of undefined May 06 21:14:10 volumio-office volumio[938]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) May 06 21:14:10 volumio-office volumio[938]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:271:18) May 06 21:14:10 volumio-office volumio[938]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) May 06 21:14:10 volumio-office volumio[938]: at WebSocket.emit (events.js:315:20) May 06 21:14:10 volumio-office volumio[938]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) May 06 21:14:10 volumio-office volumio[938]: at Receiver.emit (events.js:315:20) May 06 21:14:10 volumio-office volumio[938]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) May 06 21:14:10 volumio-office volumio[938]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) May 06 21:14:10 volumio-office volumio[938]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) May 06 21:14:10 volumio-office volumio[938]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) May 06 21:14:10 volumio-office volumio[938]: at writeOrBuffer (internal/streams/writable.js:358:12) May 06 21:14:10 volumio-office volumio[938]: at Receiver.Writable.write (internal/streams/writable.js:303:10) May 06 21:14:10 volumio-office volumio[938]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) May 06 21:14:10 volumio-office volumio[938]: at Socket.emit (events.js:315:20) May 06 21:14:10 volumio-office volumio[938]: at addChunk (internal/streams/readable.js:309:12) May 06 21:14:10 volumio-office volumio[938]: at readableAddChunk (internal/streams/readable.js:284:9) May 06 21:14:10 volumio-office volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 21:14:11 volumio-office sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 21:13 May 06 21:14:11 volumio-office sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"