#13608: IPTV Streaming issues with AES-128 encryption
-------------------------------------+------------------------------
Reporter: steven-ellis | Owner: (none)
Type: Bug Report - General | Status: new
Priority: minor | Milestone: needs_triage
Component: MythTV - HTTP Streaming | Version: v31-fixes
Severity: medium | Resolution:
Keywords: | Ticket locked: 0
-------------------------------------+------------------------------
Comment (by steven-ellis):
Some log output from the backend
{{{
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
recorders/streamhandler.cpp:41 (AddListener)
SH[48](
https://d1irqd0o6b6kl5.cloudfront.net/master.m3u8(0)):
AddListener(0x7f1ec801a0b0) -- begin
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
recorders/streamhandler.cpp:53 (AddListener)
SH[48](
https://d1irqd0o6b6kl5.cloudfront.net/master.m3u8(0)):
AddListener(0x7f1ec801a0b0) -- locked
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
recorders/streamhandler.cpp:75 (AddListener)
SH[48](
https://d1irqd0o6b6kl5.cloudfront.net/master.m3u8(0)):
AddListener(0x7f1ec801a0b0) -- end
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
mpeg/mpegstreamdata.cpp:75 (SetDesiredProgram)
MPEGStream[48](0x7f1ec801a0b0): SetDesiredProgram(0)
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:1988 (SetupDTVSignalMonitor) TVRec[48]: Successfully set up
MPEG table monitoring.
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3825
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[48]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3826
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3831
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(DummyRecorderRunning,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, @
tv_rec.cpp:3880
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:3881 (TuningFrequency) DummyDTVRecorder -- started
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(RingBufferReady,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
@ tv_rec.cpp:3882
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[48]: ClearFlags(NeedToStartRecorder,)
->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
@ tv_rec.cpp:3892
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
@ tv_rec.cpp:3894
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I TVRecEvent
tv_rec.cpp:4012 (TuningSignalCheck) TVRec[48]: TuningSignalCheck: Still
waiting. Will timeout @ 17:49:50.000
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I ProcessRequest
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(CancelNextRecording,) ->
RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
@ tv_rec.cpp:2691
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:330 (ParseM3U8) HLSReader: Meta index file
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXTM3U
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-VERSION:3
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X
-INDEPENDENT-SEGMENTS
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-
INF:BANDWIDTH=582326,AVERAGE-BANDWIDTH=581245,RESOLUTION=480x270,FRAME-
RATE=25.000,CODECS="avc1.42C015,mp4a.40.2"
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from
https://d1irqd0o6b6kl5.cloudfront.net/master_15.m3u8 Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected
PROGRAM-ID=<value>, using -1
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected
(program-id=-1, bandwidth=582326
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1
bitrate 582326
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-
INF:BANDWIDTH=934300,AVERAGE-BANDWIDTH=911210,RESOLUTION=640x360,FRAME-
RATE=25.000,CODECS="avc1.42C01E,mp4a.40.2"
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from
https://d1irqd0o6b6kl5.cloudfront.net/master_16.m3u8 Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected
PROGRAM-ID=<value>, using -1
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected
(program-id=-1, bandwidth=934300
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1
bitrate 934300
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-
INF:BANDWIDTH=1403630,AVERAGE-BANDWIDTH=1351210,RESOLUTION=960x540,FRAME-
RATE=25.000,CODECS="avc1.4D401F,mp4a.40.2"
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from
https://d1irqd0o6b6kl5.cloudfront.net/master_17.m3u8 Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected
PROGRAM-ID=<value>, using -1
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected
(program-id=-1, bandwidth=1403630
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1
bitrate 1403630
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-
INF:BANDWIDTH=1872965,AVERAGE-BANDWIDTH=1791210,RESOLUTION=1024x576,FRAME-
RATE=25.000,CODECS="avc1.4D401F,mp4a.40.2"
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from
https://d1irqd0o6b6kl5.cloudfront.net/master_18.m3u8 Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected
PROGRAM-ID=<value>, using -1
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected
(program-id=-1, bandwidth=1872965
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1
bitrate 1872965
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-
INF:BANDWIDTH=2928996,AVERAGE-BANDWIDTH=2781245,RESOLUTION=1280x720,FRAME-
RATE=25.000,CODECS="avc1.4D401F,mp4a.40.2"
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8 Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected
PROGRAM-ID=<value>, using -1
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected
(program-id=-1, bandwidth=2928996
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1
bitrate 2928996
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:124 (Open)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8: Selected stream with
2928996 bitrate
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:134 (Open)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8: Open -- end
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I thread_unknown
recorders/HLS/HLSPlaylistWorker.cpp:36 (run)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8 playlist: run --
begin
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I thread_unknown
recorders/HLS/HLSStreamWorker.cpp:37 (run)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8 worker: run -- begin
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/HLS/HLSReader.cpp:194 (Throttle)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8: Throttle(true)
Apr 16 17:47:40 mythtv mythbackend: mythbackend[29760]: I ProcessRequest
tv_rec.cpp:4425 (SetFlags) TVRec[48]: SetFlags(FrontendReady,) ->
FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
@ ../../libs/libmythtv/tv_rec.h:165
Apr 16 17:47:41 mythtv mythbackend: mythbackend[29760]: I HLSPlaylist
HLS/m3u.cpp:329 (ParseKey)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8playback of AES-128
encrypted HTTP Live media detected.
Apr 16 17:47:42 mythtv mythbackend: mythbackend[29760]: I HLSStream
recorders/HLS/HLSReader.cpp:853 (LoadSegments)
https://d1irqd0o6b6kl5.cloudfront.net/master_19.m3u8: Throttling --
sleeping 2 secs.
Apr 16 17:47:43 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/hlsstreamhandler.cpp:184 (run)
HLSSH[48](
https://d1irqd0o6b6kl5.cloudfront.net/master.m3u8(0)): Packet
not starting with SYNC Byte (got 0x0K)
Apr 16 17:47:43 mythtv mythbackend: mythbackend[29760]: I StreamHandler
recorders/hlsstreamhandler.cpp:184 (run)
HLSSH[48](
https://d1irqd0o6b6kl5.cloudfront.net/master.m3u8(0)): Packet
not starting with SYNC Byte (got 0x0<)
}}}
Playback stalls on "Packet not starting with SYNC Byte"
--
Ticket URL: <
https://code.mythtv.org/trac/ticket/13608#comment:1>
MythTV <
http://www.mythtv.org>
MythTV Media Center