search

kingosticks / mopidy-tunein

Mopidy extension for playing music from tunein
Apache License 2.0
65 stars 14 forks source link

Station Playback not working on all system #51

Closed ThomTrab closed 3 years ago

ThomTrab commented 3 years ago

Hello,

I'm not sure this is a bug, but I didn't find a solution for it so I would like your help on the matter.

I have two setups running on my local network One, let's call it x86

Executable: /usr/bin/mopidy
Platform: Linux-5.8.0-48-generic-x86_64-with-glibc2.32
Python: CPython 3.8.6 from /usr/lib/python3.8
Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
Mopidy-ALSAMixer: 2.0.0 from /usr/local/lib/python3.8/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  pyalsaaudio: 0.9.0 from /usr/local/lib/python3.8/dist-packages
Mopidy-Podcast: 3.0.0 from /usr/local/lib/python3.8/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  cachetools: 4.2.0 from /usr/local/lib/python3.8/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  uritools: 3.0.0 from /usr/local/lib/python3.8/dist-packages
Mopidy-MusicBox-Webclient: 3.1.0 from /usr/local/lib/python3.8/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
Mopidy-Podcast-iTunes: 3.0.0 from /usr/local/lib/python3.8/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  requests: 2.23.0 from /usr/lib/python3/dist-packages
  Mopidy-Podcast: 3.0.0 from /usr/local/lib/python3.8/dist-packages
    Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
    Pykka: 2.0.3 from /usr/lib/python3/dist-packages
    cachetools: 4.2.0 from /usr/local/lib/python3.8/dist-packages
    setuptools: 49.3.1 from /usr/lib/python3/dist-packages
    uritools: 3.0.0 from /usr/local/lib/python3.8/dist-packages
Mopidy-TuneIn: 1.0.0 from /usr/local/lib/python3.8/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  requests: 2.23.0 from /usr/lib/python3/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
Mopidy-MPD: 3.1.0 from /usr/local/lib/python3.8/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  setuptools: 49.3.1 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
GStreamer: 1.18.0.0 from /usr/lib/python3/dist-packages/gi
  Detailed information: 
    Python wrapper: python-gi 3.38.0
    Relevant elements:
      Found:
        uridecodebin
        souphttpsrc
        appsrc
        alsasink
        osssink
        oss4sink
        pulsesink
        id3demux
        id3v2mux
        lamemp3enc
        mpegaudioparse
        mpg123audiodec
        vorbisdec
        vorbisenc
        vorbisparse
        oggdemux
        oggmux
        oggparse
        flacdec
        flacparse
        shout2send
      Not found:
        flump3dec
        mad

And another one, let's call it Pi

Executable: /usr/bin/mopidy
Platform: Linux-5.10.17+-armv6l-with-debian-10.9
Python: CPython 3.7.3 from /usr/lib/python3.7
Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
Mopidy-TuneIn: 1.1.0 from /usr/lib/python3/dist-packages
Mopidy-MusicBox-Webclient: 3.1.0 from /usr/local/lib/python3.7/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  setuptools: 40.8.0 from /usr/lib/python3/dist-packages
Mopidy-MPD: 3.1.0 from /usr/lib/python3/dist-packages
Mopidy-ALSAMixer: 2.0.1 from /usr/local/lib/python3.7/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  pyalsaaudio: 0.9.0 from /usr/local/lib/python3.7/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  setuptools: 40.8.0 from /usr/lib/python3/dist-packages
mopidy-raspberry-gpio: 1.0.2 from /usr/local/lib/python3.7/dist-packages
  Pykka: 2.0.3 from /usr/lib/python3/dist-packages
  Mopidy: 3.1.1 from /usr/lib/python3/dist-packages
  setuptools: 40.8.0 from /usr/lib/python3/dist-packages
GStreamer: 1.14.4.0 from /usr/lib/python3/dist-packages/gi
  Detailed information: 
    Python wrapper: python-gi 3.30.4
    Relevant elements:
      Found:
        uridecodebin
        souphttpsrc
        appsrc
        alsasink
        osssink
        oss4sink
        pulsesink
        id3demux
        id3v2mux
        lamemp3enc
        mpegaudioparse
        mpg123audiodec
        vorbisdec
        vorbisenc
        vorbisparse
        oggdemux
        oggmux
        oggparse
        flacdec
        flacparse
        shout2send
      Not found:
        flump3dec
        mad

On the x86 installation, I'm able to playblack the tunein:station:s34682 without issue, but on the Pi I got the following messages from the log

Apr  8 18:45:08 mopidy[418]: WARNING  [TuneInBackend-6] mopidy.internal.http Download of 'http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1' failed due to download taking more than 4.999s
Apr  8 18:45:08 mopidy[418]: INFO     [TuneInBackend-6] mopidy_tunein.actor Unwrapping stream from URI ('http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1') failed: error downloading URI 'http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1'
Apr  8 18:45:09 mopidy[418]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Server sent bad data.

Could you help me pinpoint the issue ?

Thank you for your help

kingosticks commented 3 years ago

Did you try increasing the timeout? Is it a wireless connection for the pi? Can you try wired?

ThomTrab commented 3 years ago

Did you try increasing the timeout?

I tried to increased the timeout up to 60000 (1 min) without success.

Is it a wireless connection for the pi?

Indeed the pi use a wireless connection.

Can you try wired? I can try to use a wired one.

I did not mention it but other station are working fine on the Pi

ThomTrab commented 3 years ago

I tried to increased the timeout up to 2 min without more success even with a wired connection

Apr 10 10:14:55 chambre mopidy[377]: WARNING  [TuneInBackend-6] mopidy.internal.http Download of 'http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPDGn1APDGn1AAcABBENBRCgAAAAAH_AACiQHmAAAAIEAABAACBhAIgAAAAAAAAAACAQAAAAAAAAABgAAAAAAAAAAAAAACAAAAgAIAAAAAAAAAAABAAAAAAAAADAAAAAAAEAAABAAAAAAAAAAAAAAIAAAAAAAAAAAAAAgAwAPv__7_f_r-3_3_vp9X---_e_V399xLv9QPKAAAAQIAACAAECCABAAAAAAAAAAAEAAAAAAAAAAADAAAAAAAAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jDCEMosAKBR_RUYCJQggWAAA.YAAAAAAAAAAA&gdpr=1' failed due to download taking more than 119.993s
Apr 10 10:14:55 chambre mopidy[377]: INFO     [TuneInBackend-6] mopidy_tunein.actor Unwrapping stream from URI ('http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPDGn1APDGn1AAcABBENBRCgAAAAAH_AACiQHmAAAAIEAABAACBhAIgAAAAAAAAAACAQAAAAAAAAABgAAAAAAAAAAAAAACAAAAgAIAAAAAAAAAAABAAAAAAAAADAAAAAAAEAAABAAAAAAAAAAAAAAIAAAAAAAAAAAAAAgAwAPv__7_f_r-3_3_vp9X---_e_V399xLv9QPKAAAAQIAACAAECCABAAAAAAAAAAAEAAAAAAAAAAADAAAAAAAAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jDCEMosAKBR_RUYCJQggWAAA.YAAAAAAAAAAA&gdpr=1') failed: error downloading URI 'http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPDGn1APDGn1AAcABBENBRCgAAAAAH_AACiQHmAAAAIEAABAACBhAIgAAAAAAAAAACAQAAAAAAAAABgAAAAAAAAAAAAAACAAAAgAIAAAAAAAAAAABAAAAAAAAADAAAAAAAEAAABAAAAAAAAAAAAAAIAAAAAAAAAAAAAAgAwAPv__7_f_r-3_3_vp9X---_e_V399xLv9QPKAAAAQIAACAAECCABAAAAAAAAAAAEAAAAAAAAAAADAAAAAAAAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jDCEMosAKBR_RUYCJQggWAAA.YAAAAAAAAAAA&gdpr=1'
Apr 10 10:15:01 chambre mopidy[377]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Could not open audio device for playback.
Apr 10 10:15:01 chambre mopidy[377]: WARNING  [Audio-2] mopidy.audio.actor Setting GStreamer state to GST_STATE_PLAYING failed
Apr 10 10:15:01 chambre mopidy[377]: WARNING  [Core-7] mopidy.core.tracklist Track is not playable: tunein:station:s34682

But in the same time I'm listening to it on the x86 setup.

kingosticks commented 3 years ago

Thanks for ruling out the network.

The difference must be with the GStreamer versions. If I use the normal debugging commands I can see the same difference between my Ubuntu 20.04 system (GStreamer: 1.16.2.0):

nick@xps:~$ gst-discoverer-1.0 "http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1"
Analyzing http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1
Done discovering http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1

Topology:
  unknown: ICY internet radio
    audio: MPEG-1 Layer 3 (MP3)

Properties:
  Duration: 99:99:99.999999999
  Seekable: no
  Live: no
  Tags: 
      organisation: Jazz24
      has crc: false
      channel mode: joint-stereo
      audio codec: MPEG-1 Layer 3 (MP3)
      nominal bitrate: 128000

and my Raspbian Buster system:

gst-discoverer-1.0 "http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1"
Analyzing http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1
Done discovering http://live.wostreaming.net/direct/ppm-jazz24mp3-ibc1?source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1
An error was encountered while discovering the file
 Server sent bad data.

At this point we could stop as there's nothing we can do about this. There is a bug in the old version that's available in Buster.

But if you are curious.... taking things another level down:

GST_DEBUG=*soup*:7 gst-discoverer-1.0 -v "http://35.230.27.165/ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1"
Analyzing http://35.230.27.165/ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1
0:00:00.147125331  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1857:gst_soup_http_src_start:<source> start("http://35.230.27.165/ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1")
0:00:00.147260226  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:949:gst_soup_http_src_session_open:<source> Creating session (can share 1)
0:00:00.148357617  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:981:gst_soup_http_src_session_open:<source> Sharing session 0x2d8970
0:00:00.148446262  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1919:gst_soup_http_src_set_context:<source> Setting external session 0x2d8970 (force: 0)
0:00:00.148514543  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1965:gst_soup_http_src_get_size:<source> get_size() = FALSE
0:00:00.148604438  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:2011:gst_soup_http_src_do_seek:<source> do_seek(0-18446744073709551615)
0:00:00.148632928  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:2016:gst_soup_http_src_do_seek:<source> Seek to current read/end position and no seek pending
0:00:00.149438861  7310   0x2daa00 LOG              souphttpsrc gstsouphttpsrc.c:1581:gst_soup_http_src_do_request:<source> Running request for method: GET
0:00:00.546940639  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): > GET /ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1 HTTP/1.1
0:00:00.547094336  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): > Soup-Debug-Timestamp: 1618060767
0:00:00.547218867  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): > Soup-Debug: SoupSession 1 (0x2d8970), SoupMessage 1 (0x28ce70), SoupSocket 1 (0x70e9a088)
0:00:00.547312512  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): > Host: 35.230.27.165
0:00:00.547475115  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): > User-Agent: GStreamer souphttpsrc 1.14.4 libsoup/2.64.2
0:00:00.547694958  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): > icy-metadata: 1
0:00:00.547921051  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): > Connection: Keep-Alive
0:00:00.548124695  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M):   
0:00:00.679765726  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): < HTTP/1.1 8 Message Corrupt
0:00:00.680022287  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): < Soup-Debug-Timestamp: 1618060767
0:00:00.680209630  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M): < Soup-Debug: SoupMessage 1 (0x28ce70)
0:00:00.680428900  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Connection: close
0:00:00.680655670  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Date: Sat, 10 Apr 2021 13:19:26 GMT
0:00:00.680929210  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Content-Type: audio/mpeg
0:00:00.681234573  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Cache-Control: no-cache
0:00:00.681442697  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Pragma: no-cache
0:00:00.681643217  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Transfer-Encoding: identity
0:00:00.682181548  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Content-Encoding: identity
0:00:00.682341859  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < Expires: Thu, 01 Jan 1970 00:00:01 GMT
0:00:00.682548317  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < icy-br: 128
0:00:00.683146126  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < icy-metaint: 2048
0:00:00.683622791  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(H): < icy-name: Jazz24
0:00:00.683785446  7310   0x2daa00 TRACE              souputils gstsouputils.c:66:gst_soup_util_log_printer_cb:<source> HTTP_SESSION(M):   
0:00:00.684918566  7310   0x2daa00 INFO             souphttpsrc gstsouphttpsrc.c:1127:gst_soup_http_src_got_headers:<source> got headers
0:00:00.685600333  7310   0x2daa00 DEBUG            souphttpsrc gstsouphttpsrc.c:1212:gst_soup_http_src_got_headers:<source> icy-metaint: 2048 (parsed: 2048)
0:00:00.690794006  7310   0x2daa00 DEBUG            souphttpsrc gstsouphttpsrc.c:1268:gst_soup_http_src_got_headers:<source> Content-Type: audio/mpeg
0:00:00.691836605  7310   0x2daa00 DEBUG            souphttpsrc gstsouphttpsrc.c:1320:gst_soup_http_src_got_headers:<source> calling gst_element_found_tags with taglist, organization=(string)Jazz24;
0:00:00.692806861  7310   0x2daa00 WARN             souphttpsrc gstsouphttpsrc.c:1390:gst_soup_http_src_parse_status:<source> error: Server sent bad data.
0:00:00.693293057  7310   0x2daa00 WARN             souphttpsrc gstsouphttpsrc.c:1390:gst_soup_http_src_parse_status:<source> error: Message Corrupt (8), URL: http://35.230.27.165/ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1, Redirect to: (NULL)
0:00:00.694042584  7310   0x2daa00 DEBUG            souphttpsrc gstsouphttpsrc.c:1826:gst_soup_http_src_create:<source> Returning -5 error
0:00:00.707121531  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1933:gst_soup_http_src_unlock:<source> unlock()
0:00:00.707424811  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1946:gst_soup_http_src_unlock_stop:<source> unlock_stop()
0:00:00.707505540  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1868:gst_soup_http_src_stop:<source> stop()
0:00:00.707570592  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_session_close:<source> Closing session
0:00:00.707863351  7310   0x384b00 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_session_close:<source> Closing session
Done discovering http://35.230.27.165/ppm-jazz24mp3-ibc1?session-id=afdf98cfef971af35cc7b164dd157085&source=TuneIn&gdpr_consent=CPEPdoXPEPdoXAcABBENBUCgAAAAAH_AACiQAAAPVAAAAQIAACAAECCABIAAAAAAAAAAEAAAAAAAAAAADAAAAAAIAAAAAAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAIAAACAAAAAAAAAAAAAAQAAAAAAAAAAAAABAAAAWignkrAEou9jTCEMosAKBR_RUYCJUggWBkAAA.YAAAD_gAAAAA&gdpr=1
An error was encountered while discovering the file
 Server sent bad data.

Libsoup doesn't like something about that header. It's probably https://gitlab.com/goodvibes/goodvibes/-/issues/114 which basically says the server generated a malformed response and libsoup's parser is too strict to accept it. Fortunately we can see it has since been fixed and that fix will be included in the next release of Debian which is due in a few months. Note there is usually some delay until there's a Raspbian release based on that new Debian release. Sometimes you can avoid waiting by manually install a newer version of a package but that's not the case here due to other unmet dependencies.

So unfortunately still nothing we can do about this until Debian 11.

ThomTrab commented 3 years ago

Thank you very much for your time and explanation. :+1:

The issue lying in the GStreamer lib was my fear. So I will wait, patiently, the release of Debian 11 :wink: