-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2026-02-21 13:09:39 CET. -- Feb 21 13:08:51 volumiosalon systemd-timedated[773]: Changed local time to Sat Feb 21 13:08:51 2026 Feb 21 13:08:51 volumiosalon sudo[771]: pam_unix(sudo:session): session closed for user root Feb 21 13:08:51 volumiosalon volumio-time-update[466]: volumio-time-update-util: System time updated successfully. Feb 21 13:08:51 volumiosalon systemd[1]: Starting Daily apt download activities... Feb 21 13:08:51 volumiosalon systemd[1]: Started Volumio Time Update Utility. Feb 21 13:08:51 volumiosalon systemd[1]: Reached target Multi-User System. Feb 21 13:08:51 volumiosalon systemd[1]: Reached target Graphical Interface. Feb 21 13:08:51 volumiosalon systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 21 13:08:51 volumiosalon systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 21 13:08:51 volumiosalon systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 21 13:08:51 volumiosalon nmbd[618]: [2026/02/21 13:08:51.523080, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 21 13:08:51 volumiosalon nmbd[618]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.102 for name WORKGROUP<1d>. Feb 21 13:08:51 volumiosalon nmbd[618]: This response was from IP 192.168.1.106, reporting an IP address of 192.168.1.106. Feb 21 13:08:52 volumiosalon volumio-remote-updater[461]: [2026-02-21 13:08:52] [connect] Successful connection Feb 21 13:08:53 volumiosalon volumio[674]: info: MYVOLUMIO Environment detected Feb 21 13:08:53 volumiosalon volumio[674]: info: Plugin folders cleanup Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning into folder /volumio/app/plugins/ Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category audio_interface Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category miscellanea Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category music_service Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category plugins.json Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category system_controller Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category user_interface Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning into folder /data/plugins/ Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category audio_interface Feb 21 13:08:53 volumiosalon volumio[674]: info: Scanning category music_service Feb 21 13:08:53 volumiosalon volumio[674]: info: Plugin folders cleanup completed Feb 21 13:08:53 volumiosalon volumio[674]: info: ------------------------------------------- Feb 21 13:08:53 volumiosalon volumio[674]: info: ----- Core plugins startup ---- Feb 21 13:08:53 volumiosalon volumio[674]: info: ------------------------------------------- Feb 21 13:08:53 volumiosalon volumio[674]: info: Loading plugins from folder /volumio/app/plugins/ Feb 21 13:08:53 volumiosalon volumio[674]: info: Adding plugin upnp to MyMusic Plugins Feb 21 13:08:53 volumiosalon volumio[674]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 21 13:08:53 volumiosalon volumio[674]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 21 13:08:53 volumiosalon volumio[674]: info: Loading plugins from folder /data/plugins/ Feb 21 13:08:53 volumiosalon volumio[674]: info: Loading plugin "system"... Feb 21 13:08:53 volumiosalon volumio[674]: info: Loading plugin "appearance"... Feb 21 13:08:56 volumiosalon systemd[1]: apt-daily.service: Succeeded. Feb 21 13:08:56 volumiosalon systemd[1]: Started Daily apt download activities. Feb 21 13:08:56 volumiosalon systemd[1]: Starting Daily apt upgrade and clean activities... Feb 21 13:08:57 volumiosalon volumio[674]: info: Loading plugin "network"... Feb 21 13:08:57 volumiosalon volumio[674]: info: Refreshing Cached IP Addresses Feb 21 13:08:57 volumiosalon sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 13:08:57 volumiosalon sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:08:57 volumiosalon sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 13:08:57 volumiosalon sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:08:57 volumiosalon sudo[851]: pam_unix(sudo:session): session closed for user root Feb 21 13:08:57 volumiosalon volumio[674]: info: Loading plugin "services"... Feb 21 13:08:57 volumiosalon volumio[674]: info: Loading plugin "alsa_controller"... Feb 21 13:08:57 volumiosalon sudo[853]: pam_unix(sudo:session): session closed for user root Feb 21 13:08:57 volumiosalon sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 21 13:08:57 volumiosalon sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:08:57 volumiosalon sudo[863]: pam_unix(sudo:session): session closed for user root Feb 21 13:08:57 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:08:57 volumiosalon volumio[674]: info: Loading plugin "wizard"... Feb 21 13:08:57 volumiosalon volumio[674]: info: Loading plugin "networkfs"... Feb 21 13:08:57 volumiosalon volumio[674]: info: Starting Udev Watcher for removable devices Feb 21 13:08:57 volumiosalon sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.106/Music /mnt/NAS/Nas-Music Feb 21 13:08:57 volumiosalon sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:08:57 volumiosalon volumio[674]: info: Ignoring mount for partition: boot Feb 21 13:08:57 volumiosalon volumio[674]: info: Ignoring mount for partition: volumio Feb 21 13:08:57 volumiosalon volumio[674]: info: Ignoring mount for partition: volumio_data Feb 21 13:08:57 volumiosalon volumio[674]: info: Mounting Device FLAC_AUDIO Feb 21 13:08:57 volumiosalon sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/FLAC_AUDIO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 21 13:08:57 volumiosalon sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:08:57 volumiosalon kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 21 13:08:57 volumiosalon sudo[892]: pam_unix(sudo:session): session closed for user root Feb 21 13:08:57 volumiosalon kernel: Key type cifs.spnego registered Feb 21 13:08:57 volumiosalon kernel: Key type cifs.idmap registered Feb 21 13:08:57 volumiosalon kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 21 13:08:57 volumiosalon 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. Feb 21 13:08:58 volumiosalon kernel: CIFS: Attempting to mount //192.168.1.106/Music Feb 21 13:08:58 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:08:58 volumiosalon volumio[674]: info: Loading plugin "volumio_command_line_client"... Feb 21 13:08:58 volumiosalon volumio[674]: info: Loading plugin "upnp"... Feb 21 13:08:58 volumiosalon volumio[674]: info: [1771675738067] Starting Upmpd Daemon Feb 21 13:08:58 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:08:58 volumiosalon volumio[674]: info: Loading plugin "my_music"... Feb 21 13:08:58 volumiosalon volumio[674]: info: Loading plugin "mpd"... Feb 21 13:08:59 volumiosalon volumio[674]: info: Loading plugin "upnp_browser"... Feb 21 13:08:59 volumiosalon systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 21 13:08:59 volumiosalon systemd[1]: Started Daily apt upgrade and clean activities. Feb 21 13:08:59 volumiosalon systemd[1]: Startup finished in 16.974s (kernel) + 28.179s (userspace) = 45.153s. Feb 21 13:08:59 volumiosalon volumio[674]: info: Loading plugin "alarm-clock"... Feb 21 13:09:00 volumiosalon volumio[674]: info: Loading plugin "airplay_emulation"... Feb 21 13:09:00 volumiosalon volumio[674]: info: Starting Shairport Sync Feb 21 13:09:00 volumiosalon volumio[674]: info: Loading plugin "last_100"... Feb 21 13:09:00 volumiosalon volumio[674]: info: Loading plugin "webradio"... Feb 21 13:09:00 volumiosalon volumio[674]: info: Loading plugin "i2s_dacs"... Feb 21 13:09:00 volumiosalon volumio[674]: info: I2S DAC not set, start Auto-detection Feb 21 13:09:00 volumiosalon volumio[674]: info: Loading plugin "volumiodiscovery"... Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** For more information see Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:09:00 volumiosalon volumio[674]: *** WARNING *** For more information see Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** For more information see Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:09:00 volumiosalon node[674]: *** WARNING *** For more information see Feb 21 13:09:00 volumiosalon volumio[674]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 21 13:09:00 volumiosalon volumio[674]: info: Discovery: Started advertising with name: VolumioSalon Feb 21 13:09:01 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:09:01 volumiosalon volumio[674]: info: Loading plugin "spop"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "outputs"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "albumart"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Plugin example_plugin is not enabled Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "inputs"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "updater_comm"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Plugin mpdemulation is not enabled Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "rest_api"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "websocket"... Feb 21 13:09:04 volumiosalon volumio[674]: info: Starting Socket.io Server version 2.3.0 Feb 21 13:09:04 volumiosalon volumio[674]: info: Loading plugin "fusiondsp"... Feb 21 13:09:04 volumiosalon volumio[674]: Forking 3 albumart workers Feb 21 13:09:05 volumiosalon volumio[674]: info: Applying required configuration parameters for plugin fusiondsp Feb 21 13:09:05 volumiosalon volumio[674]: info: Loading i18n strings for locale fr Feb 21 13:09:06 volumiosalon volumio[674]: Updating browse sources language Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::initPlayerControls Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: Express server listening on port 3000 Feb 21 13:09:06 volumiosalon volumio[674]: [Metrics] WebUI: 18s 295.05ms Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreStateMachine::resetVolumioState Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreStateMachine::getcurrentVolume Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:09:06 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:06 volumiosalon volumio[674]: Starting albumart workers Feb 21 13:09:07 volumiosalon volumio[674]: Starting albumart workers Feb 21 13:09:07 volumiosalon volumio[674]: Starting albumart workers Feb 21 13:09:07 volumiosalon volumio[674]: info: Volumio Network Manager: Network status updated: 1 Feb 21 13:09:07 volumiosalon volumio[674]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 21 13:09:07 volumiosalon volumio[674]: wlan0 Interface doesn't support scanning. Feb 21 13:09:07 volumiosalon volumio[674]: info: Cannot use regular scanning, forcing with ap-force Feb 21 13:09:07 volumiosalon sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 21 13:09:07 volumiosalon sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:07 volumiosalon sudo[981]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:07 volumiosalon volumio[674]: command failed: No such device (-19) Feb 21 13:09:07 volumiosalon volumio[674]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 21 13:09:07 volumiosalon volumio[674]: command failed: No such device (-19) Feb 21 13:09:07 volumiosalon volumio[674]: verbose: New Socket.io Connection to 192.168.1.102:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 21 13:09:07 volumiosalon volumio-remote-updater[461]: [2026-02-21 13:09:07] [connect] Successful connection Feb 21 13:09:07 volumiosalon volumio[674]: verbose: New Socket.io Connection to 192.168.1.102:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::pushState Feb 21 13:09:07 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::volumioPushState Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::updateTrackBlock Feb 21 13:09:07 volumiosalon volumio[674]: info: CorePlayQueue::getTrackBlock Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:09:07 volumiosalon volumio[674]: info: Reloading queue from file Feb 21 13:09:07 volumiosalon volumio-remote-updater[461]: [2026-02-21 13:09:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771675747 101 Feb 21 13:09:07 volumiosalon volumio[674]: 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: 3 Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::setRepeat true single undefined Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::pushState Feb 21 13:09:07 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::volumioPushState Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::setRandom null Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreStateMachine::pushState Feb 21 13:09:07 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:07 volumiosalon volumio[674]: info: CoreCommandRouter::volumioPushState Feb 21 13:09:07 volumiosalon volumio[674]: info: Setting Device type: Raspberry PI Feb 21 13:09:08 volumiosalon volumio[674]: info: CoreStateMachine::pushState Feb 21 13:09:08 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:08 volumiosalon volumio[674]: info: CoreCommandRouter::volumioPushState Feb 21 13:09:08 volumiosalon systemd[1]: systemd-fsckd.service: Succeeded. Feb 21 13:09:08 volumiosalon sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 13:09:08 volumiosalon sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:08 volumiosalon sudo[1012]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:08 volumiosalon sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 13:09:08 volumiosalon sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: adding 0aa50467-b9fd-4f19-86d3-d5a93ac96242 Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: Found device VolumioChambre Feb 21 13:09:08 volumiosalon sudo[1014]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:08 volumiosalon sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 21 13:09:08 volumiosalon sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: Connecting to remote: 192.168.1.108 Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: adding df64bef3-3f69-4f0c-ab8c-e8a43925e8d1 Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: Found device VolumioSalon Feb 21 13:09:08 volumiosalon volumio[674]: info: CoreCommandRouter::volumioGetState Feb 21 13:09:08 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:08 volumiosalon systemd[1]: Started UPnP Renderer front-end to MPD. Feb 21 13:09:08 volumiosalon sudo[1019]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:08 volumiosalon volumio[674]: info: Discovery: Connected to remote: 192.168.1.108 Feb 21 13:09:08 volumiosalon volumio[674]: info: Upmpdcli Daemon Started Feb 21 13:09:09 volumiosalon volumio[1024]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 21 13:09:09 volumiosalon volumio[674]: verbose: New Socket.io Connection to 192.168.1.102:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 21 13:09:09 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 21 13:09:10 volumiosalon volumio[674]: info: CoreCommandRouter::volumioGetState Feb 21 13:09:10 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:16 volumiosalon sudo[881]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:16 volumiosalon volumio[674]: info: Completed loading Core Plugins Feb 21 13:09:16 volumiosalon volumio[674]: info: Preparing to generate the ALSA configuration file Feb 21 13:09:16 volumiosalon volumio[674]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 13:09:16 volumiosalon volumio[674]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 21 13:09:16 volumiosalon volumio[674]: info: Reading ALSA contributions from plugins. Feb 21 13:09:16 volumiosalon volumio[674]: info: Asound.conf file unchanged, so no further update is needed Feb 21 13:09:16 volumiosalon volumio[674]: info: Output device has changed, restarting MPD Feb 21 13:09:16 volumiosalon volumio[674]: info: Output device has changed, restarting Shairport Sync Feb 21 13:09:16 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:16 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:16 volumiosalon sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:09:16 volumiosalon sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:16 volumiosalon sudo[1072]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:16 volumiosalon sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:09:16 volumiosalon sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:16 volumiosalon systemd[1]: Stopping Music Player Daemon... Feb 21 13:09:16 volumiosalon volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:09:16 volumiosalon volumio[674]: info: ___________ START PLUGINS ___________ Feb 21 13:09:17 volumiosalon volumio[674]: info: ControllerMpd::onStart: Initializing MPD Feb 21 13:09:17 volumiosalon volumio[674]: info: Creating MPD Configuration file Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:09:17 volumiosalon volumio[674]: info: [1771675757069] CoreMusicLibrary::Adding element Serveurs Média Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:09:17 volumiosalon sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:09:17 volumiosalon sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:17 volumiosalon sudo[1081]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:17 volumiosalon sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:17 volumiosalon sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:17 volumiosalon systemd[1]: mpd.service: Succeeded. Feb 21 13:09:17 volumiosalon systemd[1]: Stopped Music Player Daemon. Feb 21 13:09:17 volumiosalon volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:09:17 volumiosalon systemd[1]: Starting Music Player Daemon... Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:17 volumiosalon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 21 13:09:17 volumiosalon systemd[1]: mpd.service: Succeeded. Feb 21 13:09:17 volumiosalon systemd[1]: Stopped Music Player Daemon. Feb 21 13:09:17 volumiosalon systemd[1]: Starting Music Player Daemon... Feb 21 13:09:17 volumiosalon volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:09:17 volumiosalon volumio[674]: info: [1771675757367] CoreMusicLibrary::Adding element Last_100 Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:09:17 volumiosalon volumio[674]: info: [1771675757374] CoreMusicLibrary::Adding element Webradio Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:09:17 volumiosalon volumio[674]: info: Initializing BBC Radios Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:17 volumiosalon sudo[1091]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 13:09:17 volumiosalon sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:17 volumiosalon sudo[1091]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:17 volumiosalon volumio[674]: info: Creating Spotify config file Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:17 volumiosalon volumio[674]: info: Loading i18n strings for locale fr Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 21 13:09:17 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:17 volumiosalon volumio[674]: info: FusionDsp - mixtype--------------------- Software Feb 21 13:09:17 volumiosalon volumio[674]: info: Volumio Calling Home Feb 21 13:09:17 volumiosalon volumio[674]: info: Preparing to generate the ALSA configuration file Feb 21 13:09:18 volumiosalon volumio[674]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 13:09:18 volumiosalon volumio[674]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 21 13:09:18 volumiosalon volumio[674]: info: Reading ALSA contributions from plugins. Feb 21 13:09:18 volumiosalon volumio[674]: info: MPD Permissions set Feb 21 13:09:18 volumiosalon volumio[674]: info: MPD Permissions set Feb 21 13:09:18 volumiosalon volumio[674]: info: Volumio called home Feb 21 13:09:18 volumiosalon volumio[674]: info: Spotify config file written Feb 21 13:09:18 volumiosalon sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 21 13:09:18 volumiosalon sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:18 volumiosalon volumio[674]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon systemd[1]: Started go-librespot Daemon. Feb 21 13:09:18 volumiosalon go-librespot[1126]: go-librespot daemon starting... Feb 21 13:09:18 volumiosalon sudo[1124]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:18 volumiosalon volumio[674]: info: No need to fix Spotify hosts Feb 21 13:09:18 volumiosalon volumio[674]: info: Starting Shairport Sync Feb 21 13:09:18 volumiosalon volumio[674]: info: Starting Shairport Sync Feb 21 13:09:18 volumiosalon volumio[674]: info: Starting Shairport Sync Feb 21 13:09:18 volumiosalon sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:09:18 volumiosalon sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:18 volumiosalon volumio[674]: info: CoreCommandRouter::volumioGetState Feb 21 13:09:18 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:18 volumiosalon sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:09:18 volumiosalon sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:18 volumiosalon sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:09:18 volumiosalon sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:19 volumiosalon volumio[674]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 21 13:09:19 volumiosalon volumio[674]: SPOTIFY: BQDO0CCElyDNIQ3sJfmM8ec13rVDcW4znVxH4X-MTH6bcdd-7DkPU6ciUosmmuNfGqp0Loq66rQzT8v35sHEsiqkdJG4XFAMr3NgJMND0Ou3pUQdrgxPhscoBTBaB-e9Kzf-LBUv9129yxeUyDjFC9hIuon9Li6rrdeA6orP3IWGpN_oM2fR0lq6zzhVTFj7srgHqB7zNyX6cwMzuzoM0EM5jgnN-GUO2K_E8inT6VPJ990kFj_F81ZhGYsrB5ov1t4uLcCy31xnH-Hvqq4AIYSCqQgWrvISe9I1er7-98DB78SPxieM9w Feb 21 13:09:19 volumiosalon volumio[674]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 21 13:09:19 volumiosalon volumio[674]: info: New Spotify access token = BQDO0CCElyDNIQ3sJfmM8ec13rVDcW4znVxH4X-MTH6bcdd-7DkPU6ciUosmmuNfGqp0Loq66rQzT8v35sHEsiqkdJG4XFAMr3NgJMND0Ou3pUQdrgxPhscoBTBaB-e9Kzf-LBUv9129yxeUyDjFC9hIuon9Li6rrdeA6orP3IWGpN_oM2fR0lq6zzhVTFj7srgHqB7zNyX6cwMzuzoM0EM5jgnN-GUO2K_E8inT6VPJ990kFj_F81ZhGYsrB5ov1t4uLcCy31xnH-Hvqq4AIYSCqQgWrvISe9I1er7-98DB78SPxieM9w Feb 21 13:09:19 volumiosalon volumio[674]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 21 13:09:19 volumiosalon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:09:19 volumiosalon systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:09:19 volumiosalon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:09:19 volumiosalon volumio[674]: info: Asound.conf file unchanged, so no further update is needed Feb 21 13:09:19 volumiosalon volumio[674]: info: Output device has changed, restarting MPD Feb 21 13:09:19 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:19+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 13:09:19 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:19+01:00" level=debug msg="app state loaded" Feb 21 13:09:19 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 13:09:19 volumiosalon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:09:19 volumiosalon sudo[1146]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:19 volumiosalon volumio[674]: info: Output device has changed, restarting Shairport Sync Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:19 volumiosalon sudo[1142]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:19 volumiosalon sudo[1144]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:19 volumiosalon sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:09:19 volumiosalon sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:19 volumiosalon sudo[1152]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:19 volumiosalon sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:09:19 volumiosalon sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:19 volumiosalon volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:09:19 volumiosalon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 21 13:09:19 volumiosalon systemd[1]: mpd.service: Succeeded. Feb 21 13:09:19 volumiosalon systemd[1]: Stopped Music Player Daemon. Feb 21 13:09:19 volumiosalon volumio[674]: info: MPD Permissions set Feb 21 13:09:19 volumiosalon volumio[674]: info: Shairport-Sync Started Feb 21 13:09:19 volumiosalon volumio[674]: Error adding Membership: Error: addMembership EINVAL Feb 21 13:09:19 volumiosalon volumio[674]: info: Shairport-Sync Started Feb 21 13:09:19 volumiosalon volumio[674]: info: Shairport-Sync Started Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:19 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:19 volumiosalon systemd[1]: Starting Music Player Daemon... Feb 21 13:09:19 volumiosalon volumio[674]: info: Starting Shairport Sync Feb 21 13:09:19 volumiosalon sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:09:19 volumiosalon sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:19 volumiosalon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:09:19 volumiosalon systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:09:19 volumiosalon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:09:19 volumiosalon sudo[1163]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 13:09:19 volumiosalon sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:19 volumiosalon sudo[1163]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:20 volumiosalon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:09:20 volumiosalon sudo[1170]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 21 13:09:20 volumiosalon volumio[674]: info: camilladsp spawned new process with pid 1180, instance 1, run: true Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 21 13:09:20 volumiosalon volumio[674]: info: camilladsp service started and running in background, instance 1 Feb 21 13:09:20 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=info msg="zeroconf server listening on port 41035" Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp loaded Feb 21 13:09:20 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 13:09:20 volumiosalon sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 21 13:09:20 volumiosalon sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=debug msg="obtained new client token: AABr6rtRX/t5XQeD2O7FcHXRa0GGt4ws3jXMNsKGoYTj0Lr/E0defY/s5yIx1vPB67f5kkUYJU3+zBTV8noxUvy25OQcBmEGpky0eZRRvpZtVElxXKgAZi2bCwlk41tL0Vc//mNtQlKpGcGL7TVyM5d7aSKcVF9HiyTZtH/i99bEOvu5NfbiFtpYpNynuCvEyNCMKQ3cQZqLM4XyJqyHcSQlaWkDMPEQoiy8DWuD51+RRPn75bv+j4esWIov" Feb 21 13:09:20 volumiosalon volumio[674]: info: Adding Signal Path Element [object Object] Feb 21 13:09:20 volumiosalon volumio[674]: info: Adding fusiondspeq DSP Signal Path Element Feb 21 13:09:20 volumiosalon systemd[1]: Started FusionDsp Daemon. Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp - ---- installed callbackRead Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 21 13:09:20 volumiosalon sudo[1189]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 13:09:20 volumiosalon go-librespot[1126]: time="2026-02-21T13:09:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 21 13:09:20 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:09:20 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 13:09:20 volumiosalon volumio[674]: info: Shairport-Sync Started Feb 21 13:09:20 volumiosalon volumio[674]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 21 13:09:20 volumiosalon volumio[674]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 21 13:09:20 volumiosalon volumio[674]: SPOTIFY: User informations: {"country":"FR","display_name":"andre060760","email":"andre.phocas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/andre060760"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/andre060760","id":"andre060760","images":[],"product":"premium","type":"user","uri":"spotify:user:andre060760"} Feb 21 13:09:20 volumiosalon volumio[674]: info: Spotify Successfully logged in Feb 21 13:09:20 volumiosalon volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:09:20 volumiosalon volumio[674]: info: [1771675760768] CoreMusicLibrary::Adding element Spotify Feb 21 13:09:20 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:09:20 volumiosalon volumio[674]: Cannot find translation for source Spotify Feb 21 13:09:21 volumiosalon systemd[1]: systemd-timedated.service: Succeeded. Feb 21 13:09:21 volumiosalon volumio[674]: info: go-librespot daemon successfully initialized Feb 21 13:09:22 volumiosalon mpd[1181]: Feb 21 13:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:09:23 volumiosalon systemd[1]: Started Music Player Daemon. Feb 21 13:09:23 volumiosalon sudo[1083]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:23 volumiosalon sudo[1154]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:23 volumiosalon sudo[1074]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:23 volumiosalon volumio[674]: info: Completed starting Core Plugins Feb 21 13:09:23 volumiosalon volumio[674]: info: ------------------------------------------- Feb 21 13:09:23 volumiosalon volumio[674]: info: ----- MyVolumio plugins startup ---- Feb 21 13:09:23 volumiosalon volumio[674]: info: ------------------------------------------- Feb 21 13:09:23 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 21 13:09:23 volumiosalon volumio[674]: error: MPD error: The expression evaluated to a falsy value: Feb 21 13:09:23 volumiosalon volumio[674]: assert.ok(self.idling) Feb 21 13:09:23 volumiosalon volumio[674]: error: The expression evaluated to a falsy value: Feb 21 13:09:23 volumiosalon volumio[674]: assert.ok(self.idling) Feb 21 13:09:23 volumiosalon volumio[674]: error: MPD error: The expression evaluated to a falsy value: Feb 21 13:09:23 volumiosalon volumio[674]: assert.ok(self.idling) Feb 21 13:09:23 volumiosalon volumio[674]: error: The expression evaluated to a falsy value: Feb 21 13:09:23 volumiosalon volumio[674]: assert.ok(self.idling) Feb 21 13:09:23 volumiosalon volumio[674]: info: MPD running with PID1181 Feb 21 13:09:23 volumiosalon volumio[674]: ,establishing connection Feb 21 13:09:23 volumiosalon volumio[674]: error: updateQueue error: null Feb 21 13:09:23 volumiosalon volumio[674]: error: updateQueue error: null Feb 21 13:09:23 volumiosalon systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 13:09:23 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 21 13:09:23 volumiosalon systemd[1]: Stopped go-librespot Daemon. Feb 21 13:09:23 volumiosalon systemd[1]: Started go-librespot Daemon. Feb 21 13:09:23 volumiosalon go-librespot[1203]: go-librespot daemon starting... Feb 21 13:09:23 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:23+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 13:09:23 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:23+01:00" level=debug msg="app state loaded" Feb 21 13:09:23 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=info msg="zeroconf server listening on port 38451" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=debug msg="obtained new client token: AABnPanMr7+ix3eMXAgE/cMeZEp4dkPsaFLmaS4i1IuaG4Bg4hIptOVAG+11DacGMFdH/fjOsfAfHwGMPPCgGSbeC4Sex1rQ0xKBECrNZjY1nMEpphqgw/pKlxZyScTCAAPM/83XYiJCUj+Y9KZq6xNfPT1myWu67JvDKKWlMaa23yBE4DdRd+rudUJdEcstxRto2iHIn8iI1pBf7AIC4KTdBI8ZLl/JuhBpl5fbzo2CRCCeInjrBCKcJjCU" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 13:09:24 volumiosalon go-librespot[1203]: time="2026-02-21T13:09:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 21 13:09:24 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:09:24 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 13:09:24 volumiosalon volumio[674]: info: Initializing connection to go-librespot Websocket Feb 21 13:09:24 volumiosalon volumio[674]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 13:09:27 volumiosalon systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 13:09:27 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 21 13:09:27 volumiosalon systemd[1]: Stopped go-librespot Daemon. Feb 21 13:09:27 volumiosalon systemd[1]: Started go-librespot Daemon. Feb 21 13:09:27 volumiosalon go-librespot[1225]: go-librespot daemon starting... Feb 21 13:09:27 volumiosalon volumio[674]: info: Initializing connection to go-librespot Websocket Feb 21 13:09:27 volumiosalon volumio[674]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 13:09:27 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:27+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 13:09:27 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:27+01:00" level=debug msg="app state loaded" Feb 21 13:09:27 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=info msg="zeroconf server listening on port 40367" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=debug msg="obtained new client token: AAAOuwbQS+IrieeBXvyMd/PpyO6hZaQDMBz1TjPeK1EHtapDqh7DoyPRBQdibzLNmZHhNAz8pQ0EUZkikzbvRaZZHdai1MwU02/CHDk/uz26BwRDfMPbtMw1j7FSaA0lTtOCrBgevspuUnzqzCZwiood8UH9YW/aim6tKwkqfcS8B6wbTTOXJbURcX3dHdFt82fGmIcbRzSE9dw3GQKre4Vx6eO75sj5O+hfUtEUKWb8rRM0iLoerjJf33+f" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 13:09:28 volumiosalon go-librespot[1225]: time="2026-02-21T13:09:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 21 13:09:28 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:09:28 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 13:09:30 volumiosalon volumio[674]: info: Initializing connection to go-librespot Websocket Feb 21 13:09:30 volumiosalon volumio[674]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 13:09:31 volumiosalon systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 13:09:31 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 21 13:09:31 volumiosalon systemd[1]: Stopped go-librespot Daemon. Feb 21 13:09:31 volumiosalon systemd[1]: Started go-librespot Daemon. Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 21 13:09:31 volumiosalon go-librespot[1236]: go-librespot daemon starting... Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin bluetooth to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin multiroom to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin metavolumio to MyMusic Plugins Feb 21 13:09:31 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:31+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 13:09:31 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:31+01:00" level=debug msg="app state loaded" Feb 21 13:09:31 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin cd_controller to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 21 13:09:31 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=info msg="zeroconf server listening on port 36305" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="obtained new client token: AAAP6I61D9PRXdeGcsQlgADAAJVx+7ELQ8TOZ+EbvKRq6+Espaexjee7YWIf0B4PkU4dw/QICXzOeH8y6otJYd1C22nYmUnmLfq1/ai6xsKyQhS/Zw3uE8KPFR9HeUIxgeBCaOdjl88xUElWLa4GAWNe8e3g8PNM6rndIyp3Q69k5PgZ9SFlokjhLklFgPhAz0aP8eGOe5hWw8aOiN4ZSx0HuJOm2RfPX43KmgLzYdghziPrqmQykWOSuzDF" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="completed keyexchange" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=debug msg="completed challenge" Feb 21 13:09:32 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:32+01:00" level=info msg="authenticated AP" username="an*******60" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=info msg="authenticated Login5" username="an*******60" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="initializing zeroconf session" username="an*******60" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="dealer connection opened" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=trace msg="starting accesspoint recv loop" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=trace msg="received accesspoint ping" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=trace msg="starting dealer recv loop" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="received connection id: NjdiY2E1NGYtMzJm...NjczREU2M0EwRA==" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 21 13:09:33 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:33+01:00" level=trace msg="received accesspoint pong ack" Feb 21 13:09:34 volumiosalon volumio[1024]: ......................................................................................................++++ Feb 21 13:09:34 volumiosalon volumio[674]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 21 13:09:34 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 21 13:09:34 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:34 volumiosalon volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:09:34 volumiosalon volumio[674]: info: Starting MyVolumio Remote Streaming Endpoints Feb 21 13:09:34 volumiosalon volumio[674]: info: MyVolumio login type: Token Feb 21 13:09:34 volumiosalon volumio[674]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 21 13:09:34 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="handling transfer player command from f9f756d65365315de5f30fe446283c8701c2f439" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:4hYxLMED1pHLUamsBq2DTF" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=trace msg="fetched new page 0 with 237 items (list: 237)" uri="spotify:playlist:4hYxLMED1pHLUamsBq2DTF" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="shuffled context with seed 2006686916646282061 (len: 237, keep: 51)" uri="spotify:playlist:4hYxLMED1pHLUamsBq2DTF" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="loading track (paused: true, position: 60197ms)" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=trace msg="emitting websocket event: will_play" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 314" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="selected format OGG_VORBIS_320 (ada30a1b93032396881beae531d88c5e29eb1046)" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=debug msg="requested aes key for file ada30a1b93032396881beae531d88c5e29eb1046, gid: 3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:35 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:35+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:36 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:36+01:00" level=debug msg="fetched first chunk of 21, total size is 10750300 bytes" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:36 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:36+01:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:36 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:36+01:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:36 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:36+01:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:37 volumiosalon volumio[674]: info: Starting Streaming Service Transparent Proxy Feb 21 13:09:37 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 21 13:09:37 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 21 13:09:37 volumiosalon volumio[674]: info: Streaming services startup Feb 21 13:09:37 volumiosalon volumio[674]: info: Starting Streaming Daemon Feb 21 13:09:37 volumiosalon sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 13:09:37 volumiosalon sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:09:37 volumiosalon sudo[1252]: pam_unix(sudo:session): session closed for user root Feb 21 13:09:37 volumiosalon volumio[674]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="fetched chunk 7/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:37 volumiosalon volumio[674]: info: Initializing connection to go-librespot Websocket Feb 21 13:09:37 volumiosalon volumio[674]: error: Cannot start Volumio Streaming Daemon Feb 21 13:09:37 volumiosalon volumio[674]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 21 13:09:37 volumiosalon volumio[674]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="new websocket client" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="fetched chunk 4/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=trace msg="seek to 60197ms (diff: 68ms, samples: 2654687, bytes: 2441105)" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="created new output device" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=info msg="loaded track \"Sadeness - Pt. 1 / Radio Edit\" (paused: true, position: 60197ms, duration: 260400ms, prefetched: false)" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:37 volumiosalon volumio[674]: info: Connection to go-librespot Websocket established Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=trace msg="emitting websocket event: metadata" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=trace msg="emitting websocket event: active" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="sending successful reply for dealer request" Feb 21 13:09:37 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:37+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 21 13:09:38 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:38+01:00" level=debug msg="fetched chunk 6/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:38 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:38+01:00" level=debug msg="fetched chunk 5/20, size: 524288" uri="spotify:track:3n2dV8PXv1bs8KP31UhbVj" Feb 21 13:09:38 volumiosalon volumio[674]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3n2dV8PXv1bs8KP31UhbVj","name":"Sadeness - Pt. 1 / Radio Edit","artist_names":["Enigma"],"album_name":"The Platinum Collection","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029e6f00eecd69a079407402b0","position":60197,"duration":260400,"release_date":"year:2009 month:1 day:1","track_number":1,"disc_number":1}} Feb 21 13:09:38 volumiosalon volumio[674]: SPOTIFY: received: {"type":"active","data":null} Feb 21 13:09:38 volumiosalon volumio[674]: info: Aligning Spotify Volume to Volumio Volume Feb 21 13:09:38 volumiosalon volumio[674]: info: CoreCommandRouter::volumioGetState Feb 21 13:09:38 volumiosalon volumio[674]: info: CorePlayQueue::getTrack 0 Feb 21 13:09:38 volumiosalon volumio[674]: info: Setting Spotify Volume from Volumio: 92 Feb 21 13:09:38 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 13:09:38 volumiosalon go-librespot[1236]: time="2026-02-21T13:09:38+01:00" level=trace msg="emitting websocket event: paused" Feb 21 13:09:38 volumiosalon volumio[674]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:4hYxLMED1pHLUamsBq2DTF","uri":"spotify:track:3n2dV8PXv1bs8KP31UhbVj","play_origin":"playlist"}} Feb 21 13:09:38 volumiosalon volumio[674]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:09:38 volumiosalon volumio[674]: TypeError: Cannot read property 'service' of undefined Feb 21 13:09:38 volumiosalon volumio[674]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 21 13:09:38 volumiosalon volumio[674]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Feb 21 13:09:38 volumiosalon volumio[674]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 21 13:09:38 volumiosalon volumio[674]: at WebSocket.emit (events.js:315:20) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.emit (events.js:315:20) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 21 13:09:38 volumiosalon volumio[674]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 21 13:09:38 volumiosalon volumio[674]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 21 13:09:38 volumiosalon volumio[674]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 21 13:09:38 volumiosalon volumio[674]: at Socket.emit (events.js:315:20) Feb 21 13:09:38 volumiosalon volumio[674]: at addChunk (internal/streams/readable.js:309:12) Feb 21 13:09:38 volumiosalon volumio[674]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 21 13:09:38 volumiosalon volumio[674]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:09:39 volumiosalon sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-21 13:08 Feb 21 13:09:39 volumiosalon sudo[1282]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"