Bug #5319: IPTV Stream in SAT-IP Server "stops" - Tvheadend

Bug #5319

IPTV Stream in SAT-IP Server "stops"

Added by Flole Systems almost 6 years ago. Updated almost 6 years ago.

Status:

Accepted

Priority:

Normal

Assignee:

Category:

Target version:

Start date:

2018-11-08

Due date:

% Done:

Estimated time:

Found in version:

4.3-1524~ga093b4371

Affected Versions:

Description

I'm receiving an IPTV Stream from a surveillance camera and I am trying to use tvheadend to provide it in the SAT-IP Network. I have given it a Frequency on DVB-C and my TV (the client) found it. When I switch to that channel, it works perfectly fine at first (for like 5 seconds), then the output in the subscription tab goes to 0 and the streams stops (the input is still normal, and also debugging mpegts shows that there's data coming in).

What would modules I have to debug/trace in this case? I haven't found any obvious mistakes, especially since it works for the first few seconds.

Files

ARD settings.PNG (36.8 KB) ARD settings.PNG

Flole Systems, 2018-11-09 19:32

History

Updated by Hanspeter Müller almost 6 years ago

Hi,

i was not aware that you can use IPTV (or any other non-DVB sources) via SAT>IP, how did you configure that? For the problem itself, just check the normal syslog, usually you can at least see some hints if the problem is on the source or the sat>ip part...

Updated by Jaroslav Kysela almost 6 years ago

Did you tried to play the SAT>IP stream in VLC ?

Updated by Flole Systems almost 6 years ago

Yes I just tried VLC player, same symptoms: Few seconds of stream (length is random, also VLC has some lags in there), then VLC stops playback right after the Web IF shows output bandwidth of 0. Server and clients are powerful enough (They can even handle 4K streams when using "normal" HTTP Streaming and pass as transport)

Updated by Jaroslav Kysela almost 6 years ago

Ok, I'll give a test when I'll have more time (2 weeks or so) with my IP cameras. Perhaps, the problem might be the missing audio track or so.

Updated by Flole Systems almost 6 years ago

I have tried with a source with an audio track, same problem. This also happens when I use an IPTV stream as source instead of my camera.

Updated by Jaroslav Kysela almost 6 years ago

I cannot confirm here. Testing the ipcam input through ffmpeg pipe (to join the video/audio streams). Show '--trace iptv,iptv-pcr,mpegts,service,subscription,satips' . https://tvheadend.org/projects/tvheadend/wiki/Traces

Updated by Flole Systems almost 6 years ago

In VLC I am getting the following in the logs:

main debug: no meta reader modules matched
main debug: `rtsp://a.b.c.d/?freq=194.01&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=256,257,4096' successfully opened
ts debug: pid[256] unknown
ts debug: first packet for pid=256 cc=0x3
ts debug: pid[4096] unknown
ts debug: first packet for pid=4096 cc=0x9
ts debug: pid[257] unknown
ts debug: first packet for pid=257 cc=0xc
satip warning: Gap in seq_nr (269 > 265), probably lost a packet
ts warning: discontinuity received 0x9 instead of 0xe (pid=256)
satip warning: Gap in seq_nr (275 > 271), probably lost a packet
ts warning: discontinuity received 0x2 instead of 0x7 (pid=256)
ts warning: discontinuity received 0x8 instead of 0x6 (pid=4096)
ts debug: PATCallBack called
ts debug: new PAT ts_id=0 version=1 current_next=1
ts debug: * number=1234 pid=1337
ts debug: temporary receiving program 1234
ts debug: PMTCallBack called for program 1234
ts debug: new PMT program number=1234 version=1 pid_pcr=256
ts debug: * pid=256 type=0x1b AVC video stream as defined in ITU-T Rec. H.264
ts debug: => pid 256 has now es fcc=h264
main debug: selecting program id=1234
main debug: looking for packetizer module matching "any": 24 candidates
main debug: using packetizer module "h264" 
main debug: looking for video decoder module matching "any": 17 candidates
avcodec debug: using ffmpeg Lavc58.6.103
avcodec debug: CPU flags: 0x000fd3db
avcodec debug: allowing 6 thread(s) for decoding
satip warning: Gap in seq_nr (441 > 434), probably lost a packet
satip warning: Gap in seq_nr (451 > 444), probably lost a packet
satip warning: Gap in seq_nr (461 > 454), probably lost a packet
avcodec debug: codec (h264) started
satip warning: Gap in seq_nr (479 > 462), probably lost a packet
avcodec debug: using frame thread mode with 6 threads
main debug: using video decoder module "avcodec" 
ts debug: Default program is 1234
ts debug: * pid=17 listening for SDT
ts debug: enabling pid 256 from program 1234
ts debug: enabling pcr pid 256 from program 1234
ts debug: first packet for pid=256 cc=0xf
satip warning: Gap in seq_nr (497 > 496), probably lost a packet
main debug: Buffering 0%
satip warning: Gap in seq_nr (499 > 498), probably lost a packet
satip warning: Gap in seq_nr (506 > 500), probably lost a packet
main debug: Buffering 33%
main debug: Buffering 73%
ts warning: discontinuity received 0x4 instead of 0x3 (pid=4096)
satip warning: Gap in seq_nr (577 > 573), probably lost a packet
ts warning: discontinuity received 0x3 instead of 0xf (pid=256)
satip warning: Gap in seq_nr (587 > 578), probably lost a packet
ts warning: discontinuity received 0xb instead of 0x5 (pid=4096)
satip warning: Gap in seq_nr (597 > 591), probably lost a packet
satip warning: Gap in seq_nr (599 > 598), probably lost a packet
satip warning: Gap in seq_nr (613 > 600), probably lost a packet
satip warning: Gap in seq_nr (615 > 614), probably lost a packet
ts warning: discontinuity received 0xe instead of 0x7 (pid=256)
ts warning: discontinuity received 0xc instead of 0x5 (pid=256)
ts warning: discontinuity received 0xc instead of 0x3 (pid=256)
main debug: Stream buffering done (460 ms in 2 ms)
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
main error: buffer deadlock prevented
main debug: Decoder wait done in 2 ms
ts warning: discontinuity received 0xf instead of 0xe (pid=4096)
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0x1 instead of 0x5 (pid=256)
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0x5 instead of 0x7 (pid=256)
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0xd instead of 0xc (pid=4096)
ts warning: discontinuity received 0x9 instead of 0x0 (pid=256)
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0x7 instead of 0x0 (pid=256)
ts warning: discontinuity received 0x6 instead of 0xe (pid=256)
ts warning: discontinuity received 0x4 instead of 0xd (pid=256)
ts warning: discontinuity received 0x2 instead of 0xe (pid=4096)
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
h264 warning: waiting for SPS/PPS
satip warning: Gap in seq_nr (714 > 707), probably lost a packet
satip warning: Gap in seq_nr (719 > 715), probably lost a packet
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0xa instead of 0xf (pid=256)
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0x4 instead of 0x2 (pid=4096)
satip warning: Gap in seq_nr (739 > 734), probably lost a packet
satip warning: Gap in seq_nr (743 > 740), probably lost a packet
ts warning: discontinuity received 0x2 instead of 0x0 (pid=256)
h264 warning: waiting for SPS/PPS
ts warning: discontinuity received 0xe instead of 0x9 (pid=256)
h264 debug: found NAL_SPS (sps_id=0)
ts warning: discontinuity received 0x8 instead of 0x7 (pid=4096)
h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
satip warning: Gap in seq_nr (763 > 756), probably lost a packet
satip warning: Gap in seq_nr (769 > 765), probably lost a packet
ts warning: discontinuity received 0xf instead of 0x4 (pid=256)
ts warning: discontinuity received 0xd instead of 0xa (pid=4096)
satip warning: Gap in seq_nr (785 > 776), probably lost a packet
ts warning: discontinuity received 0xd instead of 0xf (pid=256)
satip warning: Gap in seq_nr (791 > 787), probably lost a packet
ts warning: discontinuity received 0xf instead of 0xe (pid=4096)
main debug: restarting module due to input format change
ts warning: discontinuity received 0x6 instead of 0xa (pid=256)
main debug: removing module "avcodec" 
satip warning: Gap in seq_nr (820 > 802), probably lost a packet
ts warning: discontinuity received 0xc instead of 0x1 (pid=256)
ts warning: discontinuity received 0x5 instead of 0x2 (pid=4096)
satip warning: Gap in seq_nr (840 > 835), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x2 (pid=256)
satip warning: Gap in seq_nr (850 > 841), probably lost a packet
satip warning: Gap in seq_nr (854 > 852), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0xb (pid=256)
ts warning: discontinuity received 0x4 instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (857 > 856), probably lost a packet
ts warning: discontinuity received 0xb instead of 0x8 (pid=4096)
ts warning: discontinuity received 0x8 instead of 0x1 (pid=256)
satip warning: Gap in seq_nr (877 > 875), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0x3 (pid=256)
satip warning: Gap in seq_nr (890 > 879), probably lost a packet
ts warning: discontinuity received 0xa instead of 0xf (pid=256)
ts warning: discontinuity received 0x1 instead of 0xf (pid=4096)
satip warning: Gap in seq_nr (909 > 906), probably lost a packet
ts warning: discontinuity received 0xb instead of 0x7 (pid=256)
ts warning: discontinuity received 0x5 instead of 0x4 (pid=4096)
satip warning: Gap in seq_nr (928 > 920), probably lost a packet
ts warning: discontinuity received 0xd instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (934 > 930), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0x2 (pid=256)
ts warning: discontinuity received 0x9 instead of 0x6 (pid=4096)
satip warning: Gap in seq_nr (971 > 956), probably lost a packet
ts warning: discontinuity received 0x3 instead of 0xc (pid=256)
satip warning: Gap in seq_nr (979 > 974), probably lost a packet
ts warning: discontinuity received 0xf instead of 0xd (pid=4096)
ts warning: discontinuity received 0x9 instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (988 > 986), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0x0 (pid=4096)
satip warning: Gap in seq_nr (991 > 989), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x9 (pid=256)
ts warning: discontinuity received 0xb instead of 0xe (pid=256)
ts warning: discontinuity received 0x3 instead of 0x2 (pid=4096)
satip warning: Gap in seq_nr (1010 > 1009), probably lost a packet
ts warning: discontinuity received 0xd instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (1019 > 1014), probably lost a packet
ts warning: discontinuity received 0xb instead of 0x8 (pid=256)
satip warning: Gap in seq_nr (1028 > 1025), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0x3 (pid=256)
satip warning: Gap in seq_nr (1037 > 1034), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0x1 (pid=256)
ts warning: discontinuity received 0xb instead of 0xa (pid=4096)
satip warning: Gap in seq_nr (1046 > 1043), probably lost a packet
ts warning: discontinuity received 0x3 instead of 0xe (pid=256)
ts warning: discontinuity received 0xc instead of 0x5 (pid=257)
satip warning: Gap in seq_nr (1103 > 1091), probably lost a packet
satip warning: Gap in seq_nr (1113 > 1105), probably lost a packet
satip warning: Gap in seq_nr (1123 > 1115), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0x6 (pid=256)
ts warning: discontinuity received 0xc instead of 0x6 (pid=256)
ts warning: discontinuity received 0x1 instead of 0xa (pid=256)
main debug: looking for video decoder module matching "any": 17 candidates
avcodec debug: using ffmpeg Lavc58.6.103
avcodec debug: CPU flags: 0x000fd3db
avcodec debug: allowing 6 thread(s) for decoding
avcodec debug: codec (h264) started
avcodec debug: using frame thread mode with 6 threads
main debug: using video decoder module "avcodec" 
avcodec debug: available hardware decoder output format 119 (cuda)
avcodec debug: available hardware decoder output format 53 (dxva2_vld)
avcodec debug: available hardware decoder output format 118 (d3d11va_vld)
avcodec debug: available hardware decoder output format 174 (d3d11)
avcodec debug: available software decoder output format 0 (yuv420p)
avcodec debug: trying format d3d11va_vld
main debug: looking for text renderer module matching "any": 3 candidates
freetype debug: Using DWrite backend
freetype debug: DWrite_GetFamily(): family name: Arial
freetype debug: DWrite_ParseFamily(): using font at index 0 with weight 400 for bold: 0, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 1 with weight 700 for bold: 1, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 2 with weight 400 for bold: 0, italic: 1
freetype debug: DWrite_ParseFamily(): using font at index 3 with weight 700 for bold: 1, italic: 1
main debug: using text renderer module "freetype" 
main debug: looking for video converter module matching "any": 24 candidates
swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video converter module "swscale" 
main debug: looking for video converter module matching "any": 24 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video converter module "yuvp" 
main debug: Deinterlacing available
main debug: deinterlace -1, mode auto, is_needed 0
main debug: looking for vout window module matching "qt,any": 3 candidates
qt debug: requesting video window...
satip warning: Gap in seq_nr (1129 > 1124), probably lost a packet
ts warning: discontinuity received 0xa instead of 0x8 (pid=256)
ts warning: discontinuity received 0xa instead of 0x4 (pid=4096)
satip warning: Gap in seq_nr (1250 > 1248), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (1252 > 1251), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0xa (pid=256)
satip warning: Gap in seq_nr (1311 > 1309), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (1313 > 1312), probably lost a packet
ts warning: discontinuity received 0x2 instead of 0xb (pid=256)
ts warning: discontinuity received 0xa instead of 0x9 (pid=4096)
satip warning: Gap in seq_nr (1331 > 1328), probably lost a packet
ts warning: discontinuity received 0xd instead of 0x8 (pid=256)
satip warning: Gap in seq_nr (1351 > 1345), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0xc (pid=256)
satip warning: Gap in seq_nr (1357 > 1352), probably lost a packet
ts warning: discontinuity received 0xe instead of 0xc (pid=256)
ts warning: discontinuity received 0x2 instead of 0x0 (pid=4096)
satip warning: Gap in seq_nr (1377 > 1371), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0xe (pid=256)
satip warning: Gap in seq_nr (1384 > 1378), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0xe (pid=256)
ts warning: discontinuity received 0x6 instead of 0x4 (pid=4096)
satip warning: Gap in seq_nr (1416 > 1413), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0xd (pid=256)
ts warning: discontinuity received 0xc instead of 0xb (pid=4096)
satip warning: Gap in seq_nr (1433 > 1429), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0xa (pid=256)
ts warning: discontinuity received 0xf instead of 0xe (pid=4096)
satip warning: Gap in seq_nr (1444 > 1442), probably lost a packet
ts warning: discontinuity received 0x0 instead of 0x3 (pid=256)
satip warning: Gap in seq_nr (1454 > 1445), probably lost a packet
satip warning: Gap in seq_nr (1458 > 1455), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0x7 (pid=256)
ts warning: discontinuity received 0x2 instead of 0x0 (pid=4096)
ts warning: discontinuity received 0x0 instead of 0xb (pid=256)
satip warning: Gap in seq_nr (1478 > 1472), probably lost a packet
ts warning: discontinuity received 0x9 instead of 0x0 (pid=256)
satip warning: Gap in seq_nr (1481 > 1479), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0x5 (pid=4096)
ts warning: discontinuity received 0xd instead of 0xf (pid=256)
satip warning: Gap in seq_nr (1501 > 1497), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0xa (pid=256)
satip warning: Gap in seq_nr (1560 > 1555), probably lost a packet
ts warning: discontinuity received 0x9 instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (1570 > 1567), probably lost a packet
ts warning: discontinuity received 0xd instead of 0x8 (pid=256)
satip warning: Gap in seq_nr (1580 > 1572), probably lost a packet
satip warning: Gap in seq_nr (1587 > 1581), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0xb (pid=256)
ts warning: discontinuity received 0x1 instead of 0x8 (pid=256)
ts warning: discontinuity received 0x9 instead of 0x6 (pid=4096)
satip warning: Gap in seq_nr (1607 > 1604), probably lost a packet
satip warning: Gap in seq_nr (1615 > 1608), probably lost a packet
ts warning: discontinuity received 0xa instead of 0x5 (pid=256)
ts warning: discontinuity received 0xe instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (1635 > 1630), probably lost a packet
ts warning: discontinuity received 0x9 instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (1639 > 1636), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x0 (pid=256)
ts warning: discontinuity received 0x2 instead of 0x0 (pid=4096)
satip warning: Gap in seq_nr (1658 > 1656), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (1660 > 1659), probably lost a packet
ts warning: discontinuity received 0x3 instead of 0xc (pid=256)
satip warning: Gap in seq_nr (1703 > 1697), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0xf (pid=256)
ts warning: discontinuity received 0xe instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (1725 > 1716), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0x0 (pid=4096)
ts warning: discontinuity received 0xf instead of 0x1 (pid=256)
satip warning: Gap in seq_nr (1745 > 1731), probably lost a packet
satip warning: Gap in seq_nr (1749 > 1746), probably lost a packet
ts warning: discontinuity received 0x2 instead of 0xe (pid=256)
ts warning: discontinuity received 0x6 instead of 0x3 (pid=4096)
satip warning: Gap in seq_nr (1769 > 1764), probably lost a packet
ts warning: discontinuity received 0xb instead of 0x9 (pid=256)
satip warning: Gap in seq_nr (1772 > 1771), probably lost a packet
ts warning: discontinuity received 0xf instead of 0x9 (pid=256)
satip warning: Gap in seq_nr (1778 > 1773), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (1780 > 1779), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0xf (pid=256)
ts warning: discontinuity received 0xb instead of 0x8 (pid=4096)
main debug: resized to 1920x872
main debug: resized to 1920x850
main debug: resized to 1920x1080
main debug: using vout window module "qt" 
main debug: looking for inhibit module matching "any": 1 candidates
main debug: using inhibit module "inhibit" 
main debug: Opening vout display wrapper
main debug: looking for vout display module matching "any": 12 candidates
direct3d11 debug: creating Vout EventThread
direct3d11 debug: Win32VoutCreateWindow
main debug: VoutDisplayEvent 'resize' 1920x1080
direct3d11 debug: inclinometer not found. (hr=0x80070490)
direct3d11 debug: created video sub-window
direct3d11 debug: Vout EventThread running
main debug: resized to 1920x850
main debug: VoutDisplayEvent 'resize' 1920x850
direct3d11 debug: Created the D3D11 device type 1 level b100.
direct3d11 debug: NVIDIA WDDM driver 25.21.14.1681
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:FULL
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:STUDIO
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:STUDIO
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:FULL
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:FULL
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:STUDIO
direct3d11 debug: Output max luminance: 270.0, colorspace RGB Rec.709 gamma:22 range:FULL, bits per pixel 8
direct3d11 debug: using colorspace RGB Rec.709 gamma:22 range:FULL
direct3d11 debug: Using pixel format VA_NV12 for chroma DX11
main debug: VoutDisplayEvent 'resize' 1938x1127
direct3d11 debug: Direct3D11 resources created
direct3d11 debug: Direct3D11 device adapter successfully initialized
direct3d11 debug: Direct3D11 Open Succeeded
main debug: using vout display module "direct3d11" 
direct3d11 debug: D3D11 pool succeed with 40 surfaces (1920x1152) context 0x0c0de510
main debug: original format sz 1920x1088, of (0,0), vsz 1920x1080, 4cc DX11, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype" 
main debug: VoutDisplayEvent 'resize' 1920x850
main debug: looking for text renderer module matching "any": 3 candidates
direct3d11 debug: Detected size change 1511x850
main debug: resized to 1920x850
freetype debug: Using DWrite backend
main debug: VoutDisplayEvent 'resize' 1920x850
freetype debug: DWrite_GetFamily(): family name: Arial
freetype debug: DWrite_ParseFamily(): using font at index 0 with weight 400 for bold: 0, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 1 with weight 700 for bold: 1, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 2 with weight 400 for bold: 0, italic: 1
freetype debug: DWrite_ParseFamily(): using font at index 3 with weight 700 for bold: 1, italic: 1
main debug: using text renderer module "freetype" 
main debug: looking for hw decoder module matching "any": 2 candidates
d3d11va debug: NVIDIA WDDM driver 25.21.14.1681
d3d11va debug: Reusing Direct3D11 device
d3d11va debug: CreateDevice succeed
d3d11va debug: - 'MPEG-2 & MPEG-1 variable-length decoder' is supported
d3d11va debug: - 'MPEG-2 variable-length decoder' is supported
d3d11va debug: - 'MPEG-1 variable-length decoder, no D pictures' is supported
d3d11va debug: - 'VC-1 variable-length decoder' is supported
d3d11va debug: - 'VC-1 variable-length decoder' is supported
d3d11va debug: - 'Unknown decoder 0x32fcfe3f-0xde46-0x4a49-0x861bac71110649d5' is supported
d3d11va debug: - 'H.264 stereo high profile, mbs flag set' is supported
d3d11va debug: - 'H.264 stereo high profile' is supported
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology' is supported
d3d11va debug: - 'HEVC Main profile' is supported
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple Profile' is supported
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, no GMC' is supported
d3d11va debug: - 'MPEG-4 Part 2 nVidia bitstream decoder' is supported
d3d11va debug: - 'Unknown decoder 0x33fcfe41-0xde46-0x4a49-0x861bac71110649d5' is supported
d3d11va debug: - 'Unknown decoder 0x6affd11e-0x1d96-0x42b1-0xa21593a31f09a53d' is supported
d3d11va debug: - 'Unknown decoder 0x914c84a3-0x4078-0x4fa9-0x984ce2f262cb5c9c' is supported
d3d11va debug: Trying to use 'H.264 variable-length decoder, no film grain technology' as input
d3d11va debug: NV12 output is supported for decoder H.264 variable-length decoder, no film grain technology.
d3d11va debug: Using output format NV12 for decoder H.264 variable-length decoder, no film grain technology
d3d11va debug: va_pool_SetupDecoder id 27 1920x1088 count: 24
d3d11va warning: not enough decoding slices in the texture (6/24)
d3d11va debug: ID3D11VideoDecoderOutputView succeed with 24 surfaces (1920x1088)
d3d11va debug: we got 2 decoder configurations
d3d11va debug: configuration[0] ConfigBitstreamRaw 1
d3d11va debug: configuration[1] ConfigBitstreamRaw 2
d3d11va debug: DxCreateDecoderSurfaces succeed
main debug: using hw decoder module "d3d11va" 
avcodec info: Using D3D11VA (NVIDIA GeForce GTX 980M, vendor 4318(NVIDIA), device 5079, revision 161) for hardware decoding
satip warning: Gap in seq_nr (1949 > 1946), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x3 (pid=256)
satip warning: Gap in seq_nr (1955 > 1951), probably lost a packet
ts warning: discontinuity received 0x0 instead of 0x5 (pid=256)
ts warning: discontinuity received 0xa instead of 0x8 (pid=4096)
satip warning: Gap in seq_nr (1971 > 1970), probably lost a packet
ts warning: discontinuity received 0xe instead of 0x7 (pid=256)
satip warning: Gap in seq_nr (1975 > 1972), probably lost a packet
satip warning: Gap in seq_nr (1985 > 1976), probably lost a packet
satip warning: Gap in seq_nr (1988 > 1986), probably lost a packet
ts warning: discontinuity received 0x9 instead of 0x4 (pid=256)
ts warning: discontinuity received 0xd instead of 0x0 (pid=256)
ts warning: discontinuity received 0x2 instead of 0x4 (pid=256)
ts warning: discontinuity received 0xf instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (2008 > 2001), probably lost a packet
satip warning: Gap in seq_nr (2013 > 2009), probably lost a packet
ts warning: discontinuity received 0xc instead of 0x1 (pid=256)
ts warning: discontinuity received 0x4 instead of 0x2 (pid=4096)
satip warning: Gap in seq_nr (2033 > 2029), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0xa (pid=256)
satip warning: Gap in seq_nr (2052 > 2034), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x9 (pid=256)
ts warning: discontinuity received 0xa instead of 0x6 (pid=4096)
satip warning: Gap in seq_nr (2072 > 2070), probably lost a packet
ts warning: discontinuity received 0xf instead of 0x2 (pid=256)
satip warning: Gap in seq_nr (2082 > 2073), probably lost a packet
satip warning: Gap in seq_nr (2092 > 2083), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x6 (pid=256)
ts warning: discontinuity received 0xf instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (2101 > 2095), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0xa (pid=256)
ts warning: discontinuity received 0x1 instead of 0x0 (pid=4096)
ts warning: discontinuity received 0x5 instead of 0xc (pid=256)
ts warning: discontinuity received 0x3 instead of 0x2 (pid=4096)
satip warning: Gap in seq_nr (2121 > 2114), probably lost a packet
satip warning: Gap in seq_nr (2130 > 2123), probably lost a packet
ts warning: discontinuity received 0x8 instead of 0x5 (pid=4096)
satip warning: Gap in seq_nr (2160 > 2156), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0xc (pid=256)
satip warning: Gap in seq_nr (2169 > 2162), probably lost a packet
satip warning: Gap in seq_nr (2190 > 2172), probably lost a packet
ts warning: discontinuity received 0xf instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (2199 > 2191), probably lost a packet
ts warning: discontinuity received 0x4 instead of 0x9 (pid=256)
ts warning: discontinuity received 0x2 instead of 0xb (pid=256)
ts warning: discontinuity received 0xc instead of 0xf (pid=257)
ts warning: discontinuity received 0x4 instead of 0x0 (pid=4096)
main debug: auto hiding mouse cursor
satip warning: Gap in seq_nr (2320 > 2318), probably lost a packet
ts warning: discontinuity received 0xc instead of 0xe (pid=256)
main warning: picture is too late to be displayed (missing 2657 ms)
main warning: picture is too late to be displayed (missing 2637 ms)
main warning: picture is too late to be displayed (missing 2617 ms)
main warning: picture is too late to be displayed (missing 2597 ms)
main warning: picture is too late to be displayed (missing 1297 ms)
main warning: picture is too late to be displayed (missing 721 ms)
main warning: picture is too late to be displayed (missing 701 ms)
main warning: picture is too late to be displayed (missing 681 ms)
main warning: picture is too late to be displayed (missing 661 ms)
main warning: picture is too late to be displayed (missing 41 ms)
satip warning: Gap in seq_nr (2578 > 2577), probably lost a packet
ts warning: discontinuity received 0xd instead of 0x6 (pid=256)
satip warning: Gap in seq_nr (2588 > 2579), probably lost a packet
ts warning: discontinuity received 0x2 instead of 0x4 (pid=256)
satip warning: Gap in seq_nr (2598 > 2591), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x6 (pid=4096)
satip warning: Gap in seq_nr (2604 > 2599), probably lost a packet
ts warning: discontinuity received 0xf instead of 0xd (pid=256)
ts warning: discontinuity received 0xa instead of 0x8 (pid=4096)
satip warning: Gap in seq_nr (2621 > 2620), probably lost a packet
satip warning: Gap in seq_nr (2624 > 2622), probably lost a packet
ts warning: discontinuity received 0x3 instead of 0xc (pid=256)
satip warning: Gap in seq_nr (2633 > 2627), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0xa (pid=256)
satip warning: Gap in seq_nr (2639 > 2635), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0xd (pid=256)
ts warning: discontinuity received 0xe instead of 0x3 (pid=256)
ts warning: discontinuity received 0x0 instead of 0xd (pid=4096)
satip warning: Gap in seq_nr (2659 > 2657), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0x9 (pid=256)
satip warning: Gap in seq_nr (2669 > 2662), probably lost a packet
ts warning: discontinuity received 0x5 instead of 0x3 (pid=4096)
satip warning: Gap in seq_nr (2677 > 2671), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0x8 (pid=256)
satip warning: Gap in seq_nr (2683 > 2678), probably lost a packet
ts warning: discontinuity received 0xa instead of 0x8 (pid=256)
ts warning: discontinuity received 0x8 instead of 0x6 (pid=4096)
satip warning: Gap in seq_nr (2713 > 2705), probably lost a packet
satip warning: Gap in seq_nr (2723 > 2714), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x1 (pid=256)
satip warning: Gap in seq_nr (2733 > 2725), probably lost a packet
ts warning: discontinuity received 0xb instead of 0xe (pid=256)
ts warning: discontinuity received 0x0 instead of 0x9 (pid=256)
satip warning: Gap in seq_nr (2742 > 2735), probably lost a packet
ts warning: discontinuity received 0x0 instead of 0xb (pid=4096)
ts warning: discontinuity received 0x2 instead of 0x1 (pid=4096)
satip warning: Gap in seq_nr (2762 > 2761), probably lost a packet
ts warning: discontinuity received 0x6 instead of 0xf (pid=256)
satip warning: Gap in seq_nr (2771 > 2764), probably lost a packet
ts warning: discontinuity received 0x7 instead of 0x6 (pid=4096)
ts warning: discontinuity received 0xc instead of 0xb (pid=257)
main warning: picture is too late to be displayed (missing 52 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main warning: picture is too late to be displayed (missing 12 ms)
main warning: picture is too late to be displayed (missing 39 ms)
satip warning: Gap in seq_nr (2787 > 2786), probably lost a packet
ts warning: discontinuity received 0x1 instead of 0xa (pid=256)
satip debug: timed out waiting for data...
ts debug: Can't read TS packet at 3283420
main debug: EOF reached
main debug: waiting decoder fifos to empty
main warning: picture is too late to be displayed (missing 301 ms)
main debug: killing decoder fourcc `h264'
main debug: removing module "avcodec" 
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "h264" 
main debug: removing module "ts" 
main debug: Program doesn't contain anymore ES
main debug: removing module "record" 
main debug: removing module "cache_block" 
main debug: removing module "satip" 
main debug: dead input
qt debug: IM: Deleting the input
main debug: changing item without a request (current 0/1)
main debug: nothing to play
main debug: destroying useless vout
main debug: removing module "direct3d11" 
direct3d11 debug: Direct3D11 resources destroyed
direct3d11 debug: Direct3D11 device adapter closed
direct3d11 error: SetThumbNailClip failed: 0x800706f4
direct3d11 debug: Win32 Vout EventThread terminating
direct3d11 debug: Win32VoutCloseWindow
direct3d11 debug: WinProc WM_DESTROY
main debug: removing module "inhibit" 
qt debug: releasing video...
qt debug: Video is not needed anymore
main debug: removing module "freetype" 
main debug: removing module "yuvp" 
main debug: removing module "swscale"

so it seems like there are indeed packets lost. TVHeadend shows this:

Nov  9 14:44:43  tvheadend[8996]: subscription: 02BB:  chain 01: input
Nov  9 14:44:43  tvheadend[8996]: subscription: 02BB:  chain 02: streaming queue 0x7f9108003c88 size 0
Nov  9 14:44:43  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,0,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:43  tvheadend[8996]: satips: RTSP/1.0 PLAY rtsp://a.b.c.d:554/stream=27{{CSeq=1,Session=1CFD7E33}}
Nov  9 14:44:43  tvheadend[8996]: satips: 0/1CFD7E33/27: PLAY from x.y.z.z:58090 DVB-C freq 194010000 sym 6900000 mod QAM/256 fec AUTO ds 0 plp -1 pids 256,257,4096
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - update PID 0100 (256) weight 400 [2/0x7f91080008d0]
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - update PID 0101 (257) weight 400 [2/0x7f91080008d0]
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - update PID 1000 (4096) weight 400 [2/0x7f91080008d0]
Nov  9 14:44:43  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,0,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:43  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,0,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add pat 00/00 (0) pid 0000 (0)
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - open PID 0000 (0) [20/0x7f91d4076760]
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add cat 01/FF (1) pid 0001 (1)
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - open PID 0001 (1) [16/0x7f91d406ef70]
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add nit 00/00 (0) pid 0010 (16)
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - open PID 0010 (16) [20/0x7f91d4070450]
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add sdt 40/F8 (64) pid 0011 (17)
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - open PID 0011 (17) [20/0x7f91d405ead0]
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add bat 48/F8 (72) pid 0011 (17)
Nov  9 14:44:43  tvheadend[8996]: mpegts: Cam1 in My IPCAM - open PID 0011 (17) [16/0x7f91d405ffb0]
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: first 343746146 last 343746146, time start 1987680618848, end 1987680618853
Nov  9 14:44:43  tvheadend[8996]: service: Cam1 in My IPCAM: Status changed to [Demuxed packets]
Nov  9 14:44:43  tvheadend[8996]: service: Cam1 in My IPCAM: Status changed to [Demuxed packets] [Reassembled packets]
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 5640 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: table: mux 0x5599524e7e30 add pmt 02/FF (2) pid 1000 (4096)
Nov  9 14:44:43  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,0,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343748666, time start 1987680646848, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 33464 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343749026, time start 1987680650848, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343751546, time start 1987680678849, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39480 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343751906, time start 1987680682849, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343756959, time end 1987680738999
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343754066, time start 1987680706850, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343767747, time end 1987680858866
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 26320 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343776747, time end 1987680958866
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343754426, time start 1987680710850, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 29140 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 24816 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343754786, time start 1987680714850, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343787548, time end 1987681078877
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 14476 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343756946, time start 1987680738850, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 29328 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343757306, time start 1987680742850, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343798348, time end 1987681198877
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 26132 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343807348, time end 1987681298877
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 42488 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20304 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 45308 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343818147, time end 1987681418866
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343757666, time start 1987680746851, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343759826, time start 1987680770851, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343828948, time end 1987681538878
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343839748, time end 1987681658878
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 40796 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343760186, time start 1987680774851, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343848748, time end 1987681758878
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39480 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343859549, time end 1987681878889
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 40984 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343870348, time end 1987681998878
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343881149, time end 1987682118889
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343890150, time end 1987682218901
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 40796 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343900949, time end 1987682338889
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 45308 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343911749, time end 1987682458889
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343760546, time start 1987680778851, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 32148 bytes
Nov  9 14:44:43  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343763786, time start 1987680814852, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 40984 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343920749, time end 1987682558890
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 40796 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343931550, time end 1987682678902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343942349, time end 1987682798890
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23500 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20304 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343764146, time start 1987680818852, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343951350, time end 1987682898902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343962150, time end 1987683018902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343972950, time end 1987683138902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39292 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343983750, time end 1987683258902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23500 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 343992751, time end 1987683358913
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 24816 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20304 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344003551, time end 1987683478913
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39480 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344014350, time end 1987683598902
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344025152, time end 1987683718924
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344034151, time end 1987683818913
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 36472 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343764506, time start 1987680822852, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344044954, time end 1987683938946
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343766306, time start 1987680842853, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344055752, time end 1987684058925
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343766666, time start 1987680846853, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 29140 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23500 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344066551, time end 1987684178914
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 24816 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344075551, time end 1987684278914
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344086353, time end 1987684398936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 58468 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344097153, time end 1987684518936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344107952, time end 1987684638925
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 62604 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344118753, time end 1987684758936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343767026, time start 1987680850853, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39480 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344127753, time end 1987684858936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344138553, time end 1987684978936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 35156 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344149353, time end 1987685098936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 37788 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344158353, time end 1987685198936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23500 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344169153, time end 1987685318936
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 77268 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344179954, time end 1987685438947
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 18988 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344188957, time end 1987685538980
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 23312 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21808 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: updated 343767386, time start 1987680854853, limit 5000000
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344199754, time end 1987685658947
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 39480 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344210555, time end 1987685778958
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 43804 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 21996 bytes
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20304 bytes
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: last 344221354, time end 1987685898947
Nov  9 14:44:43  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:43  tvheadend[8996]: mpegts: input IPTV #1 got 20492 bytes
Nov  9 14:44:44  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:44  tvheadend[8996]: message repeated 5 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: updated 343853788, time start 1987681814876, limit 5000000
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: last 344241156, time end 1987686118970
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: last 344260955, time end 1987686338959
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: last 344293356, time end 1987686698970
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: last 344313156, time end 1987686918970
Nov  9 14:44:44  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:44  tvheadend[8996]: mpegts: input IPTV #1 got 524332 bytes
Nov  9 14:44:44  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:45  tvheadend[8996]: message repeated 2 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:45  tvheadend[8996]: service: Cam1 in My IPCAM: Status changed to [Demuxed packets] [Reassembled packets] [CA check]
Nov  9 14:44:45  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:45  tvheadend[8996]: message repeated 3 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: pcr: updated 343943790, time start 1987682814899, limit 5000000
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: pcr: last 344354556, time end 1987687378971
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: pcr: last 344376157, time end 1987687618982
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: pcr: last 344395957, time end 1987687838982
Nov  9 14:44:45  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:45  tvheadend[8996]: mpegts: input IPTV #1 got 393108 bytes
Nov  9 14:44:45  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:46  tvheadend[8996]: message repeated 6 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: pcr: updated 344033792, time start 1987683814922, limit 5000000
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: pcr: last 344426558, time end 1987688178994
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: pcr: last 344446358, time end 1987688398994
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: pcr: last 344484159, time end 1987688819005
Nov  9 14:44:46  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:46  tvheadend[8996]: mpegts: input IPTV #1 got 393296 bytes
Nov  9 14:44:47  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:47  tvheadend[8996]: message repeated 5 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: updated 344123794, time start 1987684814946, limit 5000000
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: last 344505762, time end 1987689059040
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: last 344525560, time end 1987689279018
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: last 344556160, time end 1987689619018
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: last 344577761, time end 1987689859029
Nov  9 14:44:47  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:47  tvheadend[8996]: mpegts: input IPTV #1 got 524332 bytes
Nov  9 14:44:47  tvheadend[8996]: satips: RTP streaming to x.y.z.z:9330 closed (remote request) (fatal)
Nov  9 14:44:47  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:48  tvheadend[8996]: message repeated 6 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: updated 344213796, time start 1987685814969, limit 5000000
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: last 344608360, time end 1987690199019
Nov  9 14:44:48  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: last 344628162, time end 1987690419041
Nov  9 14:44:48  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: last 344649765, time end 1987690659074
Nov  9 14:44:48  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: last 344680362, time end 1987690999041
Nov  9 14:44:48  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:48  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:48  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:49  tvheadend[8996]: message repeated 6 times: [ satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096]
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: pcr: updated 344303798, time start 1987686814992, limit 5000000
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: unpause timer callback
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: pcr: last 344714562, time end 1987691379042
Nov  9 14:44:49  tvheadend[8996]: mpegts: input IPTV #1 got 131224 bytes
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: pcr: last 344745163, time end 1987691719053
Nov  9 14:44:49  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: pcr: last 344764975, time end 1987691939186
Nov  9 14:44:49  tvheadend[8996]: iptv-pcr: pcr: paused
Nov  9 14:44:49  tvheadend[8996]: mpegts: input IPTV #1 got 131036 bytes
Nov  9 14:44:50  tvheadend[8996]: satips: RTCP send to x.y.z.z:9331 : ver=1.1;tuner=1,220,1,15,194,,dvbc,,6900,,,,;pids=256,257,4096
Nov  9 14:44:50  tvheadend[8996]: satips: RTSP/1.0 TEARDOWN rtsp://a.b.c.d:554/stream=27{{CSeq=2,Session=1CFD7E33}}
Nov  9 14:44:50  tvheadend[8996]: satips: -/1CFD7E33/27: teardown from x.y.z.z:58090

Updated by Flole Systems almost 6 years ago

I also just tried with German ARD HD Channel (which is available at https://daserstehdde-lh.akamaihd.net/i/daserstehd_de@629196/master.m3u8), and it's the exact same issue. Maybe this makes it easier for you to reproduce the issue.

Updated by Jaroslav Kysela almost 6 years ago

I think that the culprit is the UDP packet loss between your tvh server and client. Tvh does not do any packet scheduling, so if IPTV data comes in bigger chunks and your network is not able to handle those chunks, you're in trouble. I tested IPTV through loopback. There's option to send RTP data through the RTSP session (TCP), but only open-source clients support this transfer mode (enigma2 client, tvheadend - both added by me).

Help for VLC:

https://www.wowza.com/docs/how-to-configure-vlc-media-player-for-rtsp-rtp-playback-rtsp-rtp-interleaved-and-tuning

#10

Updated by Flole Systems almost 6 years ago

This network should be able to handle it without any issues. I can send 850Mbit/s of UDP Packets without a single loss. Also QoS is active to ensure the UDP Packets get to the destination as fast as possible. I am using ethernet cable connection, so no Wifi Packet loss aswell.

Also channels using SAT-IP Client in tvheadend as source are working perfectly fine, this is just happening on the IPTV channels that are "injected" into the SAT-IP Server.

I also just checked with tcpdump and I am seeing a lot of UDP packets being sent out with length 1328, then suddenly it changes to length 80 for a few packets and then the client sends the teardown. There is no packet between the UDP length change indicating the reason for that.
I just had the situation where it was keeping the stream alive for a little longer, there was a lot of the long UDP Packets being sent, then a few short packets for a few seconds, then the long packets again.

I also tried to use ffprobe on the server with the same URL I am using in VLC to see if the problem is also happening there, but I am getting error 400:

Nov  9 15:58:22  tvheadend[8996]: satips: RTSP/1.0 OPTIONS rtsp://a.b.c.d:554/?freq=194.01&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=256,257,4096{{CSeq=1,User-Agent=Lavf57.83.100}}
Nov  9 15:58:22  tvheadend[8996]: satips: RTSP/1.0 DESCRIBE rtsp://a.b.c.d:554/?freq=194.01&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=256,257,4096{{Accept=application/sdp,CSeq=2,User-Agent=Lavf57.83.100}}
Nov  9 15:58:22  tvheadend[8996]: satips: 0/1D03ABE7/0: DESCRIBE from a.b.c.d:33978 DVB-C freq 194010000 sym 6900000 mod QAM/256 fec AUTO ds 0 plp -1 pids 256,257,4096
Nov  9 15:58:22  tvheadend[8996]: satips: RTSP/1.0 SETUP rtsp://a.b.c.d{{Transport=RTP/AVP/UDP;unicast;client_port=17070-17071,CSeq=3,User-Agent=Lavf57.83.100}}
Nov  9 15:58:22  tvheadend[8996]: satips: a.b.c.d: RTSP/1.0 SETUP (6) rtsp://a.b.c.d -- 400

If there is a way to use ffmpeg to record on the server into a file that would be great, I could verify if it's packet loss or not.

#11

Updated by Jaroslav Kysela almost 6 years ago

This should not happen:

Nov  9 14:44:47  tvheadend[8996]: satips: RTP streaming to x.y.z.z:9330 closed (remote request) (fatal)

It seems like that udp_multisend_send() fails for a reason.

Apply this patch and add 'rtsp' to the traces subsystems:

diff --git a/src/udp.c b/src/udp.c
index 192877069..b2c43c9a8 100644
--- a/src/udp.c
+++ b/src/udp.c
@@ -774,6 +774,7 @@ udp_multisend_send( udp_multisend_t *um, int fd, int packets )
     ((struct mmsghdr *)um->um_msg)[i].msg_len = um->um_iovec[i].iov_len;
   if (!use_emul) {
     n = sendmmsg(fd, (struct mmsghdr *)um->um_msg, packets, MSG_DONTWAIT);
+    tvhtrace(LS_RTSP, "sendmmsg: packets = %d, n = %d, errno = %d\n", packets, n, errno);
   } else {
     n = -1;
     errno = ENOSYS;

#12

Updated by Flole Systems almost 6 years ago

This is probably what you're looking for.

Nov  9 18:21:17 tvheadend[35584]: rtsp: sendmmsg: packets = 25, n = -1, errno = 11

Not sure why I am getting error 11 there, that's EWOULDBLOCK or EAGAIN, the buffer shouldn't be full but maybe someone in here has an idea why this is only happen on "virtual" channels and what.

Also on "normal" channels it's constantly sending out about 15 packets each time, on those virtual channels it's at minimum 20, sometimes up to 40 packets each call. If you need the complete log let me know.

#13

Updated by Flole Systems almost 6 years ago

Actually I thought that the buffer is the system's UDP Buffer, that should not be full but if the size of the socket's buffer is too small it might be full indeed.

#14

Updated by Jaroslav Kysela almost 6 years ago

You may try increase RTP_BUFSIZE in src/satip/rtsp.c then. But the question is how EAGAIN should be returned for a ~~non~~ blocking socket?

Sorry: for the blocking socket.

#15

Updated by Flole Systems almost 6 years ago

It get's returned when the buffer is full, see https://www.ibm.com/developerworks/community/blogs/IMSupport/entry/WHY_DOES_SEND_RETURN_EAGAIN_EWOULDBLOCK?lang=en for reference

#16

Updated by Flole Systems almost 6 years ago

File ARD settings.PNG ARD settings.PNG added

No clue why it's returning 11 there if the socket is indeed a blocking socket. I am more suprised about why this is only happening to IPTV streams.

Have you tried with the ARD Stream I mentioned earlier as a source (I am not using libav in case that matters) and on a host other than localhost? Maybe this is specific to the stream or it does not occur when using a local connection. I've attached the settings I am using for the Source, it's gotta be related to the source somehow (as it does not happen on "normal" channels).

#17

Updated by Jaroslav Kysela almost 6 years ago

Status changed from New to Fixed
% Done changed from 0 to 100

Applied in changeset commit:tvheadend|c8bbae5047286450b5692dc8c5d2aa9825229aee.

#18

Updated by Jaroslav Kysela almost 6 years ago

Status changed from Fixed to New
% Done changed from 100 to 0

I believe it's fixed in v4.3-1529-gc8bbae504 . The nonblocking behaviour was selected by MSG_DONTWAIT flag in the sendmmsg/sendmsg call.

#19

Updated by Jaroslav Kysela almost 6 years ago

Status changed from New to Fixed

#20

Updated by Flole Systems almost 6 years ago

Unfortunately I am still not getting a working stream, but I think I am getting closer.

I did a check with Wireshark on the server and on the client, and while there are a few packets missing, the captured file plays in VLC just fine, so the stream is getting to the client properly (most of the time, there were times with lost packets but those just resulted in a 1 second hang of the stream). Then I checked the Codec Info and noticed, that the streams that were causing the issues are all MPEG 4 Streams and the ones that work are MPEG-1/2 Video (mpgv). Maybe this is the issue?

The next thing I tried was enabling "Use A/V library" for the ARD Stream, there I was getting lots of (multiple times per second)

Nov 10 09:04:34 tvheadend[47259]: libav: AVCodecContext: non-existing PPS 0 referenced
Nov 10 09:04:34 tvheadend[47259]: libav: AVCodecContext: decode_slice_header error
Nov 10 09:04:34 tvheadend[47259]: libav: AVCodecContext: no frame!

Just a guess: If we get the A/V Library to turn the MPEG4 Stream into an MPEG2 stream, the other issues could be gone.

Did you use ffmpeg to connect your cameras when you tested it? How did you configure it (I want to give that a try to see if it works for me aswell then)?

#21

Updated by Jaroslav Kysela almost 6 years ago

pipe:///usr/bin/ffmpeg -loglevel fatal -i rtsp://USER:PASSWORD@ipcam1/0 -c:v copy -c:a copy -f mpegts pipe:1

#22

Updated by Jaroslav Kysela almost 6 years ago

Status changed from Fixed to Accepted

#23

Updated by Flole Systems almost 6 years ago

Using that command line it works on the TV. That is recognized as an MPEG 2 stream though, all the other streams that are not working are MPEG4 streams (which is probably part of the issue here). The "normal" DVB HD Streams are MPEG 4 aswell, so the TV is definitely capable of playing those.

VLC is not patient enough, it takes some time to load but VLC does not wait until that is finished but instead considers the source as being dead.

#24

Updated by Flole Systems almost 6 years ago

I started looking at this again and I think I found the real source of the issue:

Jan 23 19:25:45 tvheadend[10085]: satips: RTCP send to 10.0.0.2:9063 : ver=1.1;tuner=1,220,1,15,193,,dvbc,,6900,,,,;pids=all
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12225600, time start 8484542605161, limit 5000000, diff 3460004
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 29328 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12555000, time end 8484546265166
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 45120 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12228840, time start 8484542641162, limit 5000000, diff 3624004
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 18988 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12573000, time end 8484546465167
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 23312 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 23500 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12231720, time start 8484542673163, limit 5000000, diff 3792004
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12591000, time end 8484546665168
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 34968 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12233880, time start 8484542697164, limit 5000000, diff 3968004
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 18988 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 26320 bytes
Jan 23 19:25:45 tvheadend[10085]: satips: RTCP send to 10.0.0.2:9063 : ver=1.1;tuner=1,220,1,15,193,,dvbc,,6900,,,,;pids=all
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12238200, time start 8484542745165, limit 5000000, diff 3920003
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 34968 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12609000, time end 8484546865170
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 29328 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 18988 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12242520, time start 8484542793166, limit 5000000, diff 4072004
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12627000, time end 8484547065171
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 27636 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12242880, time start 8484542797166, limit 5000000, diff 4268005
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 32148 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 26320 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12245400, time start 8484542825167, limit 5000000, diff 4240004
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12645000, time end 8484547265172
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 27636 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 20492 bytes
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: updated 12249000, time start 8484542865168, limit 5000000, diff 4400004
Jan 23 19:25:45 tvheadend[10085]: iptv-pcr: last 12663000, time end 8484547465173
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 30644 bytes
Jan 23 19:25:45 tvheadend[10085]: mpegts: input IPTV #1 got 34968 bytes
Jan 23 19:25:45 tvheadend[10085]: satips: RTCP send to 10.0.0.2:9063 : ver=1.1;tuner=1,220,1,15,193,,dvbc,,6900,,,,;pids=all
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: updated 12252960, time start 8484542909169, limit 5000000, diff 4556004
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: last 12681000, time end 8484547665174
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 32148 bytes
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 21996 bytes
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 18988 bytes
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: updated 12256200, time start 8484542945169, limit 5000000, diff 4720005
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: last 12699000, time end 8484547865174
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 27636 bytes
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 27824 bytes
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: updated 12259440, time start 8484542981170, limit 5000000, diff 4884004
Jan 23 19:25:46 tvheadend[10085]: mpegts: input IPTV #1 got 29140 bytes
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: last 12717000, time end 8484548065175
Jan 23 19:25:46 tvheadend[10085]: iptv-pcr: paused

Everything works fine until the last line happens, then stuff starts to go wrong. Could "paused"-state affect the SAT-IP Server? Using HTSP the logs the "paused" is in there aswell but there it is working fine. If I use a stream that works with SAT-IP Server, I can see that there is no "paused" in the logs.

#25

Updated by Flole Systems almost 6 years ago

After making sure the pausing does no longer happen it is at least working with DVBViewer, I haven't had much luck with the TV yet though. Still trying to figure out what exactly is causing those artifacts there.

Also available in: Atom PDF

Project

General

Profile

Tvheadend

Issues

Custom queries

Bug #5319

IPTV Stream in SAT-IP Server "stops"

History

Updated by Hanspeter Müller almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Jaroslav Kysela almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Flole Systems almost 6 years ago

Updated by Flole Systems almost 6 years ago