-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2026-04-10 18:28:18 CEST. -- Apr 10 18:28:01 volumiocybou systemd-timedated[994]: Changed local time to Fri Apr 10 18:28:01 2026 Apr 10 18:28:01 volumiocybou sudo[992]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:01 volumiocybou volumio-time-update[599]: volumio-time-update-util: System time updated successfully. Apr 10 18:28:01 volumiocybou systemd[1]: Started Volumio Time Update Utility. Apr 10 18:28:01 volumiocybou systemd[1]: Reached target Multi-User System. Apr 10 18:28:01 volumiocybou systemd[1]: Reached target Graphical Interface. Apr 10 18:28:01 volumiocybou systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 10 18:28:01 volumiocybou systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 10 18:28:01 volumiocybou systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 10 18:28:01 volumiocybou systemd[1]: Startup finished in 16.298s (kernel) + 18.100s (userspace) = 34.398s. Apr 10 18:28:01 volumiocybou volumio[903]: info: MYVOLUMIO Environment detected Apr 10 18:28:01 volumiocybou volumio[903]: info: Plugin folders cleanup Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning into folder /volumio/app/plugins/ Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category audio_interface Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category miscellanea Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category music_service Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category plugins.json Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category system_controller Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category user_interface Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning into folder /data/plugins/ Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category music_service Apr 10 18:28:01 volumiocybou volumio[903]: info: Scanning category system_hardware Apr 10 18:28:01 volumiocybou volumio[903]: info: Plugin folders cleanup completed Apr 10 18:28:01 volumiocybou volumio[903]: info: ------------------------------------------- Apr 10 18:28:01 volumiocybou volumio[903]: info: ----- Core plugins startup ---- Apr 10 18:28:01 volumiocybou volumio[903]: info: ------------------------------------------- Apr 10 18:28:01 volumiocybou volumio[903]: info: Loading plugins from folder /volumio/app/plugins/ Apr 10 18:28:01 volumiocybou volumio[903]: info: Adding plugin upnp to MyMusic Plugins Apr 10 18:28:01 volumiocybou volumio[903]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 10 18:28:01 volumiocybou volumio[903]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 10 18:28:01 volumiocybou volumio[903]: info: Loading plugins from folder /data/plugins/ Apr 10 18:28:01 volumiocybou volumio[903]: info: Loading plugin "system"... Apr 10 18:28:01 volumiocybou volumio[903]: info: Loading plugin "appearance"... Apr 10 18:28:02 volumiocybou volumio-remote-updater[612]: [2026-04-10 18:28:02] [connect] Successful connection Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "network"... Apr 10 18:28:03 volumiocybou volumio[903]: info: Refreshing Cached IP Addresses Apr 10 18:28:03 volumiocybou sudo[1005]: volumio : unable to resolve host volumiocybou Apr 10 18:28:03 volumiocybou sudo[1005]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:03 volumiocybou sudo[1007]: volumio : unable to resolve host volumiocybou Apr 10 18:28:03 volumiocybou sudo[1007]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "services"... Apr 10 18:28:03 volumiocybou sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 18:28:03 volumiocybou sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "alsa_controller"... Apr 10 18:28:03 volumiocybou sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 18:28:03 volumiocybou sudo[1007]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:03 volumiocybou sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:03 volumiocybou sudo[1005]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:03 volumiocybou sudo[1014]: volumio : unable to resolve host volumiocybou Apr 10 18:28:03 volumiocybou sudo[1014]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:03 volumiocybou sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 10 18:28:03 volumiocybou sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:03 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "wizard"... Apr 10 18:28:03 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:03 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 10 18:28:03 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "networkfs"... Apr 10 18:28:03 volumiocybou volumio[903]: info: Starting Udev Watcher for removable devices Apr 10 18:28:03 volumiocybou sudo[1033]: volumio : unable to resolve host volumiocybou Apr 10 18:28:03 volumiocybou sudo[1033]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:03 volumiocybou volumio[903]: info: Ignoring mount for partition: boot Apr 10 18:28:03 volumiocybou volumio[903]: info: Ignoring mount for partition: volumio Apr 10 18:28:03 volumiocybou volumio[903]: info: Ignoring mount for partition: volumio_data Apr 10 18:28:03 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "volumio_command_line_client"... Apr 10 18:28:03 volumiocybou sudo[1033]: 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.200/Music /mnt/NAS/Music Apr 10 18:28:03 volumiocybou sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "upnp"... Apr 10 18:28:03 volumiocybou volumio[903]: info: [1775838483738] Starting Upmpd Daemon Apr 10 18:28:03 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "my_music"... Apr 10 18:28:03 volumiocybou volumio[903]: info: Loading plugin "mpd"... Apr 10 18:28:03 volumiocybou kernel: Key type cifs.spnego registered Apr 10 18:28:03 volumiocybou kernel: Key type cifs.idmap registered Apr 10 18:28:03 volumiocybou 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. Apr 10 18:28:03 volumiocybou kernel: CIFS: Attempting to mount //192.168.1.200/Music Apr 10 18:28:04 volumiocybou kernel: cryptd: max_cpu_qlen set to 1000 Apr 10 18:28:04 volumiocybou sudo[1033]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:04 volumiocybou volumio[903]: info: Loading plugin "upnp_browser"... Apr 10 18:28:04 volumiocybou sudo[1014]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:04 volumiocybou volumio[903]: info: Loading plugin "alarm-clock"... Apr 10 18:28:05 volumiocybou volumio[903]: info: Loading plugin "airplay_emulation"... Apr 10 18:28:05 volumiocybou volumio[903]: info: Starting Shairport Sync Apr 10 18:28:05 volumiocybou volumio[903]: info: Loading plugin "last_100"... Apr 10 18:28:05 volumiocybou volumio[903]: info: Loading plugin "webradio"... Apr 10 18:28:05 volumiocybou volumio[903]: info: Loading plugin "i2s_dacs"... Apr 10 18:28:05 volumiocybou volumio[903]: info: Loading plugin "volumiodiscovery"... Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** For more information see Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 18:28:06 volumiocybou volumio[903]: *** WARNING *** For more information see Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** For more information see Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 18:28:06 volumiocybou node[903]: *** WARNING *** For more information see Apr 10 18:28:06 volumiocybou volumio[903]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 10 18:28:06 volumiocybou volumio[903]: info: Discovery: Started advertising with name: Volumio_CyBou Apr 10 18:28:06 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 18:28:06 volumiocybou volumio[903]: info: Loading plugin "spop"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "outputs"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "albumart"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Plugin example_plugin is not enabled Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "inputs"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "updater_comm"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Plugin mpdemulation is not enabled Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "rest_api"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "websocket"... Apr 10 18:28:08 volumiocybou volumio[903]: info: Starting Socket.io Server version 2.3.0 Apr 10 18:28:08 volumiocybou volumio[903]: info: Loading plugin "audiophonicsonoff"... Apr 10 18:28:08 volumiocybou volumio[903]: Forking 3 albumart workers Apr 10 18:28:09 volumiocybou volumio[903]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 10 18:28:09 volumiocybou volumio[903]: info: Audiophonics on/off initiated Apr 10 18:28:09 volumiocybou volumio[903]: info: Loading i18n strings for locale fr Apr 10 18:28:09 volumiocybou volumio[903]: Updating browse sources language Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::initPlayerControls Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 18:28:09 volumiocybou volumio[903]: Express server listening on port 3000 Apr 10 18:28:09 volumiocybou volumio[903]: [Metrics] WebUI: 11s 856.46ms Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreStateMachine::resetVolumioState Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreStateMachine::getcurrentVolume Apr 10 18:28:09 volumiocybou volumio[903]: info: CoreCommandRouter::volumioRetrievevolume Apr 10 18:28:09 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:09 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 10 18:28:09 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:09 volumiocybou volumio[903]: info: Volumio Network Manager: Network status updated: 1 Apr 10 18:28:09 volumiocybou volumio[903]: Starting albumart workers Apr 10 18:28:09 volumiocybou volumio[903]: Starting albumart workers Apr 10 18:28:09 volumiocybou volumio[903]: Starting albumart workers Apr 10 18:28:09 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:09 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:10 volumiocybou volumio[903]: info: Reloading queue from file Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::setRepeat null single undefined Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::pushState Apr 10 18:28:10 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioPushState Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::setRandom null Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::pushState Apr 10 18:28:10 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioPushState Apr 10 18:28:10 volumiocybou volumio[903]: info: VolumeController:: Volume=76 Mute =true Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::pushState Apr 10 18:28:10 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioPushState Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreStateMachine::updateTrackBlock Apr 10 18:28:10 volumiocybou volumio[903]: info: CorePlayQueue::getTrackBlock Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioRetrievevolume Apr 10 18:28:10 volumiocybou volumio[903]: info: Setting Device type: Raspberry PI Apr 10 18:28:10 volumiocybou volumio[903]: info: Completed loading Core Plugins Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:10 volumiocybou volumio[903]: info: Preparing to generate the ALSA configuration file Apr 10 18:28:10 volumiocybou volumio[903]: info: Discovery: adding dbef6245-87b0-4dbd-b1f1-5cbbe426e33b Apr 10 18:28:10 volumiocybou volumio[903]: info: Discovery: Found device Volumio_CyBou Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioGetState Apr 10 18:28:10 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:10 volumiocybou volumio[903]: info: Asound.conf file unchanged, so no further update is needed Apr 10 18:28:10 volumiocybou volumio[903]: info: Output device has changed, restarting MPD Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 10 18:28:10 volumiocybou kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 10 18:28:10 volumiocybou volumio[903]: info: Output device has changed, restarting Shairport Sync Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:10 volumiocybou sudo[1116]: volumio : unable to resolve host volumiocybou Apr 10 18:28:10 volumiocybou sudo[1116]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:10 volumiocybou sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 10 18:28:10 volumiocybou sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:10 volumiocybou sudo[1116]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:10 volumiocybou sudo[1119]: volumio : unable to resolve host volumiocybou Apr 10 18:28:10 volumiocybou sudo[1119]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:10 volumiocybou sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 10 18:28:10 volumiocybou sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:10 volumiocybou volumio[903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 18:28:10 volumiocybou volumio[903]: info: ___________ START PLUGINS ___________ Apr 10 18:28:10 volumiocybou volumio[903]: info: ControllerMpd::onStart: Initializing MPD Apr 10 18:28:10 volumiocybou volumio[903]: info: Creating MPD Configuration file Apr 10 18:28:10 volumiocybou systemd[1]: Stopping Music Player Daemon... Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 18:28:10 volumiocybou volumio[903]: info: [1775838490581] CoreMusicLibrary::Adding element Serveurs Média Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 18:28:10 volumiocybou sudo[1133]: volumio : unable to resolve host volumiocybou Apr 10 18:28:10 volumiocybou sudo[1133]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:10 volumiocybou sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 10 18:28:10 volumiocybou sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:10 volumiocybou sudo[1133]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:10 volumiocybou sudo[1135]: volumio : unable to resolve host volumiocybou Apr 10 18:28:10 volumiocybou sudo[1135]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:10 volumiocybou sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 10 18:28:10 volumiocybou sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:10 volumiocybou systemd[1]: mpd.service: Succeeded. Apr 10 18:28:10 volumiocybou systemd[1]: Stopped Music Player Daemon. Apr 10 18:28:10 volumiocybou volumio[903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:10 volumiocybou systemd[1]: Starting Music Player Daemon... Apr 10 18:28:10 volumiocybou volumio[903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 18:28:10 volumiocybou volumio[903]: info: [1775838490800] CoreMusicLibrary::Adding element Last_100 Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 18:28:10 volumiocybou volumio[903]: info: [1775838490804] CoreMusicLibrary::Adding element Webradio Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 18:28:10 volumiocybou volumio[903]: info: Initializing BBC Radios Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:10 volumiocybou sudo[1157]: root : unable to resolve host volumiocybou Apr 10 18:28:10 volumiocybou sudo[1157]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:10 volumiocybou sudo[1157]: sudo: unable to resolve host volumiocybou: System error Apr 10 18:28:10 volumiocybou sudo[1157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 10 18:28:10 volumiocybou sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:10 volumiocybou volumio[903]: info: Creating Spotify config file Apr 10 18:28:10 volumiocybou sudo[1157]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:10 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: Configuring GPIO pins Apr 10 18:28:11 volumiocybou volumio[903]: info: Soft shutdown GPIO 516 binding... OK Apr 10 18:28:11 volumiocybou volumio[903]: info: Hardware button GPIO 529 binding... OK Apr 10 18:28:11 volumiocybou volumio[903]: info: Boot OK GPIO 534 binding... OK Apr 10 18:28:11 volumiocybou volumio[903]: info: Volumio Calling Home Apr 10 18:28:11 volumiocybou volumio[903]: info: Discovery: this is already registered, dbef6245-87b0-4dbd-b1f1-5cbbe426e33b Apr 10 18:28:11 volumiocybou volumio[903]: info: Discovery: Found device Volumio_CyBou Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::volumioGetState Apr 10 18:28:11 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:11 volumiocybou volumio[903]: info: MPD Permissions set Apr 10 18:28:11 volumiocybou volumio[903]: info: MPD Permissions set Apr 10 18:28:11 volumiocybou volumio[903]: info: VolumeController:: Volume=76 Mute =true Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreStateMachine::pushState Apr 10 18:28:11 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::volumioPushState Apr 10 18:28:11 volumiocybou volumio[903]: info: Volumio called home Apr 10 18:28:11 volumiocybou volumio[903]: info: Spotify config file written Apr 10 18:28:11 volumiocybou sudo[1203]: volumio : unable to resolve host volumiocybou Apr 10 18:28:11 volumiocybou sudo[1203]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:11 volumiocybou sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 10 18:28:11 volumiocybou sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:11 volumiocybou systemd[1]: Started go-librespot Daemon. Apr 10 18:28:11 volumiocybou go-librespot[1205]: go-librespot daemon starting... Apr 10 18:28:11 volumiocybou sudo[1203]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:11 volumiocybou volumio[903]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 18:28:11 volumiocybou volumio[903]: info: No need to fix Spotify hosts Apr 10 18:28:11 volumiocybou volumio[903]: info: Starting Shairport Sync Apr 10 18:28:11 volumiocybou volumio[903]: info: Starting Shairport Sync Apr 10 18:28:11 volumiocybou volumio[903]: info: Starting Shairport Sync Apr 10 18:28:11 volumiocybou sudo[1221]: volumio : unable to resolve host volumiocybou Apr 10 18:28:11 volumiocybou sudo[1221]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:11 volumiocybou sudo[1223]: volumio : unable to resolve host volumiocybou Apr 10 18:28:11 volumiocybou sudo[1223]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:11 volumiocybou sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 18:28:11 volumiocybou sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:11 volumiocybou sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 18:28:11 volumiocybou sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:12 volumiocybou volumio[903]: info: CoreCommandRouter::volumioGetState Apr 10 18:28:12 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:12 volumiocybou sudo[1225]: volumio : unable to resolve host volumiocybou Apr 10 18:28:12 volumiocybou sudo[1225]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:12 volumiocybou sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 18:28:12 volumiocybou sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:12 volumiocybou systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 10 18:28:12 volumiocybou systemd[1]: shairport-sync.service: Succeeded. Apr 10 18:28:12 volumiocybou systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 10 18:28:12 volumiocybou volumio[903]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 10 18:28:12 volumiocybou volumio[903]: SPOTIFY: BQDgtgXjaT4HfDy63EsMTNXUZDJ1Uph8KvtO9_MOAqeeai24JFzmTQhWXpyLyVhdt2AR1hE1g8xplw82MjcCZ35e9VVLKS7rEXKzUH8SICwZq-K4IXBIV-Yarhqpwi1WlVse0IcegLC_VOvztYhhZYy4Wt5ca2cOMOyJaKdNPRzM4trVXXt5ZOr-XKUtClS2lsinJU0-OArFoiDaF664jrjUjsZr_iyBJrrNNQw8OKNAzcN8BwSjSJ7vy-I6EW73gLBHk-lfHgMmnAwj0_nsYQq2NaxE8CPQltn16tthXxzVVkBs8il7PNYL7HgnUVZLYg6wNpWmKA Apr 10 18:28:12 volumiocybou volumio[903]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 10 18:28:12 volumiocybou volumio[903]: info: New Spotify access token = BQDgtgXjaT4HfDy63EsMTNXUZDJ1Uph8KvtO9_MOAqeeai24JFzmTQhWXpyLyVhdt2AR1hE1g8xplw82MjcCZ35e9VVLKS7rEXKzUH8SICwZq-K4IXBIV-Yarhqpwi1WlVse0IcegLC_VOvztYhhZYy4Wt5ca2cOMOyJaKdNPRzM4trVXXt5ZOr-XKUtClS2lsinJU0-OArFoiDaF664jrjUjsZr_iyBJrrNNQw8OKNAzcN8BwSjSJ7vy-I6EW73gLBHk-lfHgMmnAwj0_nsYQq2NaxE8CPQltn16tthXxzVVkBs8il7PNYL7HgnUVZLYg6wNpWmKA Apr 10 18:28:12 volumiocybou volumio[903]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 10 18:28:12 volumiocybou systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 10 18:28:12 volumiocybou sudo[1221]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:12 volumiocybou sudo[1223]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:12 volumiocybou systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 10 18:28:12 volumiocybou systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 10 18:28:12 volumiocybou systemd[1]: shairport-sync.service: Succeeded. Apr 10 18:28:12 volumiocybou systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 10 18:28:12 volumiocybou volumio[903]: info: Shairport-Sync Started Apr 10 18:28:12 volumiocybou volumio[903]: Error adding Membership: Error: addMembership EINVAL Apr 10 18:28:12 volumiocybou volumio[903]: info: Shairport-Sync Started Apr 10 18:28:12 volumiocybou systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 10 18:28:12 volumiocybou sudo[1225]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:12 volumiocybou volumio[903]: info: Shairport-Sync Started Apr 10 18:28:12 volumiocybou volumio[903]: SPOTIFY: User informations: {"country":"FR","display_name":"Cyril Bourbon","email":"cybou49@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/oc84oyslvj8ffdjqrg1rwvgma"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/oc84oyslvj8ffdjqrg1rwvgma","id":"oc84oyslvj8ffdjqrg1rwvgma","images":[],"product":"premium","type":"user","uri":"spotify:user:oc84oyslvj8ffdjqrg1rwvgma"} Apr 10 18:28:12 volumiocybou volumio[903]: info: Spotify Successfully logged in Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=info msg="running go-librespot 0.4.0" Apr 10 18:28:12 volumiocybou volumio[903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="app state loaded" Apr 10 18:28:12 volumiocybou volumio[903]: info: [1775838492301] CoreMusicLibrary::Adding element Spotify Apr 10 18:28:12 volumiocybou volumio[903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 18:28:12 volumiocybou volumio[903]: Cannot find translation for source Spotify Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02: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]" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02: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]" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02: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]" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=info msg="zeroconf server listening on port 41973" Apr 10 18:28:12 volumiocybou mpd[1172]: Apr 10 18:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="obtained new client token: AABfNvcX59Narttsy/1/iuLBg5YBIe1x+UKNPPmanJ1OWxLeboZGvkRhp8Jnn6OBDEvYXQiuEd4MzN+2nbjTYZWOSWqx7bXP9ib6g3s6bliXjHxyI7eDKBz+h3wzqg3imy25wqUKWzBro0jRfeVrmPpruoi9PwBfkulKYcShdQO5IRNqG+LkxzrucVl7QgjvpOroJOqwgH7YshwJioOa4M61OXkKrCNC+9zgNYVvG/GU50xdhlQGLX9E8R8=" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="completed keyexchange" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="completed challenge" Apr 10 18:28:12 volumiocybou systemd[1]: Started Music Player Daemon. Apr 10 18:28:12 volumiocybou sudo[1119]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:12 volumiocybou sudo[1135]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=info msg="authenticated AP" username="oc*********************ma" Apr 10 18:28:12 volumiocybou volumio[903]: info: Completed starting Core Plugins Apr 10 18:28:12 volumiocybou volumio[903]: info: ------------------------------------------- Apr 10 18:28:12 volumiocybou volumio[903]: info: ----- MyVolumio plugins startup ---- Apr 10 18:28:12 volumiocybou volumio[903]: info: ------------------------------------------- Apr 10 18:28:12 volumiocybou volumio[903]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 10 18:28:12 volumiocybou volumio[903]: error: MPD error: The expression evaluated to a falsy value: Apr 10 18:28:12 volumiocybou volumio[903]: assert.ok(self.idling) Apr 10 18:28:12 volumiocybou volumio[903]: error: The expression evaluated to a falsy value: Apr 10 18:28:12 volumiocybou volumio[903]: assert.ok(self.idling) Apr 10 18:28:12 volumiocybou volumio[903]: info: MPD running with PID1172 Apr 10 18:28:12 volumiocybou volumio[903]: ,establishing connection Apr 10 18:28:12 volumiocybou volumio[903]: error: updateQueue error: null Apr 10 18:28:12 volumiocybou volumio[903]: error: updateQueue error: null Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=info msg="authenticated Login5" username="oc*********************ma" Apr 10 18:28:12 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:12+02:00" level=debug msg="initializing zeroconf session" username="oc*********************ma" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="dealer connection opened" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=trace msg="starting accesspoint recv loop" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=trace msg="starting dealer recv loop" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=trace msg="received accesspoint ping" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="received connection id: ZjllNWYwMzktNGYy...ODYyRTU4RDkyNg==" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=trace msg="received accesspoint pong ack" Apr 10 18:28:13 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:13+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 10 18:28:13 volumiocybou sudo[1254]: volumio : unable to resolve host volumiocybou Apr 10 18:28:13 volumiocybou sudo[1254]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:13 volumiocybou sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 18:28:13 volumiocybou sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:13 volumiocybou sudo[1256]: volumio : unable to resolve host volumiocybou Apr 10 18:28:13 volumiocybou sudo[1256]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:13 volumiocybou sudo[1254]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:13 volumiocybou sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 18:28:13 volumiocybou sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:13 volumiocybou sudo[1256]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:13 volumiocybou sudo[1259]: volumio : unable to resolve host volumiocybou Apr 10 18:28:13 volumiocybou sudo[1259]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:13 volumiocybou sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 10 18:28:13 volumiocybou sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 18:28:13 volumiocybou systemd[1]: Started UPnP Renderer front-end to MPD. Apr 10 18:28:13 volumiocybou sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 10 18:28:13 volumiocybou volumio[903]: info: Upmpdcli Daemon Started Apr 10 18:28:14 volumiocybou volumio[1262]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 10 18:28:14 volumiocybou volumio[903]: info: go-librespot daemon successfully initialized Apr 10 18:28:16 volumiocybou volumio[1262]: ....................++++ Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="handling transfer player command from bc64316f7e98c3be28631398ef7bcba4216202c1" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="resolved context of track" uri="spotify:artist:7pcG7za39JaUHalwqiuCt9" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:artist:7pcG7za39JaUHalwqiuCt9" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="fetched new page 1 with 23 items (list: 33)" uri="spotify:artist:7pcG7za39JaUHalwqiuCt9" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou systemd[1]: systemd-fsckd.service: Succeeded. Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="emitting websocket event: will_play" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="selected format OGG_VORBIS_320 (fb68e8c82aa4367ecff064a1fb27501e1697a019)" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="requested aes key for file fb68e8c82aa4367ecff064a1fb27501e1697a019, gid: 50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 310" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1404" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="fetched first chunk of 19, total size is 9537191 bytes" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="created new output device" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=info msg="loaded track \"Que des cœurs\" (paused: true, position: 0ms, duration: 230146ms, prefetched: false)" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:17 volumiocybou volumio[903]: info: Initializing connection to go-librespot Websocket Apr 10 18:28:17 volumiocybou volumio-remote-updater[612]: [2026-04-10 18:28:17] [connect] Successful connection Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="new websocket client" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="emitting websocket event: metadata" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="emitting websocket event: active" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="sending successful reply for dealer request" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 10 18:28:17 volumiocybou volumio[903]: info: Connection to go-librespot Websocket established Apr 10 18:28:17 volumiocybou volumio-remote-updater[612]: [2026-04-10 18:28:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1775838497 101 Apr 10 18:28:17 volumiocybou volumio[903]: 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: 2 Apr 10 18:28:17 volumiocybou volumio[903]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:50OB4Ny993e8ax95gi3GoS","name":"Que des cœurs","artist_names":["MPL"],"album_name":"L'Etoile","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02ec29eb0e7847d9981c7edaec","position":0,"duration":230146,"release_date":"year:2020 month:1 day:31","track_number":11,"disc_number":1}} Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 10 18:28:17 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:17+02:00" level=trace msg="emitting websocket event: paused" Apr 10 18:28:17 volumiocybou volumio[903]: SPOTIFY: received: {"type":"active","data":null} Apr 10 18:28:18 volumiocybou volumio[903]: info: Aligning Spotify Volume to Volumio Volume Apr 10 18:28:18 volumiocybou volumio[903]: info: CoreCommandRouter::volumioGetState Apr 10 18:28:18 volumiocybou volumio[903]: info: CorePlayQueue::getTrack 0 Apr 10 18:28:18 volumiocybou volumio[903]: info: Setting Spotify Volume from Volumio: 0 Apr 10 18:28:18 volumiocybou volumio[903]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:artist:7pcG7za39JaUHalwqiuCt9","uri":"spotify:track:50OB4Ny993e8ax95gi3GoS","play_origin":"artist"}} Apr 10 18:28:18 volumiocybou volumio[903]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 18:28:18 volumiocybou volumio[903]: TypeError: Cannot read property 'service' of undefined Apr 10 18:28:18 volumiocybou volumio[903]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Apr 10 18:28:18 volumiocybou volumio[903]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Apr 10 18:28:18 volumiocybou volumio[903]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Apr 10 18:28:18 volumiocybou volumio[903]: at WebSocket.emit (events.js:315:20) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.emit (events.js:315:20) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Apr 10 18:28:18 volumiocybou volumio[903]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 10 18:28:18 volumiocybou volumio[903]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Apr 10 18:28:18 volumiocybou volumio[903]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Apr 10 18:28:18 volumiocybou volumio[903]: at Socket.emit (events.js:315:20) Apr 10 18:28:18 volumiocybou volumio[903]: at addChunk (internal/streams/readable.js:309:12) Apr 10 18:28:18 volumiocybou volumio[903]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 10 18:28:18 volumiocybou volumio[903]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 18:28:18 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:18+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:18 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:18+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:18 volumiocybou go-librespot[1205]: time="2026-04-10T18:28:18+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:50OB4Ny993e8ax95gi3GoS" Apr 10 18:28:18 volumiocybou sudo[1291]: volumio : unable to resolve host volumiocybou Apr 10 18:28:18 volumiocybou sudo[1291]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 10 18:28:18 volumiocybou sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-10 18:27 Apr 10 18:28:18 volumiocybou sudo[1291]: 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"