-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-07-01 19:58:32 CEST. -- Jul 01 19:58:18 maison systemd-timedated[909]: Changed local time to Tue Jul 1 19:58:18 2025 Jul 01 19:58:18 maison sudo[907]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:18 maison volumio-time-update[526]: volumio-time-update-util: System time updated successfully. Jul 01 19:58:18 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "wizard"... Jul 01 19:58:18 maison systemd[1]: Starting Daily apt download activities... Jul 01 19:58:18 maison systemd[1]: Started Volumio Time Update Utility. Jul 01 19:58:18 maison systemd[1]: Reached target Multi-User System. Jul 01 19:58:18 maison systemd[1]: Reached target Graphical Interface. Jul 01 19:58:18 maison systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "networkfs"... Jul 01 19:58:18 maison systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 01 19:58:18 maison systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 01 19:58:18 maison volumio[800]: info: Starting Udev Watcher for removable devices Jul 01 19:58:18 maison sudo[938]: 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.254/Films 2/Musiques /mnt/NAS/Films_2 Jul 01 19:58:18 maison sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:18 maison volumio[800]: info: Ignoring mount for partition: boot Jul 01 19:58:18 maison volumio[800]: info: Ignoring mount for partition: volumio Jul 01 19:58:18 maison volumio[800]: info: Ignoring mount for partition: volumio_data Jul 01 19:58:18 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "volumio_command_line_client"... Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "upnp"... Jul 01 19:58:18 maison volumio[800]: info: [1751392698231] Starting Upmpd Daemon Jul 01 19:58:18 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "my_music"... Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "mpd"... Jul 01 19:58:18 maison kernel: Key type cifs.spnego registered Jul 01 19:58:18 maison kernel: Key type cifs.idmap registered Jul 01 19:58:18 maison 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. Jul 01 19:58:18 maison kernel: CIFS: Attempting to mount //192.168.1.254/Films 2/Musiques Jul 01 19:58:18 maison kernel: cryptd: max_cpu_qlen set to 1000 Jul 01 19:58:18 maison sudo[938]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:18 maison volumio[800]: info: Loading plugin "upnp_browser"... Jul 01 19:58:19 maison systemd[1]: apt-daily.service: Succeeded. Jul 01 19:58:19 maison systemd[1]: Started Daily apt download activities. Jul 01 19:58:19 maison systemd[1]: Starting Daily apt upgrade and clean activities... Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "alarm-clock"... Jul 01 19:58:19 maison volumio[800]: info: Plugin airplay_emulation is not enabled Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "last_100"... Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "webradio"... Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "i2s_dacs"... Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "volumiodiscovery"... Jul 01 19:58:19 maison systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 01 19:58:19 maison systemd[1]: Started Daily apt upgrade and clean activities. Jul 01 19:58:19 maison systemd[1]: Startup finished in 13.543s (kernel) + 19.133s (userspace) = 32.676s. Jul 01 19:58:19 maison volumio[800]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 01 19:58:19 maison volumio[800]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 19:58:19 maison volumio[800]: *** WARNING *** For more information see Jul 01 19:58:19 maison node[800]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 01 19:58:19 maison volumio[800]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 01 19:58:19 maison volumio[800]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 19:58:19 maison volumio[800]: *** WARNING *** For more information see Jul 01 19:58:19 maison node[800]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 19:58:19 maison node[800]: *** WARNING *** For more information see Jul 01 19:58:19 maison node[800]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 01 19:58:19 maison node[800]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 19:58:19 maison node[800]: *** WARNING *** For more information see Jul 01 19:58:19 maison volumio[800]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 01 19:58:19 maison volumio[800]: info: Discovery: Started advertising with name: Maison Jul 01 19:58:19 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 19:58:19 maison volumio[800]: info: Loading plugin "spop"... Jul 01 19:58:19 maison volumio-remote-updater[536]: [2025-07-01 19:58:19] [connect] Successful connection Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "outputs"... Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "albumart"... Jul 01 19:58:21 maison volumio[800]: info: Plugin example_plugin is not enabled Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "inputs"... Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "updater_comm"... Jul 01 19:58:21 maison volumio[800]: info: Plugin mpdemulation is not enabled Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "rest_api"... Jul 01 19:58:21 maison volumio[800]: info: Loading plugin "websocket"... Jul 01 19:58:21 maison volumio[800]: info: Starting Socket.io Server version 2.3.0 Jul 01 19:58:21 maison volumio[800]: info: Loading i18n strings for locale fr Jul 01 19:58:21 maison volumio[800]: Updating browse sources language Jul 01 19:58:21 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 19:58:21 maison volumio[800]: Forking 3 albumart workers Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::initPlayerControls Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 19:58:22 maison volumio[800]: Express server listening on port 3000 Jul 01 19:58:22 maison volumio[800]: [Metrics] WebUI: 8s 220.33ms Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::resetVolumioState Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::getcurrentVolume Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioRetrievevolume Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::pushState Jul 01 19:58:22 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioPushState Jul 01 19:58:22 maison volumio[800]: info: Volumio Network Manager: Network status updated: 1 Jul 01 19:58:22 maison volumio-remote-updater[536]: [2025-07-01 19:58:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751392699 101 Jul 01 19:58:22 maison volumio[800]: 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 Jul 01 19:58:22 maison volumio[800]: verbose: New Socket.io Connection to 192.168.1.12:3000 from 192.168.1.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 01 19:58:22 maison volumio[800]: info: Reloading queue from file Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::setRepeat null single undefined Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::pushState Jul 01 19:58:22 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioPushState Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::setRandom null Jul 01 19:58:22 maison volumio[800]: info: CoreStateMachine::pushState Jul 01 19:58:22 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioPushState Jul 01 19:58:22 maison volumio[800]: info: Setting Device type: Raspberry PI Jul 01 19:58:22 maison volumio[800]: info: Completed loading Core Plugins Jul 01 19:58:22 maison volumio[800]: info: Preparing to generate the ALSA configuration file Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioGetState Jul 01 19:58:22 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:22 maison volumio[800]: info: Asound.conf file unchanged, so no further update is needed Jul 01 19:58:22 maison volumio[800]: info: Output device has changed, restarting MPD Jul 01 19:58:22 maison volumio[800]: info: ___________ START PLUGINS ___________ Jul 01 19:58:22 maison volumio[800]: info: ControllerMpd::onStart: Initializing MPD Jul 01 19:58:22 maison volumio[800]: info: Creating MPD Configuration file Jul 01 19:58:22 maison sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 19:58:22 maison sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:22 maison sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 19:58:22 maison sudo[1113]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 19:58:22 maison sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:22 maison volumio[800]: info: [1751392702870] CoreMusicLibrary::Adding element Serveurs Média Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 19:58:22 maison sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 19:58:22 maison sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:22 maison sudo[1117]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 19:58:22 maison volumio[800]: info: [1751392702915] CoreMusicLibrary::Adding element Last_100 Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 19:58:22 maison volumio[800]: info: [1751392702923] CoreMusicLibrary::Adding element Webradio Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 19:58:22 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 19:58:22 maison volumio[800]: info: Initializing BBC Radios Jul 01 19:58:22 maison sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 19:58:22 maison sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:23 maison systemd[1]: Stopping Music Player Daemon... Jul 01 19:58:23 maison volumio[800]: Starting albumart workers Jul 01 19:58:23 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 19:58:23 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 19:58:23 maison volumio[800]: Starting albumart workers Jul 01 19:58:23 maison volumio[800]: info: Creating Spotify config file Jul 01 19:58:23 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:23 maison volumio[800]: Starting albumart workers Jul 01 19:58:23 maison volumio[800]: info: Volumio Calling Home Jul 01 19:58:23 maison systemd[1]: mpd.service: Succeeded. Jul 01 19:58:23 maison systemd[1]: Stopped Music Player Daemon. Jul 01 19:58:23 maison systemd[1]: Starting Music Player Daemon... Jul 01 19:58:23 maison sudo[1136]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 01 19:58:23 maison sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:23 maison sudo[1136]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:23 maison volumio[800]: info: Discovery: adding 8357a9cf-9099-432e-aefd-89b963598198 Jul 01 19:58:23 maison volumio[800]: info: Discovery: Found device Maison Jul 01 19:58:23 maison volumio[800]: info: CoreCommandRouter::volumioGetState Jul 01 19:58:23 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:23 maison volumio[800]: info: Discovery: adding 6bc4b200-9cd5-4c7f-b977-0311c15aac4b Jul 01 19:58:23 maison volumio[800]: info: Discovery: Found device Volumio HD Jul 01 19:58:23 maison volumio[800]: info: Discovery: Connecting to remote: 192.168.1.7 Jul 01 19:58:24 maison volumio[800]: info: MPD Permissions set Jul 01 19:58:24 maison volumio[800]: info: MPD Permissions set Jul 01 19:58:24 maison kernel: hwmon hwmon1: Undervoltage detected! Jul 01 19:58:24 maison volumio[800]: info: Discovery: Connected to remote: 192.168.1.7 Jul 01 19:58:24 maison volumio[800]: info: Volumio called home Jul 01 19:58:24 maison volumio[800]: info: Spotify config file written Jul 01 19:58:24 maison sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 01 19:58:24 maison sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:24 maison volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 01 19:58:24 maison volumio[800]: info: No need to fix Spotify hosts Jul 01 19:58:24 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:24 maison systemd[1]: Started go-librespot Daemon. Jul 01 19:58:24 maison go-librespot[1158]: Librespot-go daemon starting... Jul 01 19:58:24 maison sudo[1152]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:24 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 19:58:24 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:24 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:24 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:25 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 19:58:25 maison volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 19:58:25 maison volumio[800]: info: CoreCommandRouter::volumioGetState Jul 01 19:58:25 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:25 maison systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 01 19:58:25 maison systemd[1]: mpd.service: Failed with result 'protocol'. Jul 01 19:58:25 maison systemd[1]: Failed to start Music Player Daemon. Jul 01 19:58:25 maison go-librespot[1158]: time="2025-07-01T19:58:25+02:00" level=info msg="generated new device id: 0cb8de7683f778a41a429eb2731ed2c6ed98fa65" Jul 01 19:58:25 maison sudo[1115]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:25 maison sudo[1122]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:25 maison volumio[800]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 01 19:58:25 maison volumio[800]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 01 19:58:25 maison volumio[800]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 01 19:58:25 maison volumio[800]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 01 19:58:25 maison volumio[800]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 01 19:58:25 maison volumio[800]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 01 19:58:25 maison volumio[800]: info: Completed starting Core Plugins Jul 01 19:58:25 maison volumio[800]: info: ------------------------------------------- Jul 01 19:58:25 maison volumio[800]: info: ----- MyVolumio plugins startup ---- Jul 01 19:58:25 maison volumio[800]: info: ------------------------------------------- Jul 01 19:58:25 maison volumio[800]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 01 19:58:25 maison systemd[1]: Starting Music Player Daemon... Jul 01 19:58:25 maison sudo[1188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 01 19:58:25 maison sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:25 maison sudo[1188]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:26 maison go-librespot[1158]: time="2025-07-01T19:58:26+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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 01 19:58:26 maison go-librespot[1158]: time="2025-07-01T19:58:26+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 01 19:58:26 maison go-librespot[1158]: time="2025-07-01T19:58:26+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 01 19:58:26 maison go-librespot[1158]: time="2025-07-01T19:58:26+02:00" level=debug msg="zeroconf server listening on port 43489" Jul 01 19:58:27 maison mpd[1190]: Jul 01 19:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 01 19:58:28 maison sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 19:58:28 maison volumio[800]: info: go-librespot daemon successfully initialized Jul 01 19:58:28 maison sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:28 maison sudo[1208]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:28 maison sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 19:58:28 maison sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:28 maison sudo[1210]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:28 maison sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 01 19:58:28 maison sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 19:58:28 maison systemd[1]: Started UPnP Renderer front-end to MPD. Jul 01 19:58:28 maison sudo[1214]: pam_unix(sudo:session): session closed for user root Jul 01 19:58:28 maison volumio[800]: info: Upmpdcli Daemon Started Jul 01 19:58:28 maison systemd[1]: Started Music Player Daemon. Jul 01 19:58:28 maison mpd_monitor.sh[521]: MPD restarted due to no mpc output. Jul 01 19:58:28 maison kernel: hwmon hwmon1: Voltage normalised Jul 01 19:58:28 maison volumio[800]: error: MPD error: The expression evaluated to a falsy value: Jul 01 19:58:28 maison volumio[800]: assert.ok(self.idling) Jul 01 19:58:28 maison volumio[800]: error: The expression evaluated to a falsy value: Jul 01 19:58:28 maison volumio[800]: assert.ok(self.idling) Jul 01 19:58:28 maison volumio[800]: error: updateQueue error: null Jul 01 19:58:28 maison volumio[1216]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=debug msg="obtained new client token: AAAKtBWJ5IXCGrritpSwdaujwlKBPiUWccEFHxnYM8YlGFZuWXoFOM5wYZN3GRkLhS9ZZcHCFJSI4upEPXprt9TDgxPbgicxz7QCnpPG4qBxE1xan2Cdo9vVeUncxBscnGXG6kbsKGQzoq060OKyiIb0JPUGqi4sgL+Xo6NyBOFtfvlpitOGIe7TSvEUDYu4pg0hVzAyLOCufrXOfjLmKkISVg+dHVgzb/VxrPKB+gMx70VC0Pofywrq9w3m" Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=debug msg="completed keyexchange" Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=debug msg="completed challenge" Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=debug msg="authenticated as dgbertin41" Jul 01 19:58:30 maison go-librespot[1158]: time="2025-07-01T19:58:30+02:00" level=debug msg="authenticated as dgbertin41" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="dealer connection opened" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=info msg="accepted zeroconf user dgbertin41 from Xperia 1 IV" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="autoplay enabled: false" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="received connection id: YjJjZTFlZWQtZjdiMi00ODFiLThlOGQtZjAwOGM1MjNhZDVmK2RlYWxlcit0Y3A6Ly8wYWNhNDFlMi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMkM1RDYyNEE5ODUwQkY2N0EwOUY4RjU3MjJGMzkzMTEyMzczMjcwRTY4OUNBMkZBMUJENjQ1MTg2QjVFNUE3NA==" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 01 19:58:31 maison volumio[800]: info: Initializing connection to go-librespot Websocket Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="new websocket client" Jul 01 19:58:31 maison volumio[800]: info: Connection to go-librespot Websocket established Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="handling transfer player command from 8c1e35f7c78d7cbbf3db8b81e94a6c1cd68de21c" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:02vq9K1GfNkFTkSEeY6rk0" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="fetched new page 0 with 214 items (list: 214)" uri="spotify:playlist:02vq9K1GfNkFTkSEeY6rk0" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="shuffled context with seed 2189785329847359480 (len: 214, keep: 69)" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="loading track (paused: false, position: 61688ms)" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="emitting websocket event: will_play" Jul 01 19:58:31 maison volumio[800]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:06O3hxudc6V0BOuoCFSy71","play_origin":"playlist"}} Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="selected format OGG_VORBIS_320 (bdeea51c2a9b695dd96128fce252be919805e195)" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="requested aes key for file bdeea51c2a9b695dd96128fce252be919805e195, gid: 06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1341" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched first chunk of 17, total size is 8502812 bytes" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="seek to 61688ms (diff: 92ms, samples: 2720440, bytes: 2512342)" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1114" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="created new output device" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=info msg="loaded track \"Tout oublier\" (paused: false, position: 61688ms, duration: 202120ms, prefetched: false)" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:06O3hxudc6V0BOuoCFSy71" Jul 01 19:58:31 maison kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="scheduling prefetch in 110s" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="emitting websocket event: metadata" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=trace msg="emitting websocket event: active" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="sending successful reply for dealer request" Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 01 19:58:31 maison go-librespot[1158]: time="2025-07-01T19:58:31+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 01 19:58:31 maison volumio[800]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:06O3hxudc6V0BOuoCFSy71","name":"Tout oublier","artist_names":["Angèle","Roméo Elvis"],"album_name":"Brol","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022433cb43f0f2f0f23b7c8b82","position":61688,"duration":202120,"release_date":"year:2018 month:10 day:5","track_number":4,"disc_number":1}} Jul 01 19:58:31 maison volumio[800]: SPOTIFY: received: {"type":"active","data":null} Jul 01 19:58:31 maison volumio[800]: info: Aligning Spotify Volume to Volumio Volume Jul 01 19:58:31 maison volumio[800]: info: CoreCommandRouter::volumioGetState Jul 01 19:58:31 maison volumio[800]: info: CorePlayQueue::getTrack 0 Jul 01 19:58:32 maison go-librespot[1158]: time="2025-07-01T19:58:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 01 19:58:32 maison go-librespot[1158]: time="2025-07-01T19:58:32+02:00" level=trace msg="emitting websocket event: playing" Jul 01 19:58:32 maison volumio[800]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:06O3hxudc6V0BOuoCFSy71","play_origin":"playlist"}} Jul 01 19:58:32 maison volumio[800]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 19:58:32 maison volumio[800]: TypeError: Cannot read property 'service' of undefined Jul 01 19:58:32 maison volumio[800]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Jul 01 19:58:32 maison volumio[800]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Jul 01 19:58:32 maison volumio[800]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Jul 01 19:58:32 maison volumio[800]: at WebSocket.emit (events.js:315:20) Jul 01 19:58:32 maison volumio[800]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jul 01 19:58:32 maison volumio[800]: at Receiver.emit (events.js:315:20) Jul 01 19:58:32 maison volumio[800]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jul 01 19:58:32 maison volumio[800]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 01 19:58:32 maison volumio[800]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 01 19:58:32 maison volumio[800]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jul 01 19:58:32 maison volumio[800]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 01 19:58:32 maison volumio[800]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jul 01 19:58:32 maison volumio[800]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jul 01 19:58:32 maison volumio[800]: at Socket.emit (events.js:315:20) Jul 01 19:58:32 maison volumio[800]: at addChunk (internal/streams/readable.js:309:12) Jul 01 19:58:32 maison volumio[800]: at readableAddChunk (internal/streams/readable.js:284:9) Jul 01 19:58:32 maison volumio[800]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 19:58:32 maison sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 19:57 Jul 01 19:58:32 maison sudo[1246]: 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"