-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2026-01-13 20:14:19 CET. -- Jan 13 20:13:01 volumiocuisine mpd[1112]: Jan 13 20:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 20:13:01 volumiocuisine systemd[1]: Started Music Player Daemon. Jan 13 20:13:01 volumiocuisine sudo[1083]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:01 volumiocuisine sudo[1077]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:01 volumiocuisine volumio[900]: info: Completed starting Core Plugins Jan 13 20:13:01 volumiocuisine volumio[900]: info: ------------------------------------------- Jan 13 20:13:01 volumiocuisine volumio[900]: info: ----- MyVolumio plugins startup ---- Jan 13 20:13:01 volumiocuisine volumio[900]: info: ------------------------------------------- Jan 13 20:13:01 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 20:13:01 volumiocuisine volumio[900]: info: MPD running with PID1112 Jan 13 20:13:01 volumiocuisine volumio[900]: ,establishing connection Jan 13 20:13:01 volumiocuisine volumio[900]: error: MPD error: The expression evaluated to a falsy value: Jan 13 20:13:01 volumiocuisine volumio[900]: assert.ok(self.idling) Jan 13 20:13:01 volumiocuisine volumio[900]: error: The expression evaluated to a falsy value: Jan 13 20:13:01 volumiocuisine volumio[900]: assert.ok(self.idling) Jan 13 20:13:01 volumiocuisine volumio[900]: error: MPD error: The expression evaluated to a falsy value: Jan 13 20:13:01 volumiocuisine volumio[900]: assert.ok(self.idling) Jan 13 20:13:01 volumiocuisine volumio[900]: error: The expression evaluated to a falsy value: Jan 13 20:13:01 volumiocuisine volumio[900]: assert.ok(self.idling) Jan 13 20:13:01 volumiocuisine volumio[900]: error: updateQueue error: null Jan 13 20:13:03 volumiocuisine systemd[1]: systemd-fsckd.service: Succeeded. Jan 13 20:13:04 volumiocuisine sudo[1132]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:04 volumiocuisine sudo[1132]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:04 volumiocuisine sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 20:13:04 volumiocuisine sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:04 volumiocuisine sudo[1134]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:04 volumiocuisine sudo[1134]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:04 volumiocuisine sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 20:13:04 volumiocuisine sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:04 volumiocuisine systemd[1]: Started MPD Monitor Service. Jan 13 20:13:04 volumiocuisine mpd_monitor.sh[1136]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 20:13:04 volumiocuisine sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:04 volumiocuisine volumio[900]: info: Successfully started MPD Monitor Jan 13 20:13:04 volumiocuisine systemd[1]: Stopping MPD Monitor Service... Jan 13 20:13:04 volumiocuisine systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 13 20:13:04 volumiocuisine systemd[1]: mpd_monitor.service: Succeeded. Jan 13 20:13:04 volumiocuisine systemd[1]: Stopped MPD Monitor Service. Jan 13 20:13:04 volumiocuisine systemd[1]: Started MPD Monitor Service. Jan 13 20:13:04 volumiocuisine sudo[1134]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:04 volumiocuisine volumio[900]: info: Successfully started MPD Monitor Jan 13 20:13:04 volumiocuisine mpd_monitor.sh[1140]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 20:13:06 volumiocuisine volumio[900]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 20:13:06 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:06 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 20:13:09 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 20:13:11 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 20:13:11 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 20:13:11 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:11 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:11 volumiocuisine volumio[900]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 20:13:11 volumiocuisine volumio[900]: info: MyVolumio login type: Token Jan 13 20:13:11 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 20:13:11 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 20:13:12 volumiocuisine volumio[900]: info: Starting Streaming Service Transparent Proxy Jan 13 20:13:12 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 20:13:12 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 20:13:12 volumiocuisine volumio[900]: info: Streaming services startup Jan 13 20:13:12 volumiocuisine volumio[900]: info: Starting Streaming Daemon Jan 13 20:13:12 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 20:13:12 volumiocuisine sudo[1165]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:12 volumiocuisine sudo[1165]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:12 volumiocuisine sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 20:13:12 volumiocuisine sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:12 volumiocuisine sudo[1165]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:12 volumiocuisine volumio[900]: error: Cannot start Volumio Streaming Daemon Jan 13 20:13:12 volumiocuisine volumio[900]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 20:13:12 volumiocuisine volumio[900]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:12 volumiocuisine volumio[900]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 20:13:13 volumiocuisine volumio[900]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 20:13:13 volumiocuisine volumio[900]: info: MyVolumio token set successfully Jan 13 20:13:13 volumiocuisine volumio[900]: info: MYVOLUMIO: Adding device Jan 13 20:13:13 volumiocuisine volumio[900]: info: MYVOLUMIO: Evaluating Server Jan 13 20:13:13 volumiocuisine volumio[900]: STREAMING PROXY: Starting server on port 3245 Jan 13 20:13:13 volumiocuisine volumio[900]: Node JS runtime: 14 Jan 13 20:13:14 volumiocuisine volumio[900]: info: MyVolumio Plan changed: premium Jan 13 20:13:14 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 13 20:13:14 volumiocuisine volumio[900]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 20:13:14 volumiocuisine volumio[900]: info: Removing audio output: Jan 13 20:13:14 volumiocuisine volumio[900]: info: MYVOLUMIO: Adding device Jan 13 20:13:14 volumiocuisine volumio[900]: info: MYVOLUMIO: Evaluating Server Jan 13 20:13:14 volumiocuisine volumio[900]: info: Remote config written successfully Jan 13 20:13:14 volumiocuisine volumio[900]: info: Starting Tunnel 1 Jan 13 20:13:14 volumiocuisine volumio[900]: info: Starting Tunnel Connection Checker Jan 13 20:13:14 volumiocuisine volumio[900]: info: Completed starting MyVolumio Plugin Jan 13 20:13:14 volumiocuisine volumio[900]: info: MYVolumio Device enabled Jan 13 20:13:14 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 13 20:13:14 volumiocuisine volumio[900]: info: MyVolumio status changed Jan 13 20:13:14 volumiocuisine volumio[900]: info: Streaming services startup Jan 13 20:13:14 volumiocuisine volumio[900]: info: Starting Streaming Daemon Jan 13 20:13:14 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 13 20:13:14 volumiocuisine sudo[1211]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:14 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 13 20:13:14 volumiocuisine sudo[1211]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:14 volumiocuisine sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 20:13:14 volumiocuisine sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:14 volumiocuisine sudo[1211]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:14 volumiocuisine volumio[900]: info: [1768331594905] Starting BluetoothController Jan 13 20:13:15 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 13 20:13:16 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 13 20:13:17 volumiocuisine sudo[1214]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:17 volumiocuisine sudo[1214]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:17 volumiocuisine sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 13 20:13:17 volumiocuisine sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:17 volumiocuisine sudo[1214]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:17 volumiocuisine volumio[900]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: MultiRoom plugin initialized Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: STOPPING SNAPCLIENT Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: Snap server stop Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: STOPPING volumioStreaming Jan 13 20:13:17 volumiocuisine sudo[1230]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:17 volumiocuisine sudo[1230]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 13 20:13:17 volumiocuisine sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 13 20:13:17 volumiocuisine sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:17 volumiocuisine sudo[1232]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:17 volumiocuisine sudo[1232]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:17 volumiocuisine sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 13 20:13:17 volumiocuisine sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 13 20:13:17 volumiocuisine sudo[1234]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:17 volumiocuisine sudo[1234]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:17 volumiocuisine sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 13 20:13:17 volumiocuisine sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:17 volumiocuisine sudo[1232]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:17 volumiocuisine sudo[1236]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:17 volumiocuisine sudo[1236]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 13 20:13:17 volumiocuisine sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 13 20:13:17 volumiocuisine sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:17 volumiocuisine sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:17 volumiocuisine sudo[1236]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:17 volumiocuisine sudo[1234]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 13 20:13:17 volumiocuisine volumio[900]: info: Preparing to generate the ALSA configuration file Jan 13 20:13:17 volumiocuisine volumio[900]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 13 20:13:17 volumiocuisine volumio[900]: info: Reading ALSA contributions from plugins. Jan 13 20:13:17 volumiocuisine volumio[900]: info: Setting Geolocation for MyVolumio to eu8 Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:17 volumiocuisine volumio[900]: info: Setting Geolocation for MyVolumio to eu2 Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: Removed streaming files Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: volumioStreaming STOPPED Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: SNAPSERVER STOPPED Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: SNAPCLIENT STOPPED Jan 13 20:13:17 volumiocuisine volumio[900]: error: Cannot start Volumio Streaming Daemon Jan 13 20:13:17 volumiocuisine volumio[900]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 20:13:17 volumiocuisine volumio[900]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:17 volumiocuisine volumio[900]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:17 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:17 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:13:17 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:13:17 volumiocuisine volumio[900]: info: Asound.conf file unchanged, so no further update is needed Jan 13 20:13:17 volumiocuisine volumio[900]: info: Output device has changed, restarting MPD Jan 13 20:13:17 volumiocuisine volumio[900]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:17 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 13 20:13:17 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 20:13:18 volumiocuisine sudo[1245]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine sudo[1245]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 13 20:13:18 volumiocuisine sudo[1242]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine sudo[1242]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 13 20:13:18 volumiocuisine sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output for this device Jan 13 20:13:18 volumiocuisine sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output Jan 13 20:13:18 volumiocuisine sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine sudo[1242]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:18 volumiocuisine systemd[1]: systemd-timedated.service: Succeeded. Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding audio output: Jan 13 20:13:18 volumiocuisine sudo[1262]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding audio output: Jan 13 20:13:18 volumiocuisine sudo[1262]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio_Cuisine Jan 13 20:13:18 volumiocuisine sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine systemd[1]: Stopping Music Player Daemon... Jan 13 20:13:18 volumiocuisine sudo[1262]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 13 20:13:18 volumiocuisine volumio[900]: error: Hi Res Audio Failed Login: Missing Login Data Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jan 13 20:13:18 volumiocuisine volumio[900]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 13 20:13:18 volumiocuisine volumio[900]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 13 20:13:18 volumiocuisine volumio[900]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding TIDAL REST API Endpoints Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 13 20:13:18 volumiocuisine volumio[900]: info: Stopping AccessToken refresher cron for QOBUZ Jan 13 20:13:18 volumiocuisine sudo[1275]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine sudo[1275]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 13 20:13:18 volumiocuisine sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine volumio[900]: info: AccessToken refresher cron started for QOBUZ Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding QOBUZ REST API Endpoints Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 13 20:13:18 volumiocuisine sudo[1275]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:18 volumiocuisine systemd[1]: mpd.service: Succeeded. Jan 13 20:13:18 volumiocuisine systemd[1]: Stopped Music Player Daemon. Jan 13 20:13:18 volumiocuisine volumio[900]: info: Successfully Added MyVolumio device Jan 13 20:13:18 volumiocuisine volumio[900]: info: Successfully Added MyVolumio device Jan 13 20:13:18 volumiocuisine volumio[900]: info: Bluetooth name changed to Volumio_Cuisine Jan 13 20:13:18 volumiocuisine volumio[900]: info: MPD Permissions set Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine sudo[1277]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine sudo[1277]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 13 20:13:18 volumiocuisine sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine systemd[1]: Starting Music Player Daemon... Jan 13 20:13:18 volumiocuisine systemd[1]: Started Volumio Qobuz Connect Service. Jan 13 20:13:18 volumiocuisine sudo[1277]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:18 volumiocuisine volumio[900]: [Metrics] CommandRouter: 34s 507.19ms Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::volumiosetStartupVolume Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: VolumeController:: Setting startup Volume 25 Jan 13 20:13:18 volumiocuisine volumio[900]: info: VolumeController::SetAlsaVolume25 Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::Close All Modals sent Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::Close All Modals sent Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreStateMachine::pushState Jan 13 20:13:18 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioPushState Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output update for this device Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:18 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:18 volumiocuisine sudo[1278]: root : unable to resolve host volumiocuisine Jan 13 20:13:18 volumiocuisine sudo[1278]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:18 volumiocuisine sudo[1278]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:18 volumiocuisine sudo[1278]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 20:13:18 volumiocuisine sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:18 volumiocuisine sudo[1278]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Found cast device: SHIELD-Android-TV-99102863ff57d3e7830817586c68b7f2 Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding audio output: Jan 13 20:13:18 volumiocuisine volumio[900]: info: Executing endpoint qc_getconfig Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 13 20:13:18 volumiocuisine qobuz-connect[1285]: 20260113 20:13:18.875 [1285.1285] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 13 20:13:18 volumiocuisine volumio[900]: info: Updating MyVolumio device info Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: Updating MyVolumio device info Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:18 volumiocuisine volumio[900]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 13 20:13:18 volumiocuisine volumio[900]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 13 20:13:18 volumiocuisine volumio[900]: info: MRS: Found cast device: BRAVIA-4K-GB-ATV3-2ec70930c6e3eacbcacb61c1caee0eeb Jan 13 20:13:18 volumiocuisine volumio[900]: info: Adding audio output: Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.060 [1285.1285] INFO VolumeManager: [0x175ba48]: Setting new playback volume: 75 Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.061 [1285.1285] INFO VolumeManager: [0x175ba48]: Setting new mute state: 0 Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.061 [1285.1285] INFO QobuzConnect: [0x175c418]: Client initialized! Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.061 [1285.1285] INFO SampleApp: Starting Avahi advertising, name: Volumio_Cuisine, service name: _qobuz-connect._tcp Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.071 [1285.1285] INFO LocalConfigManager: [0x175b328]: Starting Local Configuration server Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.071 [1285.1285] INFO SampleApp: Starting Local configuration server Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.071 [1285.1285] INFO SampleApp: Playback volume changed: 75 Jan 13 20:13:19 volumiocuisine qobuz-connect[1285]: 20260113 20:13:19.072 [1285.1285] INFO SampleApp: Connected to UNIX socket client 0x1750f60 Jan 13 20:13:19 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:19 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:19 volumiocuisine volumio[900]: info: Successfully Updated MyVolumio device Jan 13 20:13:19 volumiocuisine sudo[1301]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:19 volumiocuisine sudo[1301]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:19 volumiocuisine sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 13 20:13:19 volumiocuisine sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:19 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 13 20:13:19 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 13 20:13:19 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 13 20:13:19 volumiocuisine systemd[1]: Started MyVolumio SSH Tunnel. Jan 13 20:13:19 volumiocuisine sudo[1301]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:19 volumiocuisine volumio[900]: info: Remote SSH Started Jan 13 20:13:19 volumiocuisine autossh[1304]: port set to 0, monitoring disabled Jan 13 20:13:19 volumiocuisine autossh[1304]: starting ssh (count 1) Jan 13 20:13:19 volumiocuisine autossh[1304]: ssh child pid is 1307 Jan 13 20:13:19 volumiocuisine volumiossh-tunnel[1303]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Jan 13 20:13:19 volumiocuisine volumio[900]: info: Successfully Updated MyVolumio device Jan 13 20:13:19 volumiocuisine volumio[900]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 13 20:13:20 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:20 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:20 volumiocuisine mpd[1291]: Jan 13 20:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 20:13:20 volumiocuisine systemd[1]: Started Music Player Daemon. Jan 13 20:13:20 volumiocuisine sudo[1245]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:20 volumiocuisine volumio[900]: error: updateQueue error: null Jan 13 20:13:21 volumiocuisine sudo[1311]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:21 volumiocuisine sudo[1311]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:21 volumiocuisine sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 13 20:13:21 volumiocuisine sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:21 volumiocuisine systemd[1]: Started Volumio Bluetooth Module. Jan 13 20:13:21 volumiocuisine sudo[1311]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:21 volumiocuisine volumio[900]: info: Volumio BT Module successfully started Jan 13 20:13:21 volumiocuisine sudo[1314]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:21 volumiocuisine sudo[1314]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:21 volumiocuisine volumiobt[1313]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:21 volumiocuisine sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 13 20:13:21 volumiocuisine sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:21 volumiocuisine volumiobt[1313]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 13 20:13:21 volumiocuisine sudo[1314]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:22 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 20:13:22 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 20:13:22 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 20:13:22 volumiocuisine volumio-remote-updater[597]: No test mode Jan 13 20:13:22 volumiocuisine volumio-remote-updater[597]: No alpha test mode Jan 13 20:13:22 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 13 20:13:22 volumiocuisine volumio[900]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 13 20:13:22 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 13 20:13:22 volumiocuisine sudo[1320]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:22 volumiocuisine sudo[1320]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:22 volumiocuisine volumiobt[1313]: sudo: unable to resolve host volumiocuisine: System error Jan 13 20:13:22 volumiocuisine sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 13 20:13:22 volumiocuisine sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:22 volumiocuisine volumiobt[1313]: pulseaudio: no process found Jan 13 20:13:22 volumiocuisine sudo[1320]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:22 volumiocuisine dbus-daemon[580]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1324 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 13 20:13:22 volumiocuisine systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 13 20:13:22 volumiocuisine volumio[900]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 20:13:22 volumiocuisine dbus-daemon[580]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 13 20:13:22 volumiocuisine systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Successfully called chroot. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Successfully dropped privileges. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Successfully limited resources. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Running. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Watchdog thread running. Jan 13 20:13:22 volumiocuisine rtkit-daemon[1325]: Canary thread running. Jan 13 20:13:22 volumiocuisine dbus-daemon[580]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1325 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 13 20:13:22 volumiocuisine systemd[1]: Starting Authorization Manager... Jan 13 20:13:22 volumiocuisine volumio[900]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 20:13:22 volumiocuisine polkitd[1329]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 13 20:13:22 volumiocuisine dbus-daemon[580]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 13 20:13:22 volumiocuisine systemd[1]: Started Authorization Manager. Jan 13 20:13:23 volumiocuisine pulseaudio[1324]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 13 20:13:23 volumiocuisine pulseaudio[1324]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Applying permissions Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Setting BT discoverable and pairable Jan 13 20:13:23 volumiocuisine bluetoothd[784]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Jan 13 20:13:23 volumiocuisine bluetoothd[784]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Jan 13 20:13:23 volumiocuisine pulseaudio[1324]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 13 20:13:23 volumiocuisine kernel: Bluetooth: RFCOMM TTY layer initialized Jan 13 20:13:23 volumiocuisine kernel: Bluetooth: RFCOMM socket layer initialized Jan 13 20:13:23 volumiocuisine kernel: Bluetooth: RFCOMM ver 1.11 Jan 13 20:13:23 volumiocuisine volumio[900]: info: MRS: Getting audio outputs on start Jan 13 20:13:23 volumiocuisine volumio[900]: info: MRS: Requesting all other devices output Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [96B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [77B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [77B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [78B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [33B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [95B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [79B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [55B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> -e power on Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Invalid command in menu main: -e Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Use "help" for a list of available commands in a menu. Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Use "menu " if you want to enter any submenu. Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Use "back" if you want to return to menu main. Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [103B blob data] Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> agent on Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Agent is already registered Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> discoverable on Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> pairable on Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> agent NoInputNoOutput Jan 13 20:13:23 volumiocuisine volumiobt[1313]: Agent is already registered Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> default-agent Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [bluetoothctl]> quit Jan 13 20:13:23 volumiocuisine volumiobt[1313]: [65B blob data] Jan 13 20:13:23 volumiocuisine sudo[1340]: volumio : unable to resolve host volumiocuisine Jan 13 20:13:23 volumiocuisine sudo[1340]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:13:23 volumiocuisine sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 20:13:23 volumiocuisine sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:13:23 volumiocuisine systemd[1]: Stopping MPD Monitor Service... Jan 13 20:13:23 volumiocuisine systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 13 20:13:23 volumiocuisine systemd[1]: mpd_monitor.service: Succeeded. Jan 13 20:13:23 volumiocuisine systemd[1]: Stopped MPD Monitor Service. Jan 13 20:13:23 volumiocuisine volumio[900]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 20:13:23 volumiocuisine volumio[900]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 13 20:13:23 volumiocuisine systemd[1]: Started MPD Monitor Service. Jan 13 20:13:23 volumiocuisine mpd_monitor.sh[1342]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 20:13:23 volumiocuisine sudo[1340]: pam_unix(sudo:session): session closed for user root Jan 13 20:13:23 volumiocuisine volumio[900]: info: Successfully started MPD Monitor Jan 13 20:13:25 volumiocuisine volumio[900]: info: BOOT COMPLETED Jan 13 20:13:26 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 20:13:26 volumiocuisine volumio[900]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 20:13:26 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:26 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:26 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:26 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:28 volumiocuisine volumio[900]: info: MRS: Adding multiroomSync output Jan 13 20:13:28 volumiocuisine volumio[900]: info: Adding audio output: Jan 13 20:13:28 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output Jan 13 20:13:30 volumiocuisine volumio[900]: info: Discovery: Disconnected from remote: 192.168.0.9 Jan 13 20:13:30 volumiocuisine volumio[900]: info: MRS: Cleaning up device: e64cb167-3154-42fe-8132-fa0a729a4296 Jan 13 20:13:30 volumiocuisine volumio[900]: info: Removing audio output: Jan 13 20:13:30 volumiocuisine volumio[900]: info: Discovery: Remote connection error: 192.168.0.9 Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetBrowseSources Jan 13 20:13:31 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 20:13:31 volumiocuisine volumio[900]: info: Discovery: A device disappeared from network Jan 13 20:13:36 volumiocuisine volumio[900]: info: Discovery: adding e64cb167-3154-42fe-8132-fa0a729a4296 Jan 13 20:13:36 volumiocuisine volumio[900]: info: Discovery: Found device Volumio_Salon Jan 13 20:13:36 volumiocuisine volumio[900]: info: Discovery: Connecting to remote: 192.168.0.9 Jan 13 20:13:36 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:36 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:36 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:13:36 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:13:37 volumiocuisine volumio[900]: info: MRS: Pushing audio output on request Jan 13 20:13:37 volumiocuisine volumio[900]: info: MRS: Pushing multiroomSync output Jan 13 20:13:37 volumiocuisine volumio[900]: info: Discovery: Connected to remote: 192.168.0.9 Jan 13 20:13:37 volumiocuisine volumio[900]: verbose: New Socket.io Connection to 192.168.0.58:3000 from 192.168.0.9 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 20:13:37 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 13 20:13:38 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:38 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:43 volumiocuisine ntpd[844]: Soliciting pool server 195.35.113.80 Jan 13 20:13:44 volumiocuisine ntpd[844]: Soliciting pool server 185.51.192.61 Jan 13 20:13:45 volumiocuisine ntpd[844]: Soliciting pool server 164.92.156.55 Jan 13 20:13:45 volumiocuisine ntpd[844]: Soliciting pool server 193.36.132.241 Jan 13 20:13:46 volumiocuisine ntpd[844]: Soliciting pool server 185.51.192.63 Jan 13 20:13:46 volumiocuisine ntpd[844]: Soliciting pool server 162.159.200.123 Jan 13 20:13:46 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:46 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:47 volumiocuisine ntpd[844]: Soliciting pool server 149.143.87.22 Jan 13 20:13:47 volumiocuisine ntpd[844]: Soliciting pool server 158.101.216.150 Jan 13 20:13:48 volumiocuisine ntpd[844]: Soliciting pool server 185.71.60.123 Jan 13 20:13:48 volumiocuisine ntpd[844]: Soliciting pool server 193.148.248.213 Jan 13 20:13:49 volumiocuisine ntpd[844]: Soliciting pool server 45.138.55.62 Jan 13 20:13:49 volumiocuisine ntpd[844]: Soliciting pool server 178.239.19.56 Jan 13 20:13:50 volumiocuisine ntpd[844]: Soliciting pool server 45.83.205.227 Jan 13 20:13:50 volumiocuisine ntpd[844]: Soliciting pool server 45.138.55.60 Jan 13 20:13:51 volumiocuisine ntpd[844]: Soliciting pool server 103.204.195.5 Jan 13 20:13:51 volumiocuisine ntpd[844]: Soliciting pool server 2001:4c3c:0:74::1:100 Jan 13 20:13:52 volumiocuisine ntpd[844]: receive: Unexpected origin timestamp 0xed1117ef.8a6c55ca does not match aorg 0000000000.00000000 from server@193.148.248.213 xmt 0xed1117f0.a590df7d Jan 13 20:13:52 volumiocuisine ntpd[844]: receive: Unexpected origin timestamp 0xed1117ef.8a6aadd7 does not match aorg 0000000000.00000000 from server@45.83.205.227 xmt 0xed1117f0.a5b52258 Jan 13 20:13:52 volumiocuisine ntpd[844]: receive: Unexpected origin timestamp 0xed1117ef.8a651d60 does not match aorg 0000000000.00000000 from server@45.138.55.60 xmt 0xed1117f0.a5e51bb1 Jan 13 20:13:58 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:13:58 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:13:58 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:13:58 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:14:06 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 20:14:06 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 20:14:08 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:14:08 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:14:09 volumiocuisine volumio[900]: info: Enabling plugin spop Jan 13 20:14:09 volumiocuisine volumio[900]: info: Loading plugin "spop"... Jan 13 20:14:11 volumiocuisine volumio[900]: info: PLUGIN START: spop Jan 13 20:14:11 volumiocuisine volumio[900]: info: Creating Spotify config file Jan 13 20:14:11 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 20:14:11 volumiocuisine volumio[900]: info: Done. Jan 13 20:14:11 volumiocuisine volumio[900]: info: Spotify config file written Jan 13 20:14:11 volumiocuisine volumio[900]: info: No need to fix Spotify hosts Jan 13 20:14:11 volumiocuisine sudo[1462]: volumio : unable to resolve host volumiocuisine Jan 13 20:14:11 volumiocuisine sudo[1462]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:14:11 volumiocuisine sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 20:14:11 volumiocuisine sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 20:14:11 volumiocuisine volumio[900]: info: New Spotify access tokenBQDEnkya9X... Jan 13 20:14:11 volumiocuisine volumio[900]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 20:14:11 volumiocuisine systemd[1]: Started go-librespot Daemon. Jan 13 20:14:11 volumiocuisine go-librespot[1464]: go-librespot daemon starting... Jan 13 20:14:11 volumiocuisine sudo[1462]: pam_unix(sudo:session): session closed for user root Jan 13 20:14:11 volumiocuisine volumio[900]: SPOTIFY: User informations: {"country":"BE","display_name":"Michael Parisi","email":"postmaster@parisi.be","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/meneomic"},"followers":{"href":null,"total":12},"href":"https://api.spotify.com/v1/users/meneomic","id":"meneomic","images":[{"height":300,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/278670811_10159927667882748_2732031877764499671_n.jpg?stp=c11.0.937.937a_dst-jpg_s320x320_tt6&_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=_Q56QZaSR5sQ7kNvwEXrG1w&_nc_oc=AdlpmXyMz6cBc1U1X77Mk65wwhncv--Enf5jGEtl112mGMM-cfdWaemueceSWzGOyLYZJyjsJtw539ckhR4g9SX9&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=TRE4lm648ggpUullwkAktA&_nc_tpa=Q5bMBQFhBLtRPZ6QQ3YHqKeatb9WEpqackemD53CudDnpx8M5OhrIsxS9Uqic6GuuKyqG6MK6ARA&oh=00_AfrMAyYx1Srs70X2PwVOrNJkjyMH6mGVLzYIOuN0plnHCw&oe=696C78A9","width":300},{"height":64,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/278670811_10159927667882748_2732031877764499671_n.jpg?stp=c11.0.937.937a_cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=_Q56QZaSR5sQ7kNvwEXrG1w&_nc_oc=AdlpmXyMz6cBc1U1X77Mk65wwhncv--Enf5jGEtl112mGMM-cfdWaemueceSWzGOyLYZJyjsJtw539ckhR4g9SX9&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=TRE4lm648ggpUullwkAktA&_nc_tpa=Q5bMBQGE_c98ZmbkDRsV1EbJMBUEdZJt1r6tEldHcQosHWa1kqoy2tHzJEJnMijKJpSDpSfh7EpD&oh=00_AfqfcyK0SLckSvD-z2PdC2E2-3PnMnH0n5pAdiBR7CIW6w&oe=696C78A9","width":64}],"product":"premium","type":"user","uri":"spotify:user:meneomic"} Jan 13 20:14:11 volumiocuisine volumio[900]: info: Spotify Successfully logged in Jan 13 20:14:11 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 20:14:11 volumiocuisine volumio[900]: info: [1768331651872] CoreMusicLibrary::Adding element Spotify Jan 13 20:14:11 volumiocuisine volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 20:14:11 volumiocuisine volumio[900]: Cannot find translation for source Spotify Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=info msg="running go-librespot 0.6.2" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="app state loaded" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=info msg="zeroconf server listening on port 45781" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="obtained new client token: AAA3wHJbXqz3uE4xof9OvuxaD1m6h1AE1eLFIsKSD93iE8Rfmrm0B/wTAnW9RpRrbAmRTSemnuXSjgmvaWjoGbwV2GP5O1KlJ3ABHvqItf3Zotw+d9g8pVWn7K0drM6/UcdeCgyndvGSaQc3dqml0NpSGMVbhCesh1KKgRbegVUxku2o7t4KW3W3TIFpr/2KU/FbJBaiCMYC6h07Eq6JLzq2rmBhKd36Qy42AHjbWntfAyr6hG9VxBc=" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 20:14:13 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:13+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.58:51258->34.158.1.133:4070: read: connection reset by peer" Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.58:45122->34.158.1.133:443: read: connection reset by peer" Jan 13 20:14:14 volumiocuisine volumio[900]: info: go-librespot daemon successfully initialized Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=debug msg="completed keyexchange" Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=debug msg="completed challenge" Jan 13 20:14:14 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:14+01:00" level=info msg="authenticated AP" username="me****ic" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=info msg="authenticated Login5" username="me****ic" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="initializing zeroconf session" username="me****ic" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="dealer connection opened" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=trace msg="starting accesspoint recv loop" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=trace msg="starting dealer recv loop" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=trace msg="received accesspoint ping" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="received connection id: ZTE2MTc3YmEtNTE2...RjkxMjIyNDlEQw==" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=trace msg="received accesspoint pong ack" Jan 13 20:14:15 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:15+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="handling transfer player command from a6e455d59960c05dcc94d7ee72d1ca65cbdab001" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="resolved context of track" uri="spotify:artist:5DW7NxqQ2875JtjuV7KMn4" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=trace msg="fetched new page 0 with 35 items (list: 35)" uri="spotify:artist:5DW7NxqQ2875JtjuV7KMn4" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=trace msg="emitting websocket event: will_play" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2244" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="selected format OGG_VORBIS_320 (52792107a2b603dccffb8b18fc7c88a1cc5e9f06)" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=debug msg="requested aes key for file 52792107a2b603dccffb8b18fc7c88a1cc5e9f06, gid: 6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:16 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:16+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="fetched first chunk of 17, total size is 8621000 bytes" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="created new output device" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=info msg="loaded track \"DIEU EST GRANDE\" (paused: true, position: 0ms, duration: 176933ms, prefetched: false)" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=trace msg="emitting websocket event: metadata" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=trace msg="emitting websocket event: active" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="sending successful reply for dealer request" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=trace msg="emitting websocket event: paused" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:17 volumiocuisine volumio[900]: info: Initializing connection to go-librespot Websocket Jan 13 20:14:17 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:17+01:00" level=debug msg="new websocket client" Jan 13 20:14:17 volumiocuisine volumio[900]: info: Connection to go-librespot Websocket established Jan 13 20:14:18 volumiocuisine volumio[900]: info: CoreCommandRouter::volumioGetState Jan 13 20:14:18 volumiocuisine volumio[900]: info: CorePlayQueue::getTrack 0 Jan 13 20:14:18 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:14:18 volumiocuisine volumio[900]: info: Listing playlists Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="handling resume player command from a6e455d59960c05dcc94d7ee72d1ca65cbdab001" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6MX7BlutD1ixkG8pnIXJuy" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="resume track at 0ms" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=trace msg="scheduling prefetch in 147s" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="sending successful reply for dealer request" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 20:14:18 volumiocuisine go-librespot[1464]: time="2026-01-13T20:14:18+01:00" level=trace msg="emitting websocket event: playing" Jan 13 20:14:18 volumiocuisine volumio[900]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:artist:5DW7NxqQ2875JtjuV7KMn4","uri":"spotify:track:6MX7BlutD1ixkG8pnIXJuy","resume":true,"play_origin":"artist"}} Jan 13 20:14:18 volumiocuisine volumio[900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 20:14:18 volumiocuisine volumio[900]: TypeError: Cannot read property 'service' of undefined Jan 13 20:14:18 volumiocuisine volumio[900]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Jan 13 20:14:18 volumiocuisine volumio[900]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Jan 13 20:14:18 volumiocuisine volumio[900]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Jan 13 20:14:18 volumiocuisine volumio[900]: at WebSocket.emit (events.js:315:20) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver.emit (events.js:315:20) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jan 13 20:14:18 volumiocuisine volumio[900]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jan 13 20:14:18 volumiocuisine volumio[900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 20:14:19 volumiocuisine sudo[1498]: volumio : unable to resolve host volumiocuisine Jan 13 20:14:19 volumiocuisine sudo[1498]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 13 20:14:19 volumiocuisine sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 20:13 Jan 13 20:14:19 volumiocuisine sudo[1498]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"