-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-05-11 20:35:35 CEST. -- May 11 20:34:32 volumiocaseta systemd-timedated[1100]: Changed local time to Sun May 11 20:34:32 2025 May 11 20:34:32 volumiocaseta sudo[1098]: pam_unix(sudo:session): session closed for user root May 11 20:34:32 volumiocaseta volumio-time-update[569]: volumio-time-update-util: System time updated successfully. May 11 20:34:32 volumiocaseta systemd[1]: Starting Daily apt download activities... May 11 20:34:32 volumiocaseta systemd[1]: Started Volumio Time Update Utility. May 11 20:34:32 volumiocaseta systemd[1]: Reached target Multi-User System. May 11 20:34:32 volumiocaseta systemd[1]: Reached target Graphical Interface. May 11 20:34:32 volumiocaseta systemd[1]: Starting Update UTMP about System Runlevel Changes... May 11 20:34:32 volumiocaseta systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 11 20:34:32 volumiocaseta systemd[1]: Started Update UTMP about System Runlevel Changes. May 11 20:34:32 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:32 volumiocaseta volumio[1069]: info: ----- Volumio3 ---- May 11 20:34:32 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:32 volumiocaseta volumio[1069]: info: ----- System startup ---- May 11 20:34:32 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:33 volumiocaseta systemd[1]: apt-daily.service: Succeeded. May 11 20:34:33 volumiocaseta systemd[1]: Started Daily apt download activities. May 11 20:34:33 volumiocaseta systemd[1]: Starting Daily apt upgrade and clean activities... May 11 20:34:33 volumiocaseta systemd[1]: systemd-fsckd.service: Succeeded. May 11 20:34:34 volumiocaseta volumio[1069]: info: MYVOLUMIO Environment detected May 11 20:34:34 volumiocaseta systemd[1]: apt-daily-upgrade.service: Succeeded. May 11 20:34:34 volumiocaseta systemd[1]: Started Daily apt upgrade and clean activities. May 11 20:34:34 volumiocaseta systemd[1]: Startup finished in 13.518s (kernel) + 33.374s (userspace) = 46.893s. May 11 20:34:34 volumiocaseta volumio[1069]: info: Plugin folders cleanup May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning into folder /volumio/app/plugins/ May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category audio_interface May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category miscellanea May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category music_service May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category plugins.json May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category system_controller May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category user_interface May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning into folder /data/plugins/ May 11 20:34:34 volumiocaseta volumio[1069]: info: Scanning category music_service May 11 20:34:34 volumiocaseta volumio[1069]: info: Plugin folders cleanup completed May 11 20:34:34 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:34 volumiocaseta volumio[1069]: info: ----- Core plugins startup ---- May 11 20:34:34 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:34 volumiocaseta volumio[1069]: info: Loading plugins from folder /volumio/app/plugins/ May 11 20:34:34 volumiocaseta volumio[1069]: info: Adding plugin upnp to MyMusic Plugins May 11 20:34:34 volumiocaseta volumio[1069]: info: Adding plugin airplay_emulation to MyMusic Plugins May 11 20:34:34 volumiocaseta volumio[1069]: info: Adding plugin upnp_browser to MyMusic Plugins May 11 20:34:34 volumiocaseta volumio[1069]: info: Loading plugins from folder /data/plugins/ May 11 20:34:34 volumiocaseta volumio[1069]: info: Loading plugin "system"... May 11 20:34:34 volumiocaseta volumio[1069]: info: Loading plugin "appearance"... May 11 20:34:35 volumiocaseta volumio[1069]: info: Loading plugin "network"... May 11 20:34:35 volumiocaseta volumio[1069]: info: Refreshing Cached IP Addresses May 11 20:34:35 volumiocaseta volumio[1069]: info: Loading plugin "services"... May 11 20:34:35 volumiocaseta sudo[1221]: volumio : unable to resolve host volumiocaseta May 11 20:34:35 volumiocaseta sudo[1221]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:35 volumiocaseta volumio[1069]: info: Loading plugin "alsa_controller"... May 11 20:34:35 volumiocaseta sudo[1219]: volumio : unable to resolve host volumiocaseta May 11 20:34:35 volumiocaseta sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:34:35 volumiocaseta sudo[1219]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:35 volumiocaseta sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:35 volumiocaseta sudo[1221]: pam_unix(sudo:session): session closed for user root May 11 20:34:35 volumiocaseta sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:34:35 volumiocaseta sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:35 volumiocaseta sudo[1228]: volumio : unable to resolve host volumiocaseta May 11 20:34:35 volumiocaseta sudo[1228]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:35 volumiocaseta sudo[1219]: pam_unix(sudo:session): session closed for user root May 11 20:34:35 volumiocaseta sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 11 20:34:35 volumiocaseta sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:36 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "wizard"... May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "networkfs"... May 11 20:34:36 volumiocaseta volumio[1069]: info: Starting Udev Watcher for removable devices May 11 20:34:36 volumiocaseta volumio[1069]: info: Ignoring mount for partition: boot May 11 20:34:36 volumiocaseta volumio[1069]: info: Ignoring mount for partition: volumio May 11 20:34:36 volumiocaseta volumio[1069]: info: Ignoring mount for partition: volumio_data May 11 20:34:36 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "volumio_command_line_client"... May 11 20:34:36 volumiocaseta sudo[1247]: volumio : unable to resolve host volumiocaseta May 11 20:34:36 volumiocaseta sudo[1247]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:36 volumiocaseta sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ppmartin,password=Kikova09,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.90/Musica /mnt/NAS/TrueNAS May 11 20:34:36 volumiocaseta sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "upnp"... May 11 20:34:36 volumiocaseta volumio[1069]: info: [1746988476188] Starting Upmpd Daemon May 11 20:34:36 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "my_music"... May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "mpd"... May 11 20:34:36 volumiocaseta kernel: Key type cifs.spnego registered May 11 20:34:36 volumiocaseta kernel: Key type cifs.idmap registered May 11 20:34:36 volumiocaseta 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 11 20:34:36 volumiocaseta kernel: CIFS: Attempting to mount //192.168.1.90/Musica May 11 20:34:36 volumiocaseta sudo[1247]: pam_unix(sudo:session): session closed for user root May 11 20:34:36 volumiocaseta volumio[1069]: info: Loading plugin "upnp_browser"... May 11 20:34:37 volumiocaseta sh[545]: timed out May 11 20:34:37 volumiocaseta dhcpcd[670]: timed out May 11 20:34:37 volumiocaseta sh[545]: dhcpcd exited May 11 20:34:37 volumiocaseta dhcpcd[670]: dhcpcd exited May 11 20:34:37 volumiocaseta sh[545]: ifup: failed to bring up eth0 May 11 20:34:37 volumiocaseta systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 11 20:34:37 volumiocaseta systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "alarm-clock"... May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "airplay_emulation"... May 11 20:34:37 volumiocaseta volumio[1069]: info: Starting Shairport Sync May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "last_100"... May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "webradio"... May 11 20:34:37 volumiocaseta systemd[1]: systemd-hostnamed.service: Succeeded. May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "i2s_dacs"... May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "volumiodiscovery"... May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** For more information see May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:34:37 volumiocaseta volumio[1069]: *** WARNING *** For more information see May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** For more information see May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 20:34:37 volumiocaseta node[1069]: *** WARNING *** For more information see May 11 20:34:37 volumiocaseta volumio[1069]: info: Applying required configuration parameters for plugin volumiodiscovery May 11 20:34:37 volumiocaseta volumio[1069]: info: Discovery: Started advertising with name: Volumio_Caseta May 11 20:34:37 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 20:34:37 volumiocaseta volumio[1069]: info: Loading plugin "spop"... May 11 20:34:38 volumiocaseta volumio-remote-updater[562]: [2025-05-11 20:34:38] [connect] Successful connection May 11 20:34:39 volumiocaseta volumio[1069]: info: Loading plugin "outputs"... May 11 20:34:39 volumiocaseta volumio[1069]: info: Loading plugin "albumart"... May 11 20:34:39 volumiocaseta volumio[1069]: info: Plugin example_plugin is not enabled May 11 20:34:39 volumiocaseta volumio[1069]: info: Loading plugin "inputs"... May 11 20:34:39 volumiocaseta volumio[1069]: info: Loading plugin "updater_comm"... May 11 20:34:40 volumiocaseta volumio[1069]: info: Plugin mpdemulation is not enabled May 11 20:34:40 volumiocaseta volumio[1069]: info: Loading plugin "rest_api"... May 11 20:34:40 volumiocaseta volumio[1069]: info: Loading plugin "websocket"... May 11 20:34:40 volumiocaseta volumio[1069]: info: Starting Socket.io Server version 2.3.0 May 11 20:34:40 volumiocaseta volumio[1069]: info: Loading i18n strings for locale es May 11 20:34:40 volumiocaseta volumio[1069]: Updating browse sources language May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:34:40 volumiocaseta volumio[1069]: Forking 3 albumart workers May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::initPlayerControls May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:34:40 volumiocaseta volumio[1069]: Express server listening on port 3000 May 11 20:34:40 volumiocaseta volumio[1069]: [Metrics] WebUI: 9s 31.65ms May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreStateMachine::resetVolumioState May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreStateMachine::getcurrentVolume May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 11 20:34:40 volumiocaseta volumio[1069]: info: Volumio Network Manager: Network status updated: 2 May 11 20:34:40 volumiocaseta volumio-remote-updater[562]: [2025-05-11 20:34:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746988478 101 May 11 20:34:40 volumiocaseta volumio[1069]: 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: 1 May 11 20:34:40 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to 192.168.1.32:3000 from 192.168.1.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:40 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:40 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:41 volumiocaseta volumio[1069]: info: Reloading queue from file May 11 20:34:41 volumiocaseta volumio[1069]: info: VolumeController:: Volume=100 Mute =false May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::pushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioPushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::updateTrackBlock May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrackBlock May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 11 20:34:41 volumiocaseta volumio[1069]: info: Setting Device type: Raspberry PI May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::setRepeat null single undefined May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::pushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioPushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::setRandom null May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::pushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioPushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 11 20:34:41 volumiocaseta volumio[1069]: info: Completed loading Core Plugins May 11 20:34:41 volumiocaseta volumio[1069]: info: Preparing to generate the ALSA configuration file May 11 20:34:41 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:34:41 volumiocaseta volumio[1069]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 11 20:34:41 volumiocaseta volumio[1069]: info: Reading ALSA contributions from plugins. May 11 20:34:41 volumiocaseta volumio[1069]: info: Discovery: adding a5b56e26-add7-4a56-bfad-f9e9fe748567 May 11 20:34:41 volumiocaseta volumio[1069]: info: Discovery: Found device Volumio_Caseta May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: VolumeController:: Volume=100 Mute =false May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreStateMachine::pushState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioPushState May 11 20:34:41 volumiocaseta volumio[1069]: info: Discovery: adding 7336e032-8625-4130-8665-70fc7c7d56c7 May 11 20:34:41 volumiocaseta volumio[1069]: info: Discovery: Found device Volumio_Despacho May 11 20:34:41 volumiocaseta volumio[1069]: Starting albumart workers May 11 20:34:41 volumiocaseta volumio[1069]: Starting albumart workers May 11 20:34:41 volumiocaseta volumio[1069]: Starting albumart workers May 11 20:34:41 volumiocaseta volumio[1069]: info: Discovery: Connecting to remote: 192.168.1.4 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:41 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:41 volumiocaseta volumio[1069]: info: Asound.conf file unchanged, so no further update is needed May 11 20:34:41 volumiocaseta volumio[1069]: info: Output device has changed, restarting MPD May 11 20:34:41 volumiocaseta volumio[1069]: info: Output device has changed, restarting Shairport Sync May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:41 volumiocaseta sudo[1330]: volumio : unable to resolve host volumiocaseta May 11 20:34:41 volumiocaseta sudo[1330]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:41 volumiocaseta sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 20:34:41 volumiocaseta sudo[1332]: volumio : unable to resolve host volumiocaseta May 11 20:34:41 volumiocaseta sudo[1332]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:41 volumiocaseta sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 20:34:41 volumiocaseta sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:41 volumiocaseta sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:41 volumiocaseta sudo[1330]: pam_unix(sudo:session): session closed for user root May 11 20:34:41 volumiocaseta volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:34:41 volumiocaseta volumio[1069]: info: ___________ START PLUGINS ___________ May 11 20:34:41 volumiocaseta volumio[1069]: info: ControllerMpd::onStart: Initializing MPD May 11 20:34:41 volumiocaseta volumio[1069]: info: Creating MPD Configuration file May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:34:41 volumiocaseta volumio[1069]: info: [1746988481679] CoreMusicLibrary::Adding element Media Servers May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:34:41 volumiocaseta systemd[1]: Stopping Music Player Daemon... May 11 20:34:41 volumiocaseta sudo[1339]: volumio : unable to resolve host volumiocaseta May 11 20:34:41 volumiocaseta sudo[1339]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:41 volumiocaseta sudo[1341]: volumio : unable to resolve host volumiocaseta May 11 20:34:41 volumiocaseta sudo[1341]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:41 volumiocaseta sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 20:34:41 volumiocaseta sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:41 volumiocaseta sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 20:34:41 volumiocaseta sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:41 volumiocaseta sudo[1339]: pam_unix(sudo:session): session closed for user root May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:41 volumiocaseta systemd[1]: mpd.service: Succeeded. May 11 20:34:41 volumiocaseta systemd[1]: Stopped Music Player Daemon. May 11 20:34:41 volumiocaseta systemd[1]: Starting Music Player Daemon... May 11 20:34:41 volumiocaseta volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:41 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:34:42 volumiocaseta volumio[1069]: info: [1746988482073] CoreMusicLibrary::Adding element Last_100 May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:34:42 volumiocaseta volumio[1069]: info: [1746988482093] CoreMusicLibrary::Adding element Webradio May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:34:42 volumiocaseta volumio[1069]: info: Initializing BBC Radios May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:42 volumiocaseta sudo[1345]: root : unable to resolve host volumiocaseta May 11 20:34:42 volumiocaseta sudo[1345]: sudo: unable to resolve host volumiocaseta: System error May 11 20:34:42 volumiocaseta sudo[1345]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:42 volumiocaseta sudo[1345]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 20:34:42 volumiocaseta sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:42 volumiocaseta volumio[1069]: info: Creating Spotify config file May 11 20:34:42 volumiocaseta sudo[1345]: pam_unix(sudo:session): session closed for user root May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: Volumio Calling Home May 11 20:34:42 volumiocaseta volumio[1069]: info: MPD Permissions set May 11 20:34:42 volumiocaseta volumio[1069]: info: MPD Permissions set May 11 20:34:42 volumiocaseta volumio[1069]: info: Spotify config file written May 11 20:34:42 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta sudo[1393]: volumio : unable to resolve host volumiocaseta May 11 20:34:42 volumiocaseta sudo[1393]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:42 volumiocaseta sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 11 20:34:42 volumiocaseta sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:34:42 volumiocaseta volumio[1069]: info: No need to fix Spotify hosts May 11 20:34:42 volumiocaseta volumio[1069]: info: Discovery: Connected to remote: 192.168.1.4 May 11 20:34:42 volumiocaseta systemd[1]: Started go-librespot Daemon. May 11 20:34:42 volumiocaseta go-librespot[1400]: go-librespot daemon starting... May 11 20:34:42 volumiocaseta sudo[1393]: pam_unix(sudo:session): session closed for user root May 11 20:34:42 volumiocaseta volumio[1069]: info: Volumio called home May 11 20:34:42 volumiocaseta volumio[1069]: info: Starting Shairport Sync May 11 20:34:42 volumiocaseta volumio[1069]: info: Starting Shairport Sync May 11 20:34:42 volumiocaseta volumio[1069]: info: Starting Shairport Sync May 11 20:34:42 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:42 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:42 volumiocaseta sudo[1408]: volumio : unable to resolve host volumiocaseta May 11 20:34:42 volumiocaseta sudo[1408]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:42 volumiocaseta sudo[1410]: volumio : unable to resolve host volumiocaseta May 11 20:34:42 volumiocaseta sudo[1410]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:42 volumiocaseta sudo[1406]: volumio : unable to resolve host volumiocaseta May 11 20:34:42 volumiocaseta sudo[1406]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:42 volumiocaseta sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:34:42 volumiocaseta sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:42 volumiocaseta sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:34:42 volumiocaseta sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:42 volumiocaseta sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 20:34:42 volumiocaseta sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:43 volumiocaseta systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 20:34:43 volumiocaseta systemd[1]: shairport-sync.service: Succeeded. May 11 20:34:43 volumiocaseta systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 20:34:43 volumiocaseta systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 20:34:43 volumiocaseta sudo[1410]: pam_unix(sudo:session): session closed for user root May 11 20:34:43 volumiocaseta sudo[1408]: pam_unix(sudo:session): session closed for user root May 11 20:34:43 volumiocaseta sudo[1406]: pam_unix(sudo:session): session closed for user root May 11 20:34:43 volumiocaseta volumio[1069]: info: Shairport-Sync Started May 11 20:34:43 volumiocaseta volumio[1069]: Error adding Membership: Error: addMembership EINVAL May 11 20:34:43 volumiocaseta volumio[1069]: info: Shairport-Sync Started May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02:00" level=info msg="running go-librespot 0.2.0" May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02:00" level=debug msg="app state loaded" May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 11 20:34:43 volumiocaseta volumio[1069]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 11 20:34:43 volumiocaseta volumio[1069]: SPOTIFY: BQBJmhVotwtZN_MG-VLWEs9m7_ztp-45V3XD8Fwvf2P5a58zf42pEJhnV1EKslqYfG4AE_TnvknQ9MZBOMc5KtxIGSc47Jb5Gymfbx-nZ2lx3S1T2RxbNGD1KBT-955R2hJCoiQgiB8hBW0Q8Eg8XCMrKLAaKnMl4mmr9woJnqpdMzwf6KmnRnf1kC63GFRkQTAawZVH9_xf4FCaVQgWZq-ARfO4mCSruLxFc2Av26JqZZXZGn-76KD_OLaF1ONn40FHgsX7knXv3Yg4XG66lKpZbLNFC0TZdzfNBawyCcUWs2U May 11 20:34:43 volumiocaseta volumio[1069]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 11 20:34:43 volumiocaseta volumio[1069]: info: New Spotify access token = BQBJmhVotwtZN_MG-VLWEs9m7_ztp-45V3XD8Fwvf2P5a58zf42pEJhnV1EKslqYfG4AE_TnvknQ9MZBOMc5KtxIGSc47Jb5Gymfbx-nZ2lx3S1T2RxbNGD1KBT-955R2hJCoiQgiB8hBW0Q8Eg8XCMrKLAaKnMl4mmr9woJnqpdMzwf6KmnRnf1kC63GFRkQTAawZVH9_xf4FCaVQgWZq-ARfO4mCSruLxFc2Av26JqZZXZGn-76KD_OLaF1ONn40FHgsX7knXv3Yg4XG66lKpZbLNFC0TZdzfNBawyCcUWs2U May 11 20:34:43 volumiocaseta volumio[1069]: info: Spotify credentials grant success - running version from March 24, 2019 May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02: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]" May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02: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]" May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02: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]" May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02:00" level=info msg="zeroconf server listening on port 42697" May 11 20:34:43 volumiocaseta volumio[1069]: SPOTIFY: User informations: {"country":"ES","display_name":"Pedro Pablo Martin","email":"pedro.pablo.martin@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31lec3ftlnkgukix3w5s53e3trzm"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31lec3ftlnkgukix3w5s53e3trzm","id":"31lec3ftlnkgukix3w5s53e3trzm","images":[],"product":"free","type":"user","uri":"spotify:user:31lec3ftlnkgukix3w5s53e3trzm"} May 11 20:34:43 volumiocaseta volumio[1069]: info: Spotify Successfully logged in May 11 20:34:43 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 11 20:34:43 volumiocaseta volumio[1069]: info: [1746988483789] CoreMusicLibrary::Adding element Spotify May 11 20:34:43 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:34:43 volumiocaseta volumio[1069]: Cannot find translation for source Spotify May 11 20:34:43 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:43+02:00" level=debug msg="obtained new client token: AADVDkenO3pW7mRYt9gYsuG1bYOgfAjKRun9bRWAVuaMMIV9vSRI3AQtfEFFtEZ7H+4jWgIcSdxzwmH4YvwYInnT+heErlV4mQ97iKsnDejRqcpAvGhQVlZwm0eIEfCeUFzsisBL9N5KOACJXJcZUipbB+Gd5tdeuaRyv0qEqlL/MdWjLTNElFNDS9Q4kMd9XNGALJM//e6n7AWsGXowjJO+DP1enNpdV4xwiMS8aRb8mzeL47a3Zt4=" May 11 20:34:44 volumiocaseta mpd[1372]: May 11 20:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="completed keyexchange" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="completed challenge" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=info msg="authenticated AP as 31lec3ftlnkgukix3w5s53e3trzm" May 11 20:34:44 volumiocaseta systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 11 20:34:44 volumiocaseta systemd[1]: mpd.service: Failed with result 'protocol'. May 11 20:34:44 volumiocaseta systemd[1]: Failed to start Music Player Daemon. May 11 20:34:44 volumiocaseta sudo[1332]: pam_unix(sudo:session): session closed for user root May 11 20:34:44 volumiocaseta volumio[1069]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 11 20:34:44 volumiocaseta volumio[1069]: sudo: unable to resolve host volumiocaseta: System error May 11 20:34:44 volumiocaseta volumio[1069]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 11 20:34:44 volumiocaseta volumio[1069]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 11 20:34:44 volumiocaseta sudo[1341]: pam_unix(sudo:session): session closed for user root May 11 20:34:44 volumiocaseta volumio[1069]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 11 20:34:44 volumiocaseta volumio[1069]: sudo: unable to resolve host volumiocaseta: System error May 11 20:34:44 volumiocaseta volumio[1069]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 11 20:34:44 volumiocaseta volumio[1069]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 11 20:34:44 volumiocaseta volumio[1069]: info: Completed starting Core Plugins May 11 20:34:44 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:44 volumiocaseta volumio[1069]: info: ----- MyVolumio plugins startup ---- May 11 20:34:44 volumiocaseta volumio[1069]: info: ------------------------------------------- May 11 20:34:44 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Fetching plans data.... May 11 20:34:44 volumiocaseta systemd[1]: Starting Music Player Daemon... May 11 20:34:44 volumiocaseta systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 11 20:34:44 volumiocaseta systemd[1]: mpd.service: Succeeded. May 11 20:34:44 volumiocaseta systemd[1]: Stopped Music Player Daemon. May 11 20:34:44 volumiocaseta systemd[1]: Starting Music Player Daemon... May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=info msg="authenticated Login5 as 31lec3ftlnkgukix3w5s53e3trzm" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="initializing zeroconf session, username: 31lec3ftlnkgukix3w5s53e3trzm" May 11 20:34:44 volumiocaseta sudo[1427]: root : unable to resolve host volumiocaseta May 11 20:34:44 volumiocaseta sudo[1427]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:44 volumiocaseta sudo[1427]: sudo: unable to resolve host volumiocaseta: System error May 11 20:34:44 volumiocaseta sudo[1427]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 20:34:44 volumiocaseta sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:44 volumiocaseta sudo[1427]: pam_unix(sudo:session): session closed for user root May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="dealer connection opened" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=trace msg="starting accesspoint recv loop" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=trace msg="starting dealer recv loop" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=trace msg="received accesspoint ping" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="received connection id: MTBkYjY3YzUtMGQ0MC00MTE5LWE0N2UtZjliMjBhNjZmOGQxK2RlYWxlcit0Y3A6Ly8wYWNhNDA0My5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArOUJGMTBGMzVDN0NCMDA4MTAxQTFBNUQwQTI1RkUxNDYyQ0M4REU5RkI3NUY4RDgyQUYzODNEMjNDRTdBQzdENA==" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=trace msg="received accesspoint pong ack" May 11 20:34:44 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:44+02:00" level=debug msg="put connect state because NEW_DEVICE" May 11 20:34:45 volumiocaseta mpd[1429]: May 11 20:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 20:34:45 volumiocaseta volumio[1069]: info: go-librespot daemon successfully initialized May 11 20:34:46 volumiocaseta sudo[1445]: volumio : unable to resolve host volumiocaseta May 11 20:34:46 volumiocaseta sudo[1445]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:46 volumiocaseta sudo[1447]: volumio : unable to resolve host volumiocaseta May 11 20:34:46 volumiocaseta sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:34:46 volumiocaseta sudo[1447]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:46 volumiocaseta sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:46 volumiocaseta sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:34:46 volumiocaseta sudo[1445]: pam_unix(sudo:session): session closed for user root May 11 20:34:46 volumiocaseta sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:46 volumiocaseta sudo[1447]: pam_unix(sudo:session): session closed for user root May 11 20:34:46 volumiocaseta sudo[1449]: volumio : unable to resolve host volumiocaseta May 11 20:34:46 volumiocaseta sudo[1449]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:46 volumiocaseta sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 11 20:34:46 volumiocaseta sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:46 volumiocaseta systemd[1]: Started UPnP Renderer front-end to MPD. May 11 20:34:46 volumiocaseta sudo[1449]: pam_unix(sudo:session): session closed for user root May 11 20:34:46 volumiocaseta volumio[1069]: info: Upmpdcli Daemon Started May 11 20:34:46 volumiocaseta systemd[1]: Started Music Player Daemon. May 11 20:34:46 volumiocaseta mpd_monitor.sh[572]: MPD restarted due to no mpc output. May 11 20:34:46 volumiocaseta volumio[1069]: error: MPD error: The expression evaluated to a falsy value: May 11 20:34:46 volumiocaseta volumio[1069]: assert.ok(self.idling) May 11 20:34:46 volumiocaseta volumio[1069]: error: The expression evaluated to a falsy value: May 11 20:34:46 volumiocaseta volumio[1069]: assert.ok(self.idling) May 11 20:34:46 volumiocaseta volumio[1069]: error: updateQueue error: null May 11 20:34:46 volumiocaseta volumio[1453]: Generating RSA private key, 4096 bit long modulus (2 primes) May 11 20:34:47 volumiocaseta volumio[1453]: ......++++ May 11 20:34:47 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:47 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:48 volumiocaseta sudo[1228]: pam_unix(sudo:session): session closed for user root May 11 20:34:48 volumiocaseta volumio[1069]: info: Initializing connection to go-librespot Websocket May 11 20:34:48 volumiocaseta go-librespot[1400]: time="2025-05-11T20:34:48+02:00" level=debug msg="new websocket client" May 11 20:34:48 volumiocaseta volumio[1069]: info: Connection to go-librespot Websocket established May 11 20:34:50 volumiocaseta volumio[1453]: ................++++ May 11 20:34:50 volumiocaseta volumio[1453]: e is 65537 (0x010001) May 11 20:34:50 volumiocaseta volumio[1453]: writing RSA key May 11 20:34:51 volumiocaseta volumio[1069]: info: Getting Spotify volume May 11 20:34:51 volumiocaseta volumio[1069]: info: Spotify volume: 100 May 11 20:34:51 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 11 20:34:51 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:51 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:52 volumiocaseta volumio[1069]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 11 20:34:52 volumiocaseta volumio[1069]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin bluetooth to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin multiroom to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin metavolumio to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin cd_controller to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin smart_inputs to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: Adding plugin tidalconnect to MyMusic Plugins May 11 20:34:52 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 11 20:34:54 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 11 20:34:54 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 11 20:34:54 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:54 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:34:54 volumiocaseta volumio[1069]: info: Starting MyVolumio Remote Streaming Endpoints May 11 20:34:54 volumiocaseta volumio[1069]: info: MyVolumio login type: Token May 11 20:34:54 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 11 20:34:54 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 11 20:34:55 volumiocaseta volumio[1069]: info: Starting Streaming Service Transparent Proxy May 11 20:34:55 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 11 20:34:55 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 11 20:34:55 volumiocaseta volumio[1069]: info: Streaming services startup May 11 20:34:55 volumiocaseta volumio[1069]: info: Starting Streaming Daemon May 11 20:34:55 volumiocaseta sudo[1509]: volumio : unable to resolve host volumiocaseta May 11 20:34:55 volumiocaseta sudo[1509]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:34:55 volumiocaseta sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 20:34:55 volumiocaseta sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:34:55 volumiocaseta volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 11 20:34:55 volumiocaseta sudo[1509]: pam_unix(sudo:session): session closed for user root May 11 20:34:55 volumiocaseta volumio[1069]: error: Cannot start Volumio Streaming Daemon May 11 20:34:55 volumiocaseta volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 20:34:55 volumiocaseta volumio[1069]: sudo: unable to resolve host volumiocaseta: System error May 11 20:34:55 volumiocaseta volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 20:34:56 volumiocaseta volumio[1069]: error: MyVolumio Custom Token format not valid, refreshing it May 11 20:34:57 volumiocaseta volumio[1069]: STREAMING PROXY: Starting server on port 3245 May 11 20:34:57 volumiocaseta volumio[1069]: Node JS runtime: 14 May 11 20:34:58 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:34:58 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:34:58 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:34:58 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:34:58 volumiocaseta volumio[1069]: info: MyVolumio login type: Token May 11 20:35:02 volumiocaseta systemd[1]: systemd-timedated.service: Succeeded. May 11 20:35:02 volumiocaseta volumio[1069]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 11 20:35:03 volumiocaseta sudo[1536]: volumio : unable to resolve host volumiocaseta May 11 20:35:03 volumiocaseta sudo[1536]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:03 volumiocaseta sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:35:03 volumiocaseta sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:03 volumiocaseta sudo[1536]: pam_unix(sudo:session): session closed for user root May 11 20:35:03 volumiocaseta sudo[1538]: volumio : unable to resolve host volumiocaseta May 11 20:35:03 volumiocaseta sudo[1538]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:03 volumiocaseta sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:35:03 volumiocaseta sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:03 volumiocaseta sudo[1538]: pam_unix(sudo:session): session closed for user root May 11 20:35:05 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 11 20:35:05 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 11 20:35:06 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 20:35:06 volumiocaseta volumio[1069]: info: MyVolumio token set successfully May 11 20:35:06 volumiocaseta volumio[1069]: info: MYVOLUMIO: Adding device May 11 20:35:06 volumiocaseta volumio[1069]: info: MYVOLUMIO: Evaluating Server May 11 20:35:07 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 20:35:08 volumiocaseta volumio[1069]: info: MyVolumio status changed May 11 20:35:08 volumiocaseta volumio[1069]: info: Streaming services startup May 11 20:35:08 volumiocaseta volumio[1069]: info: Starting Streaming Daemon May 11 20:35:08 volumiocaseta volumio[1069]: info: Removing browser output: myVolumio user plan is not superstar May 11 20:35:08 volumiocaseta volumio[1069]: info: Removing audio output: May 11 20:35:08 volumiocaseta volumio[1069]: info: Stoppping Tunnel 1 May 11 20:35:08 volumiocaseta sudo[1614]: volumio : unable to resolve host volumiocaseta May 11 20:35:08 volumiocaseta sudo[1614]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:08 volumiocaseta sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 20:35:08 volumiocaseta sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:08 volumiocaseta sudo[1614]: pam_unix(sudo:session): session closed for user root May 11 20:35:08 volumiocaseta sudo[1616]: volumio : unable to resolve host volumiocaseta May 11 20:35:08 volumiocaseta sudo[1616]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:08 volumiocaseta sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 11 20:35:08 volumiocaseta sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:08 volumiocaseta sudo[1616]: pam_unix(sudo:session): session closed for user root May 11 20:35:11 volumiocaseta volumio[1069]: Cannot compose Albumart path May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:12 volumiocaseta volumio[1069]: info: Setting Geolocation for MyVolumio to eu10 May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:12 volumiocaseta volumio[1069]: info: Remote SSH Stopped May 11 20:35:12 volumiocaseta volumio[1069]: error: Cannot start Volumio Streaming Daemon May 11 20:35:12 volumiocaseta volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 20:35:12 volumiocaseta volumio[1069]: sudo: unable to resolve host volumiocaseta: System error May 11 20:35:12 volumiocaseta volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 20:35:12 volumiocaseta sudo[1620]: volumio : unable to resolve host volumiocaseta May 11 20:35:12 volumiocaseta sudo[1620]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:12 volumiocaseta sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:35:12 volumiocaseta sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:12 volumiocaseta sudo[1620]: pam_unix(sudo:session): session closed for user root May 11 20:35:12 volumiocaseta sudo[1622]: volumio : unable to resolve host volumiocaseta May 11 20:35:12 volumiocaseta sudo[1622]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:12 volumiocaseta sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:35:12 volumiocaseta sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:35:12 volumiocaseta sudo[1622]: pam_unix(sudo:session): session closed for user root May 11 20:35:12 volumiocaseta volumio[1069]: 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 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:12 volumiocaseta volumio[1069]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetVisibleSources May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetQueue May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreStateMachine::getQueue May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getQueue May 11 20:35:12 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 20:35:12 volumiocaseta volumio[1069]: info: Received Get System Info May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 20:35:12 volumiocaseta volumio[1069]: info: Discovery: Getting this device information May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:12 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 20:35:12 volumiocaseta volumio-remote-updater[562]: No test mode May 11 20:35:12 volumiocaseta volumio-remote-updater[562]: No alpha test mode May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 11 20:35:12 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 20:35:12 volumiocaseta volumio[1069]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 11 20:35:13 volumiocaseta volumio[1069]: info: Updating MyVolumio device info May 11 20:35:13 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:13 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:13 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:13 volumiocaseta volumio[1069]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Texas/Mothers%20Heaven/2eefe39c-865c-465c-b834-8a50a591829a.png' May 11 20:35:13 volumiocaseta volumio[1069]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

    • QOBUZ Connect Ready

 

","title":"Update v3.807","updateavailable":true} May 11 20:35:13 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 11 20:35:13 volumiocaseta ntpd[826]: Soliciting pool server 195.95.153.43 May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 20:35:14 volumiocaseta volumio[1069]: info: Received Get System Info May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 20:35:14 volumiocaseta volumio[1069]: info: Discovery: Getting this device information May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:14 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetBrowseSources May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:35:14 volumiocaseta volumio[1069]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 11 20:35:14 volumiocaseta go-librespot[1400]: time="2025-05-11T20:35:14+02:00" level=trace msg="sent dealer ping" May 11 20:35:14 volumiocaseta go-librespot[1400]: time="2025-05-11T20:35:14+02:00" level=trace msg="received dealer pong" May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 20:35:14 volumiocaseta volumio[1069]: info: Received Get System Info May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 20:35:14 volumiocaseta volumio[1069]: info: Discovery: Getting this device information May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:14 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 20:35:14 volumiocaseta ntpd[826]: Soliciting pool server 178.215.228.24 May 11 20:35:14 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 11 20:35:15 volumiocaseta ntpd[826]: Soliciting pool server 84.77.195.114 May 11 20:35:15 volumiocaseta ntpd[826]: Soliciting pool server 213.165.71.32 May 11 20:35:15 volumiocaseta volumio[1069]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 11 20:35:15 volumiocaseta volumio[1069]: info: Completed starting MyVolumio Plugin May 11 20:35:15 volumiocaseta volumio[1069]: [Metrics] CommandRouter: 43s 137.78ms May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumiosetStartupVolume May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 20:35:15 volumiocaseta volumio[1069]: info: VolumeController:: Setting startup Volume 100 May 11 20:35:15 volumiocaseta ntpd[826]: Soliciting pool server 195.95.153.59 May 11 20:35:15 volumiocaseta volumio[1069]: info: VolumeController::SetAlsaVolume100 May 11 20:35:15 volumiocaseta ntpd[826]: Soliciting pool server 195.20.235.143 May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::Close All Modals sent May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::Close All Modals sent May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreStateMachine::pushState May 11 20:35:15 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:15 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioPushState May 11 20:35:15 volumiocaseta kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 11 20:35:15 volumiocaseta volumio[1069]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 11 20:35:16 volumiocaseta volumio[1069]: info: MYVOLUMIO: Adding device May 11 20:35:16 volumiocaseta volumio[1069]: info: MYVOLUMIO: Evaluating Server May 11 20:35:16 volumiocaseta ntpd[826]: Soliciting pool server 162.159.200.123 May 11 20:35:16 volumiocaseta ntpd[826]: Soliciting pool server 212.227.232.46 May 11 20:35:16 volumiocaseta ntpd[826]: Soliciting pool server 90.68.206.60 May 11 20:35:16 volumiocaseta ntpd[826]: Soliciting pool server 89.140.186.3 May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 11 20:35:17 volumiocaseta volumio[1069]: info: Setting Geolocation for MyVolumio to eu7 May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:17 volumiocaseta volumio[1069]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 11 20:35:17 volumiocaseta ntpd[826]: Soliciting pool server 83.97.79.109 May 11 20:35:17 volumiocaseta ntpd[826]: Soliciting pool server 178.255.228.77 May 11 20:35:17 volumiocaseta ntpd[826]: Soliciting pool server 65.20.99.74 May 11 20:35:17 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:17 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:17 volumiocaseta ntpd[826]: Soliciting pool server 217.160.163.33 May 11 20:35:17 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:35:17 volumiocaseta volumio[1069]: info: Listing playlists May 11 20:35:18 volumiocaseta volumio[1069]: info: Updating MyVolumio device info May 11 20:35:18 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 20:35:18 volumiocaseta ntpd[826]: Soliciting pool server 5.250.184.159 May 11 20:35:18 volumiocaseta ntpd[826]: Soliciting pool server 162.159.200.1 May 11 20:35:18 volumiocaseta ntpd[826]: Soliciting pool server 94.143.139.219 May 11 20:35:18 volumiocaseta volumio[1069]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 11 20:35:19 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:19 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:22 volumiocaseta ntpd[826]: receive: Unexpected origin timestamp 0xebcb6c68.c7923190 does not match aorg 0000000000.00000000 from server@195.95.153.59 xmt 0xebcb6c6a.2252a7a9 May 11 20:35:22 volumiocaseta ntpd[826]: receive: Unexpected origin timestamp 0xebcb6c68.c7891aeb does not match aorg 0000000000.00000000 from server@83.97.79.109 xmt 0xebcb6c6a.2228865f May 11 20:35:22 volumiocaseta ntpd[826]: receive: Unexpected origin timestamp 0xebcb6c68.c77e7bf2 does not match aorg 0000000000.00000000 from server@178.255.228.77 xmt 0xebcb6c6a.22ab2070 May 11 20:35:22 volumiocaseta ntpd[826]: receive: Unexpected origin timestamp 0xebcb6c68.c78f7e00 does not match aorg 0000000000.00000000 from server@212.227.232.46 xmt 0xebcb6c6a.22d3abb2 May 11 20:35:22 volumiocaseta ntpd[826]: receive: Unexpected origin timestamp 0xebcb6c68.c794b401 does not match aorg 0000000000.00000000 from server@213.165.71.32 xmt 0xebcb6c6a.2280c59b May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 11 20:35:23 volumiocaseta volumio[1069]: info: Received Get System Version May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:35:23 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 11 20:35:24 volumiocaseta volumio[1069]: info: BOOT COMPLETED May 11 20:35:26 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:26 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:28 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 20:35:29 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 20:35:29 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:29 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:30 volumiocaseta volumio[1069]: error: Cannot download Available plugins list: Error: self signed certificate May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 20:35:31 volumiocaseta volumio[1069]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 11 20:35:31 volumiocaseta volumio[1069]: info: Received Get System Version May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 20:35:31 volumiocaseta volumio[1069]: info: Received Get System Info May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 20:35:31 volumiocaseta volumio[1069]: info: Discovery: Getting this device information May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::volumioGetState May 11 20:35:31 volumiocaseta volumio[1069]: info: CorePlayQueue::getTrack 0 May 11 20:35:31 volumiocaseta volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 20:35:34 volumiocaseta volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:35:34 volumiocaseta volumio[1069]: Error: self signed certificate May 11 20:35:34 volumiocaseta volumio[1069]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 11 20:35:34 volumiocaseta volumio[1069]: at TLSSocket.emit (events.js:315:20) May 11 20:35:34 volumiocaseta volumio[1069]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 11 20:35:34 volumiocaseta volumio[1069]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 11 20:35:34 volumiocaseta volumio[1069]: code: 'DEPTH_ZERO_SELF_SIGNED_CERT' May 11 20:35:34 volumiocaseta volumio[1069]: } May 11 20:35:34 volumiocaseta volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:35:35 volumiocaseta sudo[1705]: volumio : unable to resolve host volumiocaseta May 11 20:35:35 volumiocaseta sudo[1705]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 20:35:35 volumiocaseta sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 20:34 May 11 20:35:35 volumiocaseta sudo[1705]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"