Introducing pleezer: a Deezer Connect player

[Tim Curtis]

ISSUE:

1. Output volume (from pleezer) is almost 100% even though "Deezer Connect" slider volume on iPhone is almost 0.
Note: When a renderer connects to moode, ALSA volume if present is set to 100% (0dB) based on the assumption the volume is controlled entirely by the client app.
2. After "disconnect" event happens the ALSA output is still busy.

#1 - Can you share the trace log messages? What happens if you change the volume?

#2 - Check question to be sure: the output is probably also busy when you haven't even connected yet? At the moment it opens the output, and never releases it. Indeed I can look into opening on connection, and closing on disconnection.

#2. Assume something else has the audio output open before pleezer has started. This implies that it should not open the output upon connect but rather upon play.

#1 Debug log

Code:

[2024-11-29T23:34:11Z DEBUG pleezer::remote] REMOTEDISCOVER <- DiscoveryRequest
[2024-11-29T23:34:11Z DEBUG pleezer::remote] REMOTEDISCOVER -> ConnectionOffer
[2024-11-29T23:34:13Z DEBUG pleezer::remote] REMOTEDISCOVER <- Connect
[2024-11-29T23:34:13Z DEBUG pleezer::remote] subscribing to 6348705983_6348705983_REMOTEQUEUE
[2024-11-29T23:34:13Z DEBUG pleezer::remote] subscribing to 6348705983_6348705983_REMOTECOMMAND
[2024-11-29T23:34:13Z DEBUG pleezer::remote] REMOTECOMMAND -> Ready
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTEQUEUE <- PublishQueue
[2024-11-29T23:34:14Z INFO  pleezer::remote] setting queue to ADFA9772-AF10-438E-9756-C1E62F56E601
[2024-11-29T23:34:14Z DEBUG pleezer::remote] extending queue 611a2cc3-b53c-471f-996f-486b7248c772 with 8 tracks
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTEQUEUE -> PublishQueue
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTEQUEUE -> RefreshQueue
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:14Z INFO  pleezer::player] setting track progress to 0.0%
[2024-11-29T23:34:14Z INFO  pleezer::player] setting repeat mode to None
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:14Z DEBUG pleezer::remote] user data time to live: 1295940s
[2024-11-29T23:34:14Z INFO  pleezer::remote] user casting quality: High Quality
[2024-11-29T23:34:14Z INFO  pleezer::remote] connected to xxxxxxxxxxx
[2024-11-29T23:34:14Z DEBUG pleezer::remote] handling event: Connected
Process: connected
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:14Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:15Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:15Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:15Z DEBUG pleezer::track] starting download of track 108733460: "Jeff Buckley - Lilac Wine" from e-cdnt-stream.dzcdn.net
[2024-11-29T23:34:15Z INFO  pleezer::track] downloading 10919182 bytes for track 108733460: "Jeff Buckley - Lilac Wine"
[2024-11-29T23:34:16Z DEBUG pleezer::remote] handling event: TrackChanged
Process: track_changed
[2024-11-29T23:34:17Z INFO  pleezer::track] completed download of track 108733460: "Jeff Buckley - Lilac Wine"
[2024-11-29T23:34:17Z DEBUG pleezer::track] starting download of track 2626489212: "Marc Broussard - Cry To Me" from cdnt-proxy-7.dzcdn.net
[2024-11-29T23:34:17Z INFO  pleezer::track] downloading 9749942 bytes for track 2626489212: "Marc Broussard - Cry To Me"
[2024-11-29T23:34:19Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:20Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:20Z INFO  pleezer::track] completed download of track 2626489212: "Marc Broussard - Cry To Me"
[2024-11-29T23:34:24Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:25Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:29Z DEBUG pleezer::remote] REMOTECOMMAND <- Ping
[2024-11-29T23:34:29Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:34Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:35Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 5.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 10.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 15.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 10.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 15.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z INFO  pleezer::player] setting volume to 20.0%
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:36Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Skip
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> PlaybackProgress
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Status
[2024-11-29T23:34:42Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:42Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:47Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:47Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:52Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:52Z DEBUG pleezer::remote] REMOTECOMMAND <- Ping
[2024-11-29T23:34:52Z DEBUG pleezer::remote] REMOTECOMMAND -> Acknowledgement
[2024-11-29T23:34:52Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:34:57Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:34:57Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:02Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:02Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:07Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:07Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:12Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:12Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:17Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:17Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:22Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:22Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:27Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:27Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:32Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:32Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:37Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:37Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:42Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:42Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:47Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:47Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement
[2024-11-29T23:35:52Z DEBUG pleezer::remote] REMOTECOMMAND -> Ping
[2024-11-29T23:35:52Z DEBUG pleezer::remote] REMOTECOMMAND <- Acknowledgement

[roderickvd]

This is the debug output with "-v". Could you provide me the trace output with "-vv" (twice)?
Then you can see which volume pleezer is instructed to set in the "Skip" message.

[Tim Curtis]

I sent you a PM with a download link to the vv log.

I should clarify moode renderer handling with respect to audio output.

Example: Spotify Connect
1. Assume MPD is playing
2. When librespot starts it does not open the audio output
3 When client connects to librespot the audio output remains closed
- The hook script for librespot will stop MPD playback when it processes the "session_connected" event
4. When client starts playback librespot opens the audio output (it was closed via #3)
5. When client stops playback or disconnects librespot close the audio output

[Nutul]

That should be the correct behavior.

[roderickvd]

No problem, I'll work on opening and closing the audio output on connection and disconnection.

w.r.t. the volume issue, please help me understand.
In the logs I see that the initial volume is set to 100% by instruction of the remote.
Quickly after, it is decreased to about 20%.
Then, I see it changes to 15% - 10% - 15% - 20% - 25%.

Where does pleezer do something different than it was instructed to?

[Tim Curtis]

No problem, I'll work on opening and closing the audio output on connection and disconnection.

w.r.t. the volume issue, please help me understand.
In the logs I see that the initial volume is set to 100% by instruction of the remote.
Quickly after, it is decreased to about 20%.
Then, I see it changes to 15% - 10% - 15% - 20% - 25%.

Where does pleezer do something different than it was instructed to?

To clarify: On connection assume MPD is playing and thus the audio output will be busy. The pleezer hook script used by moode will stop MPD playback when it receives event = "connected" and MPD will close the audio output. Then when Deezer client sends "play", pleezer should open the audio output at that time and then and begin playback.

By "remote" do you mean the Deezer IOS app? If thats the case then what I see after connecting is the volume slider at whatever the last position was from the previous connection (prolly like 10% position). Then I moved the slider a few times.

The output volume (to the ear) before touching the slider was definitely 100%. Note that my downstream volume control is always really low when testing :-) Then after moving the slider the volume went down a bit but still way too loud at anything less than 50% slider position. It's as if the volume curve is super steep in the beginning.

Bluetooth and Plexamp renderers have same "loud" volume issue (we compensate for that by having a "Max ALSA volume" option. AirPlay and Spotify Connect no issues.

The logs shows the volume commands sent by the IOS app to pleezer, except that wild 100% oddity, have the correct percentages but the question is what is the volume algorithm doing in pleezer?

I'll make a moode image soon that you can use for testing :-)

[roderickvd]

To clarify: On connection assume MPD is playing and thus the audio output will be busy. The pleezer hook script used by moode will stop MPD playback when it receives event = "connected" and MPD will close the audio output. Then when Deezer client sends "play", pleezer should open the audio output at that time and then and begin playback.

Yup, got it. I've pushed a commit to the repo that now does that.

While testing with connecting-disconnecting-connecting I found a couple of queue synchronization issues that I also tried to address. It became a fairly big change, would be good to give it some testing before I release v0.4.0.

By "remote" do you mean the Deezer IOS app?

Yes.

The logs shows the volume commands sent by the IOS app to pleezer, except that wild 100% oddity, have the correct percentages but the question is what is the volume algorithm doing in pleezer?

Currently just linear but no problem to make it logarithmic.

I'll make a moode image soon that you can use for testing :-)

[roderickvd]

To clarify: On connection assume MPD is playing and thus the audio output will be busy. The pleezer hook script used by moode will stop MPD playback when it receives event = "connected" and MPD will close the audio output. Then when Deezer client sends "play", pleezer should open the audio output at that time and then and begin playback.

Yup, got it. I've pushed a commit to the repo that now does that.

While testing with connecting-disconnecting-connecting I found a couple of queue synchronization issues that I also tried to address. It became a fairly big change, would be good to give it some testing before I release v0.4.0.

I see it's still acquiring the sink before connection, so I need to fix that. I'll let you know when I have.

[Tim Curtis]

To clarify: On connection assume MPD is playing and thus the audio output will be busy. The pleezer hook script used by moode will stop MPD playback when it receives event = "connected" and MPD will close the audio output. Then when Deezer client sends "play", pleezer should open the audio output at that time and then and begin playback.

Yup, got it. I've pushed a commit to the repo that now does that.

While testing with connecting-disconnecting-connecting I found a couple of queue synchronization issues that I also tried to address. It became a fairly big change, would be good to give it some testing before I release v0.4.0.

I see it's still acquiring the sink before connection, so I need to fix that. I'll let you know when I have.

Yes, I also noticed that it opens ALSA output right after it starts.