Attempt to rewind song through IOS (17.3.1) spotify home screen widget restults in constant hum/noise (while listening through cspot)

[bungh0l10]

Hi there,

just found that rewinding songs through IOS Spotify widget restult s in very loud noise (while playing a single track without having anything else queued) - sounds like reaching close to a channel while seeking on an analog TV. Double tapping back rewinds to the previous track in best case but I also achieved it while pressing back and forth that the squeezeamp quits the spotify connection with a super loud crack through the speakers (unfortunately I have no logs for that scenario yet).

Preliminary Information

1. 1670
2. 0.600

Hardware Details

• Squeezeamp v3.20

NVS Settings

{ "a2dp_ctmt": "1000", "a2dp_ctrld": "500", "a2dp_dev_name": "Squeezelite", "a2dp_sink_name": "SMSL BT4.2", "a2dp_spin": "0000", "actrls_config": "", "airplay_name": "ESP32-AirPlay-ceded8", "airplay_port": "5000", "ap_channel": "1", "ap_ip_address": "192.168.4.1", "ap_ip_gateway": "192.168.4.1", "ap_ip_netmask": "255.255.255.0", "ap_pwd": "squeezelite", "ap_ssid": "squeezelite-ceded8", "autoexec": "1", "autoexec1": "squeezelite -o I2S -b 500:2000 -d all=info -C 30 -W -n \"squeezelite-ceded8\"", "bat_config": "", "bt_name": "ESP32-BT-ceded8", "bt_sink_pin": "1234", "bt_sink_volume": "127", "bypass_wm": "0", "cspot_config": "{\"deviceName\":\"squeezelite-ceded8\",\"bitrate\":320,\"zeroConf\":1,\"volume\":32767}", "dac_config": "", "dac_controlset": "", "dhcp_tmout": "8", "display_config": "", "enable_airplay": "Y", "enable_bt_sink": "Y", "enable_cspot": "Y", "equalizer": "0,0,0,0,0,0,0,0,0,0", "eth_config": "", "ethtmout": "8", "gpio_exp_config": "", "host_name": "squeezelite-ceded8", "i2c_config": "", "jack_mutes_amp": "n", "led_brightness": "", "led_vu_config": "", "lms_ctrls_raw": "n", "loudness": "0", "metadata_config": "", "ota_erase_blk": "249856", "ota_prio": "6", "ota_stack": "10240", "pollmin": "15", "pollmx": "600", "rel_api": "https://api.github.com/repos/sle118/squeezelite-esp32/releases", "release_url": "https://github.com/sle118/squeezelite-esp32/releases", "rotary_config": "", "set_GPIO": "0=ir", "sleep_config": "", "spdif_config": "", "spi_config": "", "stats": "N", "target": "", "telnet_block": "500", "telnet_buffer": "40000", "telnet_enable": "Y" }

  [02:23:47.393] I TrackPlayer.cpp:171: Got track ID=8b7eedf2eb445b768d0997f518c039451153784f
[02:23:47.396] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-ak-spotify-com.akamaized.net/audio/8b7eedf2eb445b768d0997f518c039451153784f?__token__=exp=1708626743~hmac=1d25044d94499af1e0a18501365fefe23c33f17145733b7a337121b66d23a964
[02:23:48.578] I CDNAudioFile.cpp:70: Header and footer bytes received
[02:23:48.582] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:23:48.594] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:23:48.603] cspot_cmd_handler:383 CSpot start track
[02:23:48.604] I Shim.cpp:217: (re)start playing at 0
[02:23:48.606] cspot_cmd_handler:396 CSpot play
[02:23:48.660] output_thread_i2s:518 Output state is 2
[02:23:48.665] output_thread_i2s:585 Restarting I2S.
[02:23:48.696] I MercurySession.cpp:42: Received packet, command: 178
[02:23:48.698] D MercurySession.cpp:174: Received mercury packet
[02:23:48.925] I TrackPlayer.cpp:206: Playing
[02:23:48.933] I MercurySession.cpp:42: Received packet, command: 178
[02:23:48.935] D MercurySession.cpp:174: Received mercury packet
[02:23:48.973] I Shim.cpp:120: new track started <> => <8b7eedf2eb445b768d0997f518c039451153784f>
[02:23:48.975] I Shim.cpp:262: next track will play in 0 ms
[02:23:48.990] _output_frames:150 track start sample rate: 44100 replay_gain: 0
[02:23:49.138] I Shim.cpp:416: next track's audio has reached DAC (offset 0)
[02:23:49.147] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:23:49.170] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:23:49.303] I MercurySession.cpp:42: Received packet, command: 178
[02:23:49.305] D MercurySession.cpp:174: Received mercury packet
[02:23:49.422] I MercurySession.cpp:42: Received packet, command: 178
[02:23:49.426] D MercurySession.cpp:174: Received mercury packet
I (302715) battery: Voltage 8.73V
[02:25:28.367] I MercurySession.cpp:42: Received packet, command: 4
[02:25:28.369] D TimeProvider.cpp:15: Time synced with spotify servers
[02:25:28.483] I MercurySession.cpp:42: Received packet, command: 74
[02:27:28.428] I MercurySession.cpp:42: Received packet, command: 4
[02:27:28.430] D TimeProvider.cpp:15: Time synced with spotify servers
[02:27:28.489] I MercurySession.cpp:42: Received packet, command: 74
[02:28:42.378] I MercurySession.cpp:42: Received packet, command: 181
[02:28:42.381] D SpircHandler.cpp:69: Received subscription response
[02:28:42.387] I TrackPlayer.cpp:107: Seeking...
[02:28:42.391] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:28:42.402] cspot_cmd_handler:401 CSpot seek by 0
[02:28:42.539] I MercurySession.cpp:42: Received packet, command: 178
[02:28:42.544] D MercurySession.cpp:174: Received mercury packet
I (602715) battery: Voltage 8.79V
[02:29:28.411] I MercurySession.cpp:42: Received packet, command: 4
[02:29:28.413] D TimeProvider.cpp:15: Time synced with spotify servers
[02:29:28.454] I MercurySession.cpp:42: Received packet, command: 74
[02:30:11.984] I TrackPlayer.cpp:224: EOF
[02:30:11.989] I TrackPlayer.cpp:255: Playing done
[02:30:11.995] I Shim.cpp:247: playlist ended, no track left to play
[02:30:23.753] I Shim.cpp:430: last track finished
[02:30:23.756] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:30:23.769] I TrackPlayer.cpp:98: Resetting state
[02:30:23.959] I MercurySession.cpp:42: Received packet, command: 178
[02:30:23.961] D MercurySession.cpp:174: Received mercury packet
[02:30:24.042] I TrackPlayer.cpp:171: Got track ID=8b7eedf2eb445b768d0997f518c039451153784f
[02:30:24.046] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-ak-spotify-com.akamaized.net/audio/8b7eedf2eb445b768d0997f518c039451153784f?__token__=exp=1708626743~hmac=1d25044d94499af1e0a18501365fefe23c33f17145733b7a337121b66d23a964
[02:30:25.198] I CDNAudioFile.cpp:70: Header and footer bytes received
[02:30:25.202] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:30:25.211] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[02:30:25.219] cspot_cmd_handler:383 CSpot start track
[02:30:25.220] I Shim.cpp:217: (re)start playing at 0
[02:30:25.221] cspot_cmd_handler:412 CSpot pause
[02:30:25.239] output_thread_i2s:518 Output state is 0
[02:30:25.410] I MercurySession.cpp:42: Received packet, command: 178
[02:30:25.412] D MercurySession.cpp:174: Received mercury packet
[02:30:25.587] I TrackPlayer.cpp:206: Playing
[02:30:25.634] I Shim.cpp:120: new track started <> => <8b7eedf2eb445b768d0997f518c039451153784f>
[02:30:25.636] I Shim.cpp:262: next track will play in 0 ms
[02:30:25.743] I MercurySession.cpp:42: Received packet, command: 178
[02:30:25.745] D MercurySession.cpp:174: Received mercury packet
[02:30:55.239] output_thread_i2s:518 Output state is -1
[02:30:55.239] output_thread_i2s:522 switching off amp GPIO -1

[sle118]

@feelfreelinux Filip might be able to help here, unless @philippe44 wants to comment

[philippe44]

I happens from time to time and it's due to a byte/word misalignment when seeking and it's rather a cspot thing as it happens on all my other apps that use cspot . I looked at it a while ago but could not see anything obvious and I don't have time to investigate that currently.

[sle118]

Thank you @philippe44

Summoning the spirit of cspot here 🙂 @feelfreelinux

[feelfreelinux]

Thanks for reporting this, will try to investigate it in a bit. I think I was able to reproduce that before in upstream cspot too.

[feelfreelinux]

Hm, not quite able to recreate it on the cli / desktop sink of cspot. Will have to retry on esp32 implementation. Running iOS 17.3.1 with current upstream cspot (541fed52b792975e721b30bcaa220fb957b7bc76).

[philippe44]

I have it on my other cspot apps as well, the upnp and raop bridges.