-- Logs begin at Sun 2025-05-18 08:55:10 CEST, end at Sun 2025-05-18 09:05:51 CEST. -- May 18 09:04:00 veranda volumio[857]: SPOTIFY: SETTING SPOTIFY VOLUME 10 May 18 09:04:00 veranda volumio[857]: info: Sending Spotify command with payload to local API: /player/volume May 18 09:04:00 veranda go-librespot[4192]: time="2025-05-18T09:04:00+02:00" level=debug msg="update volume to 6553/65535" May 18 09:04:01 veranda go-librespot[4192]: time="2025-05-18T09:04:01+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 18 09:04:01 veranda go-librespot[4192]: time="2025-05-18T09:04:01+02:00" level=trace msg="emitting websocket event: volume" May 18 09:04:01 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} May 18 09:04:01 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 May 18 09:04:01 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} May 18 09:04:01 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 May 18 09:04:01 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} May 18 09:04:01 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:01 veranda volumio[857]: info: Executing endpoint metavolumio May 18 09:04:01 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 09:04:03 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:04:07 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:04:09 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:11 veranda volumio[857]: info: May 18 09:04:11 veranda volumio[857]: ---------------------------- Client requests Volume 53 May 18 09:04:11 veranda volumio[857]: info: VolumeController::SetAlsaVolume53 May 18 09:04:11 veranda volumio[857]: info: CoreStateMachine::pushState May 18 09:04:11 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 09:04:11 veranda volumio[857]: info: CoreCommandRouter::volumioPushState May 18 09:04:11 veranda volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 18 09:04:11 veranda volumio[857]: info: MRS: Pushing multiroomSync output May 18 09:04:11 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:11 veranda volumio[857]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 18 09:04:11 veranda volumio[857]: SPOTIFY: SPOTIFY VOLUME 10 May 18 09:04:11 veranda volumio[857]: SPOTIFY: VOLUMIO VOLUME 53 May 18 09:04:11 veranda volumio[857]: SPOTIFY: DELTA VOLUME ENOUGH: true May 18 09:04:11 veranda volumio[857]: info: Setting Spotify Volume from Volumio: 53 May 18 09:04:11 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:13 veranda volumio[857]: SPOTIFY: SETTING SPOTIFY VOLUME 53 May 18 09:04:13 veranda volumio[857]: info: Sending Spotify command with payload to local API: /player/volume May 18 09:04:13 veranda go-librespot[4192]: time="2025-05-18T09:04:13+02:00" level=debug msg="update volume to 34733/65535" May 18 09:04:14 veranda volumio[857]: info: May 18 09:04:14 veranda volumio[857]: ---------------------------- Client requests Volume 13 May 18 09:04:14 veranda volumio[857]: info: VolumeController::SetAlsaVolume13 May 18 09:04:14 veranda volumio[857]: info: CoreStateMachine::pushState May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::volumioPushState May 18 09:04:14 veranda volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 18 09:04:14 veranda volumio[857]: info: MRS: Pushing multiroomSync output May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:14 veranda volumio[857]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 May 18 09:04:14 veranda volumio[857]: SPOTIFY: SPOTIFY VOLUME 53 May 18 09:04:14 veranda volumio[857]: SPOTIFY: VOLUMIO VOLUME 13 May 18 09:04:14 veranda volumio[857]: SPOTIFY: DELTA VOLUME ENOUGH: true May 18 09:04:14 veranda volumio[857]: info: Setting Spotify Volume from Volumio: 13 May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:14 veranda go-librespot[4192]: time="2025-05-18T09:04:14+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 18 09:04:14 veranda go-librespot[4192]: time="2025-05-18T09:04:14+02:00" level=trace msg="emitting websocket event: volume" May 18 09:04:14 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":53,"max":100}} May 18 09:04:14 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 53 May 18 09:04:14 veranda volumio[857]: info: Setting Volumio Volume from Spotify: 53 May 18 09:04:14 veranda volumio[857]: info: VolumeController::SetAlsaVolume53 May 18 09:04:14 veranda volumio[857]: info: CoreStateMachine::pushState May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::volumioPushState May 18 09:04:14 veranda volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 18 09:04:14 veranda volumio[857]: info: MRS: Pushing multiroomSync output May 18 09:04:14 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:14 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":53,"max":100}} May 18 09:04:14 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 53 May 18 09:04:14 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":53,"max":100}} May 18 09:04:14 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 53 May 18 09:04:14 veranda volumio[857]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 18 09:04:15 veranda volumio[857]: SPOTIFY: SETTING SPOTIFY VOLUME 13 May 18 09:04:15 veranda volumio[857]: info: Sending Spotify command with payload to local API: /player/volume May 18 09:04:15 veranda go-librespot[4192]: time="2025-05-18T09:04:15+02:00" level=debug msg="update volume to 8519/65535" May 18 09:04:16 veranda go-librespot[4192]: time="2025-05-18T09:04:16+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 18 09:04:16 veranda go-librespot[4192]: time="2025-05-18T09:04:16+02:00" level=trace msg="emitting websocket event: volume" May 18 09:04:16 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":13,"max":100}} May 18 09:04:16 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 13 May 18 09:04:16 veranda volumio[857]: info: Setting Volumio Volume from Spotify: 13 May 18 09:04:16 veranda volumio[857]: info: VolumeController::SetAlsaVolume13 May 18 09:04:16 veranda volumio[857]: info: CoreStateMachine::pushState May 18 09:04:16 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 09:04:16 veranda volumio[857]: info: CoreCommandRouter::volumioPushState May 18 09:04:16 veranda volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 18 09:04:16 veranda volumio[857]: info: MRS: Pushing multiroomSync output May 18 09:04:16 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:16 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":13,"max":100}} May 18 09:04:16 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 13 May 18 09:04:16 veranda volumio[857]: SPOTIFY: received: {"type":"volume","data":{"value":13,"max":100}} May 18 09:04:16 veranda volumio[857]: SPOTIFY: RECEIVED SPOTIFY VOLUME 13 May 18 09:04:16 veranda volumio[857]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 May 18 09:04:19 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:19 veranda volumio[857]: info: Listing playlists May 18 09:04:19 veranda volumio[857]: info: Listing playlists May 18 09:04:22 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:04:24 veranda go-librespot[4192]: time="2025-05-18T09:04:24+02:00" level=debug msg="update volume to 9830/65535" May 18 09:04:24 veranda go-librespot[4192]: time="2025-05-18T09:04:24+02:00" level=debug msg="update volume to 17039/65535" May 18 09:04:24 veranda go-librespot[4192]: time="2025-05-18T09:04:24+02:00" level=debug msg="update volume to 22937/65535" May 18 09:04:25 veranda go-librespot[4192]: time="2025-05-18T09:04:25+02:00" level=debug msg="update volume to 26869/65535" May 18 09:04:25 veranda go-librespot[4192]: time="2025-05-18T09:04:25+02:00" level=debug msg="update volume to 28180/65535" May 18 09:04:28 veranda go-librespot[4192]: time="2025-05-18T09:04:28+02:00" level=trace msg="sent dealer ping" May 18 09:04:28 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:04:29 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:32 veranda go-librespot[4192]: time="2025-05-18T09:04:32+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 18 09:04:32 veranda go-librespot[4192]: time="2025-05-18T09:04:32+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1114" May 18 09:04:33 veranda volumio[857]: info: MRS: Updating multiroomSync output May 18 09:04:34 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:04:39 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:04:39 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:39 veranda volumio[857]: info: Listing playlists May 18 09:04:39 veranda volumio[857]: info: Listing playlists May 18 09:04:43 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:04:49 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:49 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:04:51 veranda volumio[857]: info: MRS: Updating multiroomSync output May 18 09:04:53 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:04:54 veranda volumio[857]: info: MRS: Updating multiroomSync output May 18 09:04:57 veranda volumio[857]: info: MRS: Updating multiroomSync output May 18 09:04:58 veranda go-librespot[4192]: time="2025-05-18T09:04:58+02:00" level=trace msg="received accesspoint ping" May 18 09:04:58 veranda go-librespot[4192]: time="2025-05-18T09:04:58+02:00" level=trace msg="received accesspoint pong ack" May 18 09:04:58 veranda volumio[857]: info: MRS: Updating multiroomSync output May 18 09:04:58 veranda go-librespot[4192]: time="2025-05-18T09:04:58+02:00" level=error msg="did not receive last pong from dealer, 60s passed" May 18 09:04:59 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:04:59 veranda volumio[857]: info: Listing playlists May 18 09:04:59 veranda volumio[857]: info: Listing playlists May 18 09:04:59 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:04 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:05:08 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:09 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:14 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:05:18 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:19 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:19 veranda volumio[857]: info: Listing playlists May 18 09:05:19 veranda volumio[857]: info: Listing playlists May 18 09:05:24 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:05:28 veranda go-librespot[4192]: time="2025-05-18T09:05:28+02:00" level=error msg="did not receive last pong from dealer, 90s passed" May 18 09:05:29 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:29 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:35 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:05:39 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:39 veranda volumio[857]: info: Listing playlists May 18 09:05:39 veranda volumio[857]: info: Listing playlists May 18 09:05:39 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:43 veranda kernel: hwmon hwmon1: Voltage normalised May 18 09:05:45 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 09:05:45 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 18 09:05:49 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:49 veranda volumio[857]: info: Enabling plugin fusiondsp May 18 09:05:49 veranda volumio[857]: info: Loading plugin "fusiondsp"... May 18 09:05:49 veranda volumio[857]: info: Applying required configuration parameters for plugin fusiondsp May 18 09:05:49 veranda volumio[857]: info: Preparing to generate the ALSA configuration file May 18 09:05:49 veranda volumio[857]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 18 09:05:49 veranda volumio[857]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 09:05:49 veranda volumio[857]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 18 09:05:49 veranda volumio[857]: info: Reading ALSA contributions from plugins. May 18 09:05:49 veranda volumio[857]: info: Asound.conf file unchanged, so no further update is needed May 18 09:05:49 veranda volumio[857]: info: Output device has changed, restarting MPD May 18 09:05:49 veranda kernel: hwmon hwmon1: Undervoltage detected! May 18 09:05:49 veranda sudo[4678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 09:05:49 veranda sudo[4678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:49 veranda sudo[4678]: pam_unix(sudo:session): session closed for user root May 18 09:05:49 veranda volumio[857]: info: Output device has changed, restarting Shairport Sync May 18 09:05:49 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:49 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 09:05:49 veranda sudo[4682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 09:05:49 veranda sudo[4682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:49 veranda systemd[1]: Stopping Music Player Daemon... May 18 09:05:49 veranda volumio[857]: xcb_connection_has_error() returned true May 18 09:05:49 veranda volumio[857]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 09:05:49 veranda volumio[857]: xcb_connection_has_error() returned true May 18 09:05:49 veranda systemd[1]: mpd.service: Succeeded. May 18 09:05:49 veranda systemd[1]: Stopped Music Player Daemon. May 18 09:05:50 veranda volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: QobuzConnect: setDeactiveState invoked May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:50 veranda vtcs[3316]: [2025-05-18 09:05:50.049] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 18 09:05:50 veranda vtcs[3316]: [2025-05-18 09:05:50.058] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=5 errorno=104 error=Connection reset by peer May 18 09:05:50 veranda vtcs[3316]: [2025-05-18 09:05:50.058] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 18 09:05:50 veranda systemd[1]: Starting Music Player Daemon... May 18 09:05:50 veranda volumio[857]: info: PLUGIN START: fusiondsp May 18 09:05:50 veranda volumio[857]: info: Loading i18n strings for locale nl May 18 09:05:50 veranda sudo[4692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 09:05:50 veranda sudo[4692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: FusionDsp - mixtype--------------------- Software May 18 09:05:50 veranda volumio[857]: info: Preparing to generate the ALSA configuration file May 18 09:05:50 veranda systemd[1]: Stopping Volumio Tidal Connect Service... May 18 09:05:50 veranda systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 18 09:05:50 veranda systemd[1]: vtcs.service: Succeeded. May 18 09:05:50 veranda systemd[1]: Stopped Volumio Tidal Connect Service. May 18 09:05:50 veranda volumio[857]: info: Done. May 18 09:05:50 veranda sudo[4692]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda sudo[4696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 09:05:50 veranda sudo[4696]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda sudo[4687]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 09:05:50 veranda sudo[4687]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda sudo[4687]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda volumio[857]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 18 09:05:50 veranda volumio[857]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 09:05:50 veranda volumio[857]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 18 09:05:50 veranda volumio[857]: info: Reading ALSA contributions from plugins. May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:50 veranda sudo[4704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 09:05:50 veranda sudo[4704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:50 veranda volumio[857]: info: MPD Permissions set May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda sudo[4696]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda sudo[4704]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 09:05:50 veranda sudo[4708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 09:05:50 veranda sudo[4708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: Starting Shairport Sync May 18 09:05:50 veranda systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 09:05:50 veranda qobuz-connect[3288]: 20250518 09:05:50.489 [3288.3288] INFO SampleApp: Stopping Local configuration server May 18 09:05:50 veranda qobuz-connect[3288]: 20250518 09:05:50.500 [3288.3288] INFO SampleApp: shat down connection on UNIX socket May 18 09:05:50 veranda systemd[1]: qobuz-connect.service: Succeeded. May 18 09:05:50 veranda systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:50 veranda volumio[857]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 18 09:05:50 veranda volumio[857]: info: QobuzConnect: setDeactiveState invoked May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:50 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 09:05:50 veranda volumio[857]: info: Asound.conf file unchanged, so no further update is needed May 18 09:05:50 veranda volumio[857]: info: Output device has changed, restarting MPD May 18 09:05:50 veranda sudo[4714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 09:05:50 veranda sudo[4714]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda systemd[1]: Started Volumio Qobuz Connect Service. May 18 09:05:50 veranda sudo[4708]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda sudo[4718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 09:05:50 veranda sudo[4718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 09:05:50 veranda systemd[1]: shairport-sync.service: Succeeded. May 18 09:05:50 veranda systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 09:05:50 veranda sudo[4718]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 09:05:50 veranda volumio[857]: info: Output device has changed, restarting Shairport Sync May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 09:05:50 veranda sudo[4714]: pam_unix(sudo:session): session closed for user root May 18 09:05:50 veranda sudo[4723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 09:05:50 veranda sudo[4723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 18 09:05:50 veranda systemd[1]: mpd.service: Succeeded. May 18 09:05:50 veranda systemd[1]: Stopped Music Player Daemon. May 18 09:05:50 veranda volumio[857]: xcb_connection_has_error() returned true May 18 09:05:50 veranda volumio[857]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 09:05:50 veranda volumio[857]: xcb_connection_has_error() returned true May 18 09:05:50 veranda volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 09:05:50 veranda systemd[1]: Starting Music Player Daemon... May 18 09:05:50 veranda volumio[857]: info: QobuzConnect: setDeactiveState invoked May 18 09:05:50 veranda volumio[857]: info: CoreCommandRouter::volumioGetState May 18 09:05:50 veranda sudo[4735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 09:05:50 veranda sudo[4735]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda sudo[4730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 09:05:50 veranda sudo[4730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:50 veranda sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 09:05:50 veranda sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:51 veranda sudo[4730]: pam_unix(sudo:session): session closed for user root May 18 09:05:51 veranda sudo[4746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 09:05:51 veranda sudo[4746]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:51 veranda sudo[4735]: pam_unix(sudo:session): session closed for user root May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 09:05:51 veranda sudo[4738]: pam_unix(sudo:session): session closed for user root May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 09:05:51 veranda volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 09:05:51 veranda sudo[4746]: pam_unix(sudo:session): session closed for user root May 18 09:05:51 veranda volumio[857]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 09:05:51 veranda volumio[857]: TypeError: Cannot read property 'off' of undefined May 18 09:05:51 veranda volumio[857]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 18 09:05:51 veranda volumio[857]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 18 09:05:51 veranda volumio[857]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 18 09:05:51 veranda volumio[857]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 18 09:05:51 veranda volumio[857]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 18 09:05:51 veranda volumio[857]: at Socket.emit (events.js:315:20) May 18 09:05:51 veranda volumio[857]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 18 09:05:51 veranda volumio[857]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 18 09:05:51 veranda volumio[857]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 09:05:51 veranda sudo[4750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 09:05:51 veranda sudo[4750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 09:05:51 veranda systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 09:05:51 veranda systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT May 18 09:05:51 veranda systemd[1]: qobuz-connect.service: Succeeded. May 18 09:05:51 veranda systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 09:05:51 veranda systemd[1]: Started Volumio Qobuz Connect Service. May 18 09:05:51 veranda sudo[4750]: pam_unix(sudo:session): session closed for user root May 18 09:05:51 veranda sudo[4765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 09:04 May 18 09:05:51 veranda sudo[4765]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"