search

voc / plugin.video.media-ccc-de

Kodi plugin for https://media.ccc.de/
Other
43 stars 4 forks source link

streaming live events from 33c3 doesn't work. #12

Closed boettner closed 7 years ago

boettner commented 7 years ago

My Kodi 16.1 Git:c327c53 on RaspberryPi + Hifiberry Amp+ doesn't play 33c3 livestreams. Here is some debug logging output:

12:09:37 2295.155762 T:1962983424   DEBUG: OnPlayMedia https://cdn.c3voc.de/hls/s1_native_hd.m3u8
12:09:37 2295.158203 T:1962983424    INFO: easy_aquire - Created session to https://cdn.c3voc.de
12:09:38 2295.741699 T:1962983424   DEBUG: CCurlFile::GetMimeType - https://cdn.c3voc.de/hls/s1_native_hd.m3u8 -> application/vnd.apple.mpegurl
12:09:38 2295.742432 T:1962983424   DEBUG: DoWork - Saving file state for audio item plugin://plugin.audio.radio_de/station/9770
12:09:38 2295.778320 T:1962983424   DEBUG: Loading settings for https://cdn.c3voc.de/hls/s1_native_hd.m3u8
12:09:38 2295.802979 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers(https://cdn.c3voc.de/hls/s1_native_hd.m3u8)
12:09:38 2295.803223 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:09:38 2295.803467 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:09:38 2295.803467 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
12:09:38 2295.803711 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:09:38 2295.803955 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
12:09:38 2295.803955 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:09:38 2295.804199 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:09:38 2295.804199 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
12:09:38 2295.804443 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
12:09:38 2295.804443 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
12:09:38 2295.804688 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:09:38 2295.804688 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:09:38 2295.804688 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:09:38 2295.804932 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:09:38 2295.804932 T:1962983424   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
12:09:38 2295.805176 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:09:38 2295.805420 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
12:09:38 2295.805420 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
12:09:38 2295.805664 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:09:38 2295.805664 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:09:38 2295.805908 T:1962983424   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
12:09:38 2295.906738 T:1886380960   DEBUG: CActiveAE::DiscardStream - audio stream deleted
12:09:38 2295.906982 T:1886380960   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
12:09:38 2296.015137 T:1534813088   DEBUG: Previous line repeats 1 times.
12:09:38 2296.017090 T:1534813088   DEBUG: Thread FileCache 1534813088 terminating
12:09:38 2296.017822 T:1706529696   DEBUG: Thread ShoutcastFile 1706529696 terminating
12:09:38 2296.018311 T:1486492576   DEBUG: OnPlayBackStopped: play state was 2, starting 1
12:09:38 2296.018555 T:1486492576   DEBUG: Thread PAPlayer 1486492576 terminating
12:09:38 2296.104980 T:1962983424   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
12:09:38 2296.105225 T:1962983424  NOTICE: DVDPlayer: Opening: https://cdn.c3voc.de/hls/s1_native_hd.m3u8
12:09:38 2296.105469 T:1962983424 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:09:38 2296.105713 T:1962983424   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
12:09:38 2296.105713 T:1962983424   DEBUG: CMMALRenderer::UnInitMMAL pool((nil))
12:09:38 2296.105713 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffers
12:09:38 2296.105957 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:0
12:09:38 2296.105957 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:1
12:09:38 2296.106201 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:2
12:09:38 2296.106201 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:3
12:09:38 2296.106201 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:4
12:09:38 2296.106201 T:1962983424   DEBUG: CMMALRenderer::ReleaseBuffer - not configured: source:5
12:09:38 2296.106445 T:1962983424   DEBUG: CMMALRenderer::PreInit
12:09:38 2296.106689 T:1486492576   DEBUG: Thread DVDPlayer start, auto delete: false
12:09:38 2296.108154 T:1486492576  NOTICE: Creating InputStream
12:09:38 2296.109131 T:1486492576   DEBUG: CFileCache::Open - opening <hls/s1_native_hd.m3u8> using cache
12:09:38 2296.109619 T:1486492576   DEBUG: CurlFile::Open(0x5de47f50) https://cdn.c3voc.de/hls/s1_native_hd.m3u8
12:09:38 2296.305664 T:1953493920    INFO: CActiveAESink::OpenSink - initialize sink
12:09:39 2296.506592 T:1953493920   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
12:09:39 2296.506836 T:1953493920    INFO: CAESinkALSA::Initialize - Attempting to open device "@"
12:09:39 2296.514160 T:1953493920    INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
12:09:39 2296.516113 T:1953493920    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
12:09:39 2296.516357 T:1953493920    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
12:09:39 2296.516357 T:1953493920   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
12:09:39 2296.517822 T:1953493920   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
12:09:39 2296.517822 T:1953493920   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
12:09:39 2296.518555 T:1953493920   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
12:09:39 2296.518555 T:1953493920   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
12:09:39 2296.518555 T:1953493920   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none)
12:09:39 2296.518799 T:1953493920   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
12:09:39 2296.518799 T:1953493920   DEBUG:   Output Device : Default (snd_rpi_hifiberry_amp Analog)
12:09:39 2296.518799 T:1953493920   DEBUG:   Sample Rate   : 44100
12:09:39 2296.518799 T:1953493920   DEBUG:   Sample Format : AE_FMT_S24NE4
12:09:39 2296.519043 T:1953493920   DEBUG:   Channel Count : 2
12:09:39 2296.519043 T:1953493920   DEBUG:   Channel Layout: FL,FR
12:09:39 2296.519043 T:1953493920   DEBUG:   Frames        : 2205
12:09:39 2296.519287 T:1953493920   DEBUG:   Frame Samples : 4410
12:09:39 2296.519287 T:1953493920   DEBUG:   Frame Size    : 8
12:09:39 2296.527588 T:1886380960   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
12:09:39 2296.770264 T:1486492576   DEBUG: CCurlFile::Open - effective URL: <https://dekan.cch.c3voc.de:443/hls/s1_native_hd.m3u8>
12:09:39 2296.770508 T:1706529696   DEBUG: Thread FileCache start, auto delete: false
12:09:39 2296.770996 T:1706529696    INFO: CFileCache::Process - Hit eof.
12:09:39 2296.771729 T:1706529696   DEBUG: Thread FileCache 1706529696 terminating
12:09:39 2296.771973 T:1486492576  NOTICE: Creating Demuxer
12:09:39 2296.772949 T:1486492576   DEBUG: ffmpeg[589A13A0]: Using network protocols without global network initialization. Please use avformat_network_init(), this will become mandatory later.
12:09:39 2296.856689 T:1962983424   DEBUG: Previous line repeats 2 times.
12:09:39 2296.856934 T:1962983424   DEBUG: ------ Window Init (DialogBusy.xml) ------
12:09:39 2296.858887 T:1486492576   ERROR: ffmpeg[589A13A0]: [tls] Unable to negotiate TLS/SSL session
12:09:39 2296.859375 T:1486492576   DEBUG: Error, could not open file https://cdn.c3voc.de/hls/s1_native_hd.m3u8
12:09:39 2296.859619 T:1486492576   ERROR: OpenDemuxStream - Error creating demuxer
TobiX commented 7 years ago

That looks like your distribution is using a broken/old TLS setup:

12:09:39 2296.858887 T:1486492576   ERROR: ffmpeg[589A13A0]: [tls] Unable to negotiate TLS/SSL session

Is that Raspbian or OpenElec or something else? The streaming servers only accept TLS 1.2

mattsches commented 7 years ago

Same error here with OpenELEC, upgraded to the latest LibreELEC 7.0.2 (on Raspi 2, Kodi 16.1), error still appears. Could it be some problem with TLS?

12:52:01 132.105042 T:1787794336   ERROR: ffmpeg[6A8F93A0]: [tls] Unable to negotiate TLS/SSL session
12:52:01 132.105576 T:1787794336   ERROR: OpenDemuxStream - Error creating demuxer

Update: Looks like a bug in OpenELEC/LibreELEC that has been fixed (but not in the stable version?): https://libreelec.tv/2016/10/libreelec-krypton-v7-90-008-alpha/

boettner commented 7 years ago

That's it @mattsches! I'm using Libreelec 7.0.2, too. Thanks all for your quick help!

TobiX commented 7 years ago

@boettner Do you have a fix? I could add an option to disable TLS as a quick workaround..

boettner commented 7 years ago

Updating to latest Libreelec "works for me".

How to is described here: https://wiki.libreelec.tv/index.php?title=HOW_TO:Update_LibreELEC

mattsches commented 7 years ago

Just updated to LibreELEC (Krypton) v7.90.010 ALPHA (http://releases.libreelec.tv/LibreELEC-RPi2.arm-7.90.010.tar) and can confirm that it works (for me) now \o/

TobiX commented 7 years ago

If you don't want (or cannot) update, please try the new version from http://is.gd/ccckodi