Project

General

Profile

Actions
Copy link

Bug #3336

closed

Bug with livestream transcoding. large zapping times (15 seconds+) on some channels.

Added by Stephan Oelze over 9 years ago. Updated over 9 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
Start date:
2015-11-21
Due date:
% Done:

0%

Estimated time:
Found in version:
HTS Tvheadend 4.1-1037~gcd6dc6d-dirty
Affected Versions:

Description

Hello!!

I am facing a strange problem with transcoding livestream DVB Stream.
On some channels i have zapping times of 15seconds+.
The tvheadend instance is serving another host through hts where zapping is bellow 3seconds in most cases.(without transcoding of course)

Please see my Log with comments and Config bellow.
I allready upgraded libx265 static in tvheadend with same result. libx264 is behaving the same way = big zapping times.

(I think)® that the stream is passed to fast to transcoder. maybe add an "delay option" into streaming/profile section?
I do not have the skills in coding to do this on my own. But i can build from git and test changes if someone can add a feature like this to git repo.

This is where the bunny lies in between the pepper ;)
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: number of reference frames (0+4) exceeds max (3; probably corrupt input), discarding one

Thanks!
Stephan

Complete log with comments/config:

=======        A Quick zap ~ 4seconds till Picture plops up    ======
Nov 21 21:35:57 skynet daemon.info tvheadend[27462]: mpegts: 322MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:35:57 skynet daemon.info tvheadend[27462]: subscription: 0034: "HTTP" subscribing on channel "ZDF HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "322MHz", provider: "ZDFvision", service: "ZDF HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:35:57 skynet daemon.info tvheadend[27462]: transcode: 0013: 1:H264 1280x720 ==> HEVC 1280x720 (libx265)
Nov 21 21:35:57 skynet daemon.info tvheadend[27462]: transcode: 0013: 2:MPEG2AUDIO ==> AAC (aac)
Nov 21 21:35:58 skynet daemon.warn tvheadend[27462]: transcode: 0013: Detected framedrop in audio
Nov 21 21:35:58 skynet daemon.info tvheadend[27462]: subscription: 0034: "HTTP" unsubscribing from "ZDF HD", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:35:58 skynet daemon.warn tvheadend[27462]: libav: 2 frames left in the queue on closing
Nov 21 21:35:59 skynet daemon.info tvheadend[27462]: mpegts: 322MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:35:59 skynet daemon.info tvheadend[27462]: subscription: 0036: "HTTP" subscribing on channel "ZDF HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "322MHz", provider: "ZDFvision", service: "ZDF HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:35:59 skynet daemon.info tvheadend[27462]: transcode: 0014: 1:H264 1280x720 ==> HEVC 1280x720 (libx265)
Nov 21 21:35:59 skynet daemon.info tvheadend[27462]: transcode: 0014: 2:MPEG2AUDIO ==> AAC (aac)

=======        A Quick zap ~ 4seconds till Picture plops up    ======
Nov 21 21:36:46 skynet daemon.info tvheadend[27462]: mpegts: 169MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:36:46 skynet daemon.info tvheadend[27462]: subscription: 003A: "HTTP" subscribing on channel "NDR FS NDS HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "169MHz", provider: "ARD", service: "NDR FS NDS HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:36:47 skynet daemon.info tvheadend[27462]: transcode: 0015: 1:H264 1280x720 ==> HEVC 1280x720 (libx265)
Nov 21 21:36:47 skynet daemon.info tvheadend[27462]: transcode: 0015: 2:MPEG2AUDIO ==> AAC (aac)
Nov 21 21:36:47 skynet daemon.warn tvheadend[27462]: transcode: 0015: Detected framedrop in audio
Nov 21 21:36:48 skynet daemon.info tvheadend[27462]: subscription: 003A: "HTTP" unsubscribing from "NDR FS NDS HD", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:36:48 skynet daemon.warn tvheadend[27462]: libav: 2 frames left in the queue on closing
Nov 21 21:36:48 skynet daemon.info tvheadend[27462]: mpegts: 169MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:36:48 skynet daemon.info tvheadend[27462]: subscription: 003B: "HTTP" subscribing on channel "NDR FS NDS HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "169MHz", provider: "ARD", service: "NDR FS NDS HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:36:49 skynet daemon.info tvheadend[27462]: transcode: 0016: 1:H264 1280x720 ==> HEVC 1280x720 (libx265)
Nov 21 21:36:49 skynet daemon.info tvheadend[27462]: transcode: 0016: 2:MPEG2AUDIO ==> AAC (aac)
Nov 21 21:36:49 skynet daemon.warn tvheadend[27462]: transcode: 0016: Detected framedrop in audio

========    A Zap for about 15seconds ( no Joke !!)    it restarts on error    =============
Nov 21 21:37:45 skynet daemon.info tvheadend[27462]: mpegts: 330MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:37:45 skynet daemon.info tvheadend[27462]: subscription: 0040: "HTTP" subscribing on channel "Sky Atlantic HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "330MHz", provider: "SKY", service: "Sky Atlantic HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:37:45 skynet daemon.info tvheadend[27462]: transcode: 0017: 1:TELETEXT ==> Filtered
Nov 21 21:37:45 skynet daemon.info tvheadend[27462]: transcode: 0017: 2:H264 1920x1080 ==> HEVC 1920x1080 (libx265)
Nov 21 21:37:45 skynet daemon.info tvheadend[27462]: transcode: 0017: 3:AC3 ==> AAC (aac)
Nov 21 21:37:46 skynet daemon.warn tvheadend[27462]: transcode: 0017: Detected framedrop in audio
Nov 21 21:37:46 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:37:46 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:37:46 skynet daemon.err tvheadend[27462]: libav: number of reference frames (0+4) exceeds max (3; probably corrupt input), discarding one
Nov 21 21:37:47 skynet daemon.info tvheadend[27462]: subscription: 0040: "HTTP" unsubscribing from "Sky Atlantic HD", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:37:47 skynet daemon.warn tvheadend[27462]: libav: 2 frames left in the queue on closing
Nov 21 21:37:47 skynet daemon.info tvheadend[27462]: mpegts: 330MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:37:47 skynet daemon.info tvheadend[27462]: subscription: 0041: "HTTP" subscribing on channel "Sky Atlantic HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "330MHz", provider: "SKY", service: "Sky Atlantic HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:37:48 skynet daemon.info tvheadend[27462]: transcode: 0018: 1:TELETEXT ==> Filtered
Nov 21 21:37:48 skynet daemon.info tvheadend[27462]: transcode: 0018: 2:H264 1920x1080 ==> HEVC 1920x1080 (libx265)
Nov 21 21:37:48 skynet daemon.info tvheadend[27462]: transcode: 0018: 3:AC3 ==> AAC (aac)
Nov 21 21:37:49 skynet daemon.warn tvheadend[27462]: transcode: 0018: Detected framedrop in audio
Nov 21 21:37:49 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:37:49 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:37:49 skynet daemon.err tvheadend[27462]: libav: number of reference frames (0+4) exceeds max (3; probably corrupt input), discarding one
Nov 21 21:37:52 skynet daemon.info tvheadend[27462]: mpegts: 330MHz in primacom scan complete

========    A Zap for about 15seconds ( no Joke !!) it restarts on error        =============
Nov 21 21:39:12 skynet daemon.info tvheadend[27462]: mpegts: 418MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:39:12 skynet daemon.info tvheadend[27462]: subscription: 0046: "HTTP" subscribing on channel "Sky Cinema HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "418MHz", provider: "SKY", service: "Sky Cinema HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:39:13 skynet daemon.info tvheadend[27462]: transcode: 0019: 1:TELETEXT ==> Filtered
Nov 21 21:39:13 skynet daemon.info tvheadend[27462]: transcode: 0019: 2:H264 1920x1080 ==> HEVC 1920x1080 (libx265)
Nov 21 21:39:13 skynet daemon.info tvheadend[27462]: transcode: 0019: 3:AC3 ==> AAC (aac)
Nov 21 21:39:15 skynet daemon.err tvheadend[27462]: descrambler: cannot decode packets for service "Sky Cinema HD" 
Nov 21 21:39:15 skynet daemon.warn tvheadend[27462]: TS: primacom/418MHz/Sky Cinema HD: TELETEXT @ #32 Continuity counter error (total 1)
Nov 21 21:39:15 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:15 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:15 skynet daemon.err tvheadend[27462]: libav: number of reference frames (0+4) exceeds max (3; probably corrupt input), discarding one
Nov 21 21:39:15 skynet daemon.warn tvheadend[27462]: transcode: 0019: Detected framedrop in audio
Nov 21 21:39:16 skynet daemon.info tvheadend[27462]: subscription: 0046: "HTTP" unsubscribing from "Sky Cinema HD", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:39:16 skynet daemon.warn tvheadend[27462]: libav: 2 frames left in the queue on closing
Nov 21 21:39:16 skynet daemon.info tvheadend[27462]: mpegts: 418MHz in primacom - tuning on HVR-930C _ 1
Nov 21 21:39:16 skynet daemon.info tvheadend[27462]: subscription: 0047: "HTTP" subscribing on channel "Sky Cinema HD", weight: 125, adapter: "HVR-930C _ 1", network: "primacom", mux: "418MHz", provider: "SKY", service: "Sky Cinema HD", profile="webtv-h264-aac-matroska", hostname="10.3.0.6", client="Kodi/15.2 (Windows NT 6.1; WOW64) App_Bitness/32 Version/15.2-Git:20151019-02e7013" 
Nov 21 21:39:17 skynet daemon.info tvheadend[27462]: transcode: 001A: 1:TELETEXT ==> Filtered
Nov 21 21:39:17 skynet daemon.info tvheadend[27462]: transcode: 001A: 2:H264 1920x1080 ==> HEVC 1920x1080 (libx265)
Nov 21 21:39:17 skynet daemon.info tvheadend[27462]: transcode: 001A: 3:AC3 ==> AAC (aac)
Nov 21 21:39:17 skynet daemon.warn tvheadend[27462]: transcode: 001A: Detected framedrop in audio
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: mmco: unref short failure
Nov 21 21:39:17 skynet daemon.err tvheadend[27462]: libav: number of reference frames (0+4) exceeds max (3; probably corrupt input), discarding one

My Streaming profile:

{
        "container": 1,
        "resolution": 1080,
        "channels": 2,
        "language": "ger",
        "vcodec": "libx265",
        "vbitrate": 1536,
        "acodec": "aac",
        "abitrate": 256,
        "class": "profile-transcode",
        "enabled": true,
        "default": true,
        "name": "webtv-h264-aac-matroska",
        "comment": "WEBTV profile H264/AAC/Matroska",
        "priority": 2,
        "fpriority": 0,
        "timeout": 0,
        "restart": false,
        "contaccess": true,
        "svfilter": 0,
        "shield": true
}

Files

Download all files
long zap.PNG (5.11 KB) long zap.PNG Stephan Oelze, 2015-11-22 17:43
short zap.PNG (4.96 KB) short zap.PNG Stephan Oelze, 2015-11-22 17:43
tvheadend.log_quickzap_1 (1.19 MB) tvheadend.log_quickzap_1 Stephan Oelze, 2015-11-22 21:39
tvheadend.log_quickzap_2 (622 KB) tvheadend.log_quickzap_2 Stephan Oelze, 2015-11-22 21:39
tvheadend.log_longzap_1 (1.17 MB) tvheadend.log_longzap_1 Stephan Oelze, 2015-11-22 21:39
tvheadend.log_longzap_2 (1.11 MB) tvheadend.log_longzap_2 Stephan Oelze, 2015-11-22 21:39
tvheadend.log_quickzap_1 (628 KB) tvheadend.log_quickzap_1 Stephan Oelze, 2015-11-22 23:33
tvheadend.log_quickzap_2 (365 KB) tvheadend.log_quickzap_2 Stephan Oelze, 2015-11-22 23:33
tvheadend.log_longzap_1 (852 KB) tvheadend.log_longzap_1 Stephan Oelze, 2015-11-22 23:33
tvheadend.log_longzap_2 (1.01 MB) tvheadend.log_longzap_2 Stephan Oelze, 2015-11-22 23:33
tvheadend.log_quickzap_1 (756 KB) tvheadend.log_quickzap_1 Stephan Oelze, 2015-11-22 23:45
tvheadend.log_quickzap_2 (421 KB) tvheadend.log_quickzap_2 Stephan Oelze, 2015-11-22 23:45
tvheadend.log_longzap_1 (1.44 MB) tvheadend.log_longzap_1 Stephan Oelze, 2015-11-22 23:46
tvheadend.log_longzap_2 (1.38 MB) tvheadend.log_longzap_2 Stephan Oelze, 2015-11-22 23:46
tvheadend.log_quickzap_1 (2.7 MB) tvheadend.log_quickzap_1 Stephan Oelze, 2015-11-23 14:57
tvheadend.log_quickzap_2 (2.97 MB) tvheadend.log_quickzap_2 Stephan Oelze, 2015-11-23 14:57
tvheadend.log_longzap_1 (4.78 MB) tvheadend.log_longzap_1 Stephan Oelze, 2015-11-23 14:57
tvheadend.log_longzap_2 (3.11 MB) tvheadend.log_longzap_2 Stephan Oelze, 2015-11-23 14:57
long_zap_player (76.4 KB) long_zap_player Stephan Oelze, 2015-11-23 15:46
quick_zap_player (131 KB) quick_zap_player Stephan Oelze, 2015-11-23 15:46
tvheadend.log (21.7 MB) tvheadend.log Stephan Oelze, 2015-11-23 18:37
tv_quickzap_1_trace_log_VLC.log (3.57 MB) tv_quickzap_1_trace_log_VLC.log The log as VLC strams a quick zap channel. Stephan Oelze, 2015-11-24 08:54
tv_quickzap_1_video_h265.mkv (17.6 MB) tv_quickzap_1_video_h265.mkv The video of a quickzap 1 live encoded to h265 Stephan Oelze, 2015-11-24 08:54
tv_quickzap_1_video_pass.ts (60.5 MB) tv_quickzap_1_video_pass.ts The viedeo of quickzap 1 as broadcasted (profile=pass) Stephan Oelze, 2015-11-24 08:55
tv_quickzap_2_trace_log_VLC.log (4.18 MB) tv_quickzap_2_trace_log_VLC.log Stephan Oelze, 2015-11-24 08:56
tv_quickzap_2_video_h265.mkv (8.34 MB) tv_quickzap_2_video_h265.mkv Stephan Oelze, 2015-11-24 08:56
tv_quickzap_2_video_pass.ts (31.6 MB) tv_quickzap_2_video_pass.ts Stephan Oelze, 2015-11-24 08:56
tv_longzap_1_trace_log_VLC.log (4.82 MB) tv_longzap_1_trace_log_VLC.log Stephan Oelze, 2015-11-24 08:58
tv_longzap_1_video_h265.mkv (7.65 MB) tv_longzap_1_video_h265.mkv Stephan Oelze, 2015-11-24 08:58
tv_longzap_1_video_pass.ts (41.4 MB) tv_longzap_1_video_pass.ts Stephan Oelze, 2015-11-24 08:58
tv_longzap_2_trace_log_WGET_STUCKED.log (14.8 MB) tv_longzap_2_trace_log_WGET_STUCKED.log A Log as wget stucked several time while getting data. Stephan Oelze, 2015-11-24 09:05
tv_longzap_2_trace_log_VLC.log (5.22 MB) tv_longzap_2_trace_log_VLC.log Stephan Oelze, 2015-11-24 09:05
tv_longzap_2_video_h265.mkv (12.7 MB) tv_longzap_2_video_h265.mkv This video was loaded with wget. stucked several times. playback of file is fluent, no missing frames. Stephan Oelze, 2015-11-24 09:05
tv_longzap_2_video_pass.ts (42.1 MB) tv_longzap_2_video_pass.ts Stephan Oelze, 2015-11-24 09:06
tv_quickzap_1_trace (1.8 MB) tv_quickzap_1_trace Stephan Oelze, 2015-11-24 13:10
tv_quickzap_2_trace (2.92 MB) tv_quickzap_2_trace Stephan Oelze, 2015-11-24 13:10
tv_longzap_1_trace (4 MB) tv_longzap_1_trace Stephan Oelze, 2015-11-24 13:10
tv_longzap_2_trace (3.3 MB) tv_longzap_2_trace Stephan Oelze, 2015-11-24 13:10
Actions
Copy link

Also available in: Atom PDF