-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2026-02-24 17:31:08 CET. -- Feb 24 17:30:14 minidsp-shd volumio[706]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:14 minidsp-shd volumio[706]: info: Volume configurations have been set Feb 24 17:30:14 minidsp-shd volumio[706]: info: QobuzConnect: setDeactiveState invoked Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:14 minidsp-shd volumio[706]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Feb 24 17:30:14 minidsp-shd volumio[706]: xcb_connection_has_error() returned true Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 17:30:14 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 17:30:15 minidsp-shd sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 17:30:15 minidsp-shd sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 17:30:15 minidsp-shd sudo[1979]: pam_unix(sudo:session): session closed for user root Feb 24 17:30:15 minidsp-shd sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 17:30:15 minidsp-shd sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 17:30:15 minidsp-shd systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 24 17:30:15 minidsp-shd qobuz-connect[1104]: 20260224 17:30:15.091 [1104.1104] INFO SampleApp: Stopping Local configuration server Feb 24 17:30:15 minidsp-shd volumio[706]: xcb_connection_has_error() returned true Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 17:30:15 minidsp-shd qobuz-connect[1104]: 20260224 17:30:15.102 [1104.1104] INFO SampleApp: shat down connection on UNIX socket Feb 24 17:30:15 minidsp-shd systemd[1]: qobuz-connect.service: Succeeded. Feb 24 17:30:15 minidsp-shd systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 24 17:30:15 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Feb 24 17:30:15 minidsp-shd sudo[1982]: pam_unix(sudo:session): session closed for user root Feb 24 17:30:15 minidsp-shd volumio[706]: xcb_connection_has_error() returned true Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Feb 24 17:30:15 minidsp-shd volumio[706]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 17:30:15 minidsp-shd volumio[706]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 17:30:15 minidsp-shd volumio[706]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 17:30:15 minidsp-shd volumio[706]: info: QobuzConnect: setDeactiveState invoked Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:15 minidsp-shd volumio[706]: info: Executing endpoint qc_getconfig Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.293 [1985.1985] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 17:30:15 minidsp-shd volumio[706]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 17:30:15 minidsp-shd volumio[706]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.301 [1985.1985] INFO VolumeManager: [0x16dd9d0]: Setting new playback volume: 75 Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.301 [1985.1985] INFO VolumeManager: [0x16dd9d0]: Setting new mute state: 0 Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.301 [1985.1985] INFO QobuzConnect: [0x16de3a0]: Client initialized! Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.301 [1985.1985] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.316 [1985.1985] INFO LocalConfigManager: [0x16dd2b0]: Starting Local Configuration server Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.316 [1985.1985] INFO SampleApp: Starting Local configuration server Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.317 [1985.1985] INFO SampleApp: Connected to UNIX socket client 0x16d2ed8 Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 17:30:15 minidsp-shd volumio[706]: info: Not Reporting Auto name since its the default one Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 24 17:30:15 minidsp-shd qobuz-connect[1985]: 20260224 17:30:15.443 [1985.1985] INFO SampleApp: Playback volume changed: 75 Feb 24 17:30:15 minidsp-shd volumio[706]: xcb_connection_has_error() returned true Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:15 minidsp-shd sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 24 17:30:15 minidsp-shd sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 17:30:15 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Feb 24 17:30:15 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 24 17:30:15 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Feb 24 17:30:15 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Feb 24 17:30:15 minidsp-shd systemd[1]: Started RAAT DAEMON. Feb 24 17:30:15 minidsp-shd sudo[2001]: pam_unix(sudo:session): session closed for user root Feb 24 17:30:15 minidsp-shd volumio[706]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 17:30:15 minidsp-shd volumio[706]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 17:30:15 minidsp-shd volumio[706]: info: Raat Daemon started successfully Feb 24 17:30:15 minidsp-shd volumio[706]: info: Executing endpoint restartRAATSocket Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 24 17:30:15 minidsp-shd volumio[706]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 24 17:30:15 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:26 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":55,"dbVolume":-57.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:26 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:26 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:26 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":60,"dbVolume":-51,"mute":false,"disableVolumeControl":false} Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:26 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:26 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:26 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:27 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":65,"dbVolume":-44.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:27 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:27 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:27 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:27 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:27 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:27 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:28 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":75,"dbVolume":-32,"mute":false,"disableVolumeControl":false} Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:28 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":80,"dbVolume":-25.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:28 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":85,"dbVolume":-19,"mute":false,"disableVolumeControl":false} Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:28 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":90,"dbVolume":-12.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:28 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:28 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:29 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":95,"dbVolume":-6.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:29 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":90,"dbVolume":-12.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:29 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":80,"dbVolume":-25.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:29 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:29 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":55,"dbVolume":-57.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:29 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:29 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:31 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":50,"dbVolume":-63.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:31 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":45,"dbVolume":-70,"mute":false,"disableVolumeControl":false} Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:31 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":40,"dbVolume":-76.5,"mute":false,"disableVolumeControl":false} Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:31 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:31 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:32 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Feb 24 17:30:32 minidsp-shd volumio[706]: info: msSurfaceDial volumioupdatevolume callback: {"vol":35,"dbVolume":-83,"mute":false,"disableVolumeControl":false} Feb 24 17:30:32 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:32 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:32 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:32 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:32 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:32 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioSeek Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::seek Feb 24 17:30:37 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:37 minidsp-shd volumio[706]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto; Rococo Variations","albumUri":"qobuz://album/0002894798092","albumart":"https://static.qobuz.com/images/covers/92/80/0002894798092_600.jpg","artist":"Nemanja Radulović","artistUri":"qobuz://artist/101858","audioQuality":"HI_RES","duration":1125,"explicit":false,"name":"I. Allegro moderato","service":"qobuz","title":"I. Allegro moderato","tracknumber":1,"type":"track","uri":"qobuz://song/42241262","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:30:37 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:37 minidsp-shd volumio[706]: info: [1771950637209] ControllerQobuz::seek Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 17:30:37 minidsp-shd volumio[706]: info: ControllerMpd::seek Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:37 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:37 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:37 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:37 minidsp-shd volumio[706]: info: getStreamUrl took 239 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954237&hmac=IlH00afN4Gs0eoplBZlVgRIK7rY Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=30593289- Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 342540102 Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:37 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:37 minidsp-shd volumio[706]: info: getStreamUrl took 202 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954237&hmac=IlH00afN4Gs0eoplBZlVgRIK7rY Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=30581183- Feb 24 17:30:37 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 342552208 Feb 24 17:30:37 minidsp-shd volumio[706]: error: null Feb 24 17:30:37 minidsp-shd volumio[706]: info: Feb 24 17:30:37 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:37 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:37 minidsp-shd volumio[706]: info: Feb 24 17:30:37 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:37 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:37 minidsp-shd volumio[706]: info: Feb 24 17:30:37 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:37 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand status took 23 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand status took 19 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand status took 11 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:37 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:37 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2744 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:37 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:37 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:37 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:37 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:37 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2744 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:38 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2744 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:38 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: ------------------------------ 250ms Feb 24 17:30:38 minidsp-shd volumio[706]: info: ------------------------------ 245ms Feb 24 17:30:38 minidsp-shd volumio[706]: info: ------------------------------ 237ms Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioSeek Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::seek Feb 24 17:30:38 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:38 minidsp-shd volumio[706]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto; Rococo Variations","albumUri":"qobuz://album/0002894798092","albumart":"https://static.qobuz.com/images/covers/92/80/0002894798092_600.jpg","artist":"Nemanja Radulović","artistUri":"qobuz://artist/101858","audioQuality":"HI_RES","duration":1125,"explicit":false,"name":"I. Allegro moderato","service":"qobuz","title":"I. Allegro moderato","tracknumber":1,"type":"track","uri":"qobuz://song/42241262","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:30:38 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:38 minidsp-shd volumio[706]: info: [1771950638240] ControllerQobuz::seek Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::seek Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:38 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:38 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:38 minidsp-shd volumio[706]: info: getStreamUrl took 193 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954238&hmac=22b48MES19dOMPuthl6bvcKL-uA Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=70223143- Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 302910248 Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:38 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:38 minidsp-shd volumio[706]: info: getStreamUrl took 323 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954238&hmac=22b48MES19dOMPuthl6bvcKL-uA Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=70210159- Feb 24 17:30:38 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 302923232 Feb 24 17:30:38 minidsp-shd volumio[706]: error: null Feb 24 17:30:38 minidsp-shd volumio[706]: info: Feb 24 17:30:38 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:38 minidsp-shd volumio[706]: info: sendMpdCommand status took 4 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:38 minidsp-shd volumio[706]: info: Feb 24 17:30:38 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:38 minidsp-shd volumio[706]: info: Feb 24 17:30:38 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:38 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: info: sendMpdCommand status took 11 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: info: sendMpdCommand status took 7 milliseconds Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:38 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:38 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:38 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2523 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:39 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: ------------------------------ 114ms Feb 24 17:30:39 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 81 milliseconds Feb 24 17:30:39 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:39 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2523 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:39 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2523 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:39 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:39 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:39 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:39 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:39 minidsp-shd volumio[706]: info: ------------------------------ 266ms Feb 24 17:30:39 minidsp-shd volumio[706]: info: ------------------------------ 261ms Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioSeek Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreStateMachine::seek Feb 24 17:30:40 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:40 minidsp-shd volumio[706]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto; Rococo Variations","albumUri":"qobuz://album/0002894798092","albumart":"https://static.qobuz.com/images/covers/92/80/0002894798092_600.jpg","artist":"Nemanja Radulović","artistUri":"qobuz://artist/101858","audioQuality":"HI_RES","duration":1125,"explicit":false,"name":"I. Allegro moderato","service":"qobuz","title":"I. Allegro moderato","tracknumber":1,"type":"track","uri":"qobuz://song/42241262","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:30:40 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:40 minidsp-shd volumio[706]: info: [1771950640614] ControllerQobuz::seek Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 17:30:40 minidsp-shd volumio[706]: info: ControllerMpd::seek Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:40 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:40 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:40 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:40 minidsp-shd volumio[706]: info: getStreamUrl took 210 milliseconds Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954240&hmac=x7j4wm_ZZTi2siE7MuZXzAJokAI Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=71158632- Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 301974759 Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:40 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:40 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioSeek Feb 24 17:30:40 minidsp-shd volumio[706]: info: CoreStateMachine::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:41 minidsp-shd volumio[706]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto; Rococo Variations","albumUri":"qobuz://album/0002894798092","albumart":"https://static.qobuz.com/images/covers/92/80/0002894798092_600.jpg","artist":"Nemanja Radulović","artistUri":"qobuz://artist/101858","audioQuality":"HI_RES","duration":1125,"explicit":false,"name":"I. Allegro moderato","service":"qobuz","title":"I. Allegro moderato","tracknumber":1,"type":"track","uri":"qobuz://song/42241262","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:30:41 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:41 minidsp-shd volumio[706]: info: [1771950641001] ControllerQobuz::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 17:30:41 minidsp-shd volumio[706]: info: ControllerMpd::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:41 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:41 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:41 minidsp-shd volumio[706]: info: getStreamUrl took 214 milliseconds Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954241&hmac=zGUi8V4gF0TZ4caWlcA4SbptH8w Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=71189164- Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 301944227 Feb 24 17:30:41 minidsp-shd volumio[706]: error: null Feb 24 17:30:41 minidsp-shd volumio[706]: info: Feb 24 17:30:41 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:41 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:41 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:41 minidsp-shd volumio[706]: info: Feb 24 17:30:41 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:41 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:41 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:41 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioSeek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:41 minidsp-shd volumio[706]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto; Rococo Variations","albumUri":"qobuz://album/0002894798092","albumart":"https://static.qobuz.com/images/covers/92/80/0002894798092_600.jpg","artist":"Nemanja Radulović","artistUri":"qobuz://artist/101858","audioQuality":"HI_RES","duration":1125,"explicit":false,"name":"I. Allegro moderato","service":"qobuz","title":"I. Allegro moderato","tracknumber":1,"type":"track","uri":"qobuz://song/42241262","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:30:41 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:41 minidsp-shd volumio[706]: info: [1771950641298] ControllerQobuz::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 17:30:41 minidsp-shd volumio[706]: info: ControllerMpd::seek Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:41 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:41 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:41 minidsp-shd volumio[706]: info: getStreamUrl took 235 milliseconds Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954241&hmac=zGUi8V4gF0TZ4caWlcA4SbptH8w Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=49953084- Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 323180307 Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:41 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:41 minidsp-shd volumio[706]: info: getStreamUrl took 182 milliseconds Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954241&hmac=zGUi8V4gF0TZ4caWlcA4SbptH8w Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=49892296- Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 323241095 Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:41 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:41 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:41 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:42 minidsp-shd volumio[706]: info: getStreamUrl took 270 milliseconds Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954242&hmac=_NfZP06uqZSI5484YxGitYhH3Zs Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=49883151- Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 323250240 Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: error: null Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:42 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:42 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:42 minidsp-shd volumio[706]: info: getStreamUrl took 302 milliseconds Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954242&hmac=_NfZP06uqZSI5484YxGitYhH3Zs Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=47898255- Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 325235136 Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Client dropped request, destroying Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Handling url /?data=qobuz://song/42241262 Feb 24 17:30:42 minidsp-shd volumio[706]: info: Executing endpoint getStreamUrlqobuz Feb 24 17:30:42 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 24 17:30:42 minidsp-shd volumio[706]: info: getStreamUrl took 243 milliseconds Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=320094&eid=42241262&fmt=7&profile=raw&app_id=539451548&cid=1227711&etsp=1771954242&hmac=_NfZP06uqZSI5484YxGitYhH3Zs Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Got range request: bytes=47888439- Feb 24 17:30:42 minidsp-shd volumio[706]: STREAMING PROXY: Response: 206, length: 325244952 Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:42 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:42 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:42 minidsp-shd volumio[706]: error: null Feb 24 17:30:42 minidsp-shd volumio[706]: info: Feb 24 17:30:42 minidsp-shd volumio[706]: ---------------------------- MPD announces state update: player Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 1748 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 1747 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::getState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand status Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 745 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 747 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 23 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 20 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 19 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 17 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand status took 44 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseState Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 2350ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 2350ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 1348ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 1350ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 625ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 624ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 625ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 626ms Feb 24 17:30:43 minidsp-shd volumio[706]: info: sendMpdCommand playlistinfo took 573 milliseconds Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: ControllerMpd::parseTrackInfo Feb 24 17:30:43 minidsp-shd volumio[706]: info: ControllerMpd::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::servicePushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CorePlayQueue::getTrack 10 Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":1125,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2518 Kbps","isStreaming":false,"title":"42241262","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/42241262","trackType":"qobuz"} Feb 24 17:30:43 minidsp-shd volumio[706]: verbose: CURRENT POSITION 10 Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState stateService play Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:30:43 minidsp-shd volumio[706]: info: Received an update from plugin. extracting info from payload Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreStateMachine::pushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioPushState Feb 24 17:30:43 minidsp-shd volumio[706]: info: CoreCommandRouter::volumioGetState Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:30:43 minidsp-shd volumio[706]: info: MRS: Pushing multiroomSync output Feb 24 17:30:43 minidsp-shd volumio[706]: info: ------------------------------ 692ms Feb 24 17:31:08 minidsp-shd volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Feb 24 17:31:08 minidsp-shd volumio[706]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Feb 24 17:31:08 minidsp-shd volumio[706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:31:08 minidsp-shd volumio[706]: TypeError: Cannot read property 'then' of undefined Feb 24 17:31:08 minidsp-shd volumio[706]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Feb 24 17:31:08 minidsp-shd volumio[706]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Feb 24 17:31:08 minidsp-shd volumio[706]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Feb 24 17:31:08 minidsp-shd volumio[706]: at Socket.emit (events.js:400:28) Feb 24 17:31:08 minidsp-shd volumio[706]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 24 17:31:08 minidsp-shd volumio[706]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 24 17:31:08 minidsp-shd volumio[706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:31:08 minidsp-shd sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 17:30 Feb 24 17:31:08 minidsp-shd sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:14:14 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="57978c68a391dbf6badbe915e5672a72"