Bug #5326
open
tvheadend stuck at DTS and PCR diff is very big and after this error pvr.hts says - Command hello failed: No response received.
Added by Илья Ваганов over 6 years ago. Updated over 6 years ago.
0%
Description
Hi, friends! This problem occurs periodically, not so often. Who is right and who is wrong, i could not understand. Kodi? PVR addon? TBS drivers? Kernel? Please see logs.
Files
| kodi.log (577 KB) kodi.log | Илья Ваганов, 2018-11-11 19:20 | ||
| sys.log (577 KB) sys.log | Илья Ваганов, 2018-11-11 19:21 | ||
| sys.log (1.94 KB) sys.log | Илья Ваганов, 2018-11-11 20:10 | ||
| tvh.log (145 KB) tvh.log | Daniel Wandrei, 2018-11-14 10:56 | ||
| tvheadend_hts.log (3.41 MB) tvheadend_hts.log | Илья Ваганов, 2018-11-19 10:29 | ||
| err.jpg (132 KB) err.jpg | Илья Ваганов, 2018-11-19 11:54 | ||
| 60sec_parser.log (381 KB) 60sec_parser.log | Илья Ваганов, 2018-11-19 12:30 | ||
| start_wait60sec_stop with_--trace_ parser.log (24 KB) start_wait60sec_stop with_--trace_ parser.log | Илья Ваганов, 2018-11-19 13:13 | ||
| start_wait60s_stop.log (25.3 KB) start_wait60s_stop.log | Илья Ваганов, 2018-11-19 18:02 | ||
| start_play60s_stop.log (1.49 MB) start_play60s_stop.log | Илья Ваганов, 2018-11-19 18:02 | ||
| err_22112018.log (3.19 KB) err_22112018.log | Илья Ваганов, 2018-11-22 08:14 | ||
| tvh.zip (3.95 MB) tvh.zip | Daniel Wandrei, 2018-11-25 12:18 | ||
| err_26112018.txt (5.09 KB) err_26112018.txt | Илья Ваганов, 2018-11-26 12:27 | ||
| tvheadend_hts.zip (17.8 MB) tvheadend_hts.zip | Илья Ваганов, 2018-11-27 07:27 |
Updated by Mark Clarkstone over 6 years ago
Илья Ваганов wrote:
Hi, friends! This problem occurs periodically, not so often. Who is right and who is wrong, i could not understand. Kodi? PVR addon? TBS drivers? Kernel? Please see logs.
Please provide a Tvheadend generated log see Traces and Debugging. Additionally, What OS and version of Tvheadend?
Updated by Илья Ваганов over 6 years ago
Debugging not enabled at this time and i don't know when the error will appear again, but this error will appear, because it happens periodically. Probably this happens at a large Kodi uptime. My system is Arch, kernel 4.9.65 LTS, TBS6904, v4l-dvb-git-r908.48b0842. Debugging enabled now, time has gone... After restarting tvheadend daemon all worked as expected.
Updated by Илья Ваганов over 6 years ago
tvheadend-git 4.3.r1529.gc8bbae504-1 (latest)
Updated by saen acro over 6 years ago
Илья are you use AUR?
# tvheadend -v tvheadend: version 4.3-1529~gc8bbae5
there are regular and debug package
during crash debug one report source code location of error
Monitor your IRQ load tbs drivers have GIGANTIC bug in MSI, no one read this
https://github.com/torvalds/linux/blob/master/Documentation/PCI/MSI-HOWTO.txt
stop in BIOS all unused devices.
also tune manually unused devices with powertop
Ждем отчета ;)
Updated by Mark Clarkstone over 6 years ago
Guys, if it's easier use Russian, just be sure to write a translation under it! :)
Updated by saen acro over 6 years ago
Waiting report is translation
off-topic
What you do in school Mark ;) why you not learn languages I learned 5 ;)
Russian is my Third next learned English then Italian ;)
Updated by Joe User over 6 years ago
saen acro wrote:
off-topic
What you do in school Mark ;) why you not learn languages I learned 5 ;)
Maybe it is time to learn a 6th - "C". ;)
Updated by saen acro over 6 years ago
Joe User wrote:
saen acro wrote:
off-topic
What you do in school Mark ;) why you not learn languages I learned 5 ;)Maybe it is time to learn a 6th - "C". ;)
i learned just Basic and can program Industal automation aka robots ;)
Updated by Daniel Wandrei over 6 years ago
I don't think it's related to the TBS card because I have the same messages with FritzBox 6490 as a Tuner.
2018-11-03 19:48:59.926 [ ERROR]:tsfix: transport stream AC3, DTS discontinuity. DTS = 126546112, last = 127082304 2018-11-03 19:48:59.929 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #259: DTS and PCR diff is very big (512677) 2018-11-03 19:49:01.911 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #259: DTS and PCR diff is very big (710110117) 2018-11-03 19:49:03.914 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #259: DTS and PCR diff is very big (1474836517) 2018-11-03 19:49:05.918 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #259: DTS and PCR diff is very big (2218567717) 2018-11-03 19:49:06.730 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new
I have Tvheadend 4.3 running on a Debian 9 VM inside ESXi. Main Client is a Kodi x86 PC.
Updated by Luis Alves over 6 years ago
From my experience this looks like tvheadend is getting "stuck" somewhere.
When that happens you get this message "mpegts: too much queued input data (over 50MB) [...]" (tvh is locked somewhere and stops processing input data).
And that's why Kodi complains with "No response received" (tvh stops responding to requests - and I bet that the webui is also "dead")
Your uploaded tvh log is short (we can't see what happened before getting stuck).
Please post a "better" tvh log and/or explain what conditions lead to this problem.
BTW, which TBS drivers are you using - mine or the closed source ones?
Updated by Илья Ваганов over 6 years ago
Luis Alves wrote:
BTW, which TBS drivers are you using - mine or the closed source ones?
i use drivers from https://github.com/tbsdtv/linux_media.
Updated by Daniel Wandrei over 6 years ago
Here is my complete log.
Updated by Jaroslav Kysela over 6 years ago
Create a log only for one stream session (one channel, one tuning). Show '--trace parsers'. https://tvheadend.org/projects/tvheadend/wiki/Traces
Updated by Daniel Wandrei over 6 years ago
Jaroslav Kysela wrote:
Create a log only for one stream session (one channel, one tuning). Show '--trace parsers'. https://tvheadend.org/projects/tvheadend/wiki/Traces
Who do you mean? I have only one client and I was watching the same channel while logging.
Updated by Jaroslav Kysela over 6 years ago
Run tvh with the log options, watch one channel for 60 seconds, stop tvh.
Updated by Илья Ваганов over 6 years ago
hi. Same issue with internet radio stream.
kodi.log:
12:33:18.571 T:776 NOTICE: CVideoPlayerAudio::Process - stream stalled 12:33:19.326 T:7000 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
tvh.log:
2018-11-19 12:10:12.561 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - subscribe to Европа+ using profile htsp 2018-11-19 12:10:12.561 [ TRACE]:subscription: 0090: creating subscription for Европа+ weight 0 using profile htsp 2018-11-19 12:10:12.561 [ TRACE]:subscription: 0090: find service for Европа+ weight 150 2018-11-19 12:10:12.561 [ INFO]:mpegts: pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_service_type digi - tuning on IPTV #1 2018-11-19 12:10:12.564 [ INFO]:spawn: Executing "/usr/bin/ffmpeg" 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: linking sub 0x7f1a8002c310 to svc 0x5607263c4960 type 0 2018-11-19 12:10:12.564 [ INFO]:subscription: 0090: "46.0.205.59 [ hts | Kodi Media Center ]" subscribing on channel "Европа+", weight: 150, adapter: "IPTV #1", network: "Internet Radio", mux: "pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_service_type digi", provider: "Internet", service: "Europa_plus", profile="htsp", hostname="46.0.205.59", username="hts", cli 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 01: input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 02: parser input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 03: profile input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 04: tsfix input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 05: profile sharer input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 06: globalheaders input 2018-11-19 12:10:12.564 [ TRACE]:subscription: 0090: chain 07: wtimeshift input 2018-11-19 12:10:12.564 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - subscription grace 15 seconds 2018-11-19 12:10:12.573 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:10:12.597 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method subscriptionSpeed 2018-11-19 12:10:12.597 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - subscription speed 2018-11-19 12:10:12.608 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:10:12.630 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method subscriptionSpeed 2018-11-19 12:10:12.630 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - subscription speed 2018-11-19 12:10:13.020 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - subscription start 2018-11-19 12:10:13.021 [ DEBUG]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - first packet 2018-11-19 12:15:12.778 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:15:12.813 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:20:11.969 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:20:12.004 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:20:27.561 [WARNING]:epggrab: PSIP: ATSC Grabber - data completion timeout for pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_service_type digi 2018-11-19 12:20:27.561 [WARNING]:epggrab: EIT: EPG Grabber - data completion timeout for pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_service_type digi 2018-11-19 12:25:12.170 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:25:12.205 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:30:12.376 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:30:12.411 [ TRACE]:htsp: 46.0.205.59 [ hts | Kodi Media Center ] - method getEvents 2018-11-19 12:33:15.104 [ INFO]:spawn: Executing "/usr/bin/ffmpeg" 2018-11-19 12:33:16.102 [WARNING]:TS: Internet Radio/pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_se Continuity counter error (total 1) 2018-11-19 12:33:16.102 [WARNING]:parser: Internet Radio/pipe:///usr/bin/ffmpeg -loglevel fatal -i http://hls-02-europaplus.emgsound.ru/11/128/playlist.m3u8 -vn -acodec aac -flags +global_header -strict -2 -metadata service_provider=Internet -metadata service_name=Europa_plus -f mpegts -mpegts_se: DTS and PCR diff is very big (124960481)
Updated by Илья Ваганов over 6 years ago
- File tvheadend_hts.log tvheadend_hts.log added
Updated by Jaroslav Kysela over 6 years ago
Show '--trace parsers'. https://tvheadend.org/projects/tvheadend/wiki/Traces
Updated by Илья Ваганов over 6 years ago
2018-11-19 14:42:59.718 config: unkown subsystem 'parsers'
Updated by Илья Ваганов over 6 years ago
Updated by Илья Ваганов over 6 years ago
- File 60sec_parser.log 60sec_parser.log added
Updated by Luis Alves over 6 years ago
You have to upload a log where we can see the issue happening, not just a log of 60 sec running tvh...
Updated by Илья Ваганов over 6 years ago
Updated by Jaroslav Kysela over 6 years ago
No 'TRACE' lines, read again https://tvheadend.org/projects/tvheadend/wiki/Traces
Updated by Илья Ваганов over 6 years ago
- File start_play60s_stop.log start_play60s_stop.log added
- File start_wait60s_stop.log start_wait60s_stop.log added
Updated by Jaroslav Kysela over 6 years ago
No "DTS and PCR diff is very big" lines in log. Please, think about what are you trying to report.
Updated by Илья Ваганов over 6 years ago
This problem occurs periodically, but not so often. Need time to wait for... :)
Updated by Daniel Wandrei over 6 years ago
Jaroslav Kysela wrote:
Run tvh with the log options, watch one channel for 60 seconds, stop tvh.
Problem does not occur instantly after 60 seconds, it sometimes takes hours.
Updated by Илья Ваганов over 6 years ago
- File err_22112018.log err_22112018.log added
Hi, guys! Just caught a error...
Updated by Jaroslav Kysela over 6 years ago
Please, add 'pcr' to the log (--trace pcr,parsers). Anyway, it seems that the ffmpeg pushes the wrong PCR clock to the output stream (tvh uses those clocks to drop the invalid packets). It might also help to save the original MPEG-TS stream which received by the tvheadend (capture it using the passthrough MPEG-TS profile) to check the MPEG-TS timestamps.
Updated by Daniel Wandrei over 6 years ago
Here is another one, first the stream began to stutter and freeze with decoding artefacts, then tvheadend stopped with "too much input data".
Updated by saen acro over 6 years ago
Event "too much input data" need to be direct connected to restart service action.
Updated by Daniel Wandrei over 6 years ago
At this point, I can't restart tvheadend, the service does not respond anymore. I have to forcfully kill it.
Updated by Jaroslav Kysela over 6 years ago
@Daeniel, parser traces are missing. --trace pcr,parsers
Updated by Илья Ваганов over 6 years ago
- File err_26112018.txt err_26112018.txt added
Updated by Daniel Wandrei over 6 years ago
I can not log parsers: unknown subsystem "parsers".
Updated by Илья Ваганов over 6 years ago
Daniel Wandrei wrote:
I can not log parsers: unknown subsystem "parsers".
It will be right 'trace --pcr,parser'
Updated by Илья Ваганов over 6 years ago
- File tvheadend_hts.zip tvheadend_hts.zip added
Another log...
Updated by Jaroslav Kysela over 6 years ago
It seems like a ffmpeg problem. There's PCR mismatch starting since 2018-11-27 11:03:40.476:
2018-11-27 11:03:40.132 [ TRACE]:pcr: Internet Radio/pipe: change : 571050380 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571113380 pts 571113380 dur 2089 len 387 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571115469 pts 571115469 dur 2089 len 369 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571117558 pts 571117558 dur 2089 len 363 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571119647 pts 571119647 dur 2089 len 395 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571121736 pts 571121736 dur 2089 len 361 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571123825 pts 571123825 dur 2089 len 349 err 0) 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571050380 dts 571125914 pts 571125914 dur 2089 len 382 err 0) 2018-11-27 11:03:40.132 [ TRACE]:pcr: Internet Radio/pipe: change : 571065008 2018-11-27 11:03:40.132 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571128008 pts 571128008 dur 2089 len 365 err 0) 2018-11-27 11:03:40.476 [WARNING]:TS: Internet Radio/pipe Continuity counter error (total 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571130097 pts 571130097 dur 2089 len 371 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571132186 pts 571132186 dur 2089 len 393 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571134275 pts 571134275 dur 2089 len 381 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571136364 pts 571136364 dur 2089 len 396 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571138453 pts 571138453 dur 2089 len 388 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 571065008 dts 571140542 pts 571140542 dur 2089 len 387 err 0) 2018-11-27 11:03:40.476 [ TRACE]:pcr: Internet Radio/pipe: change : 63000 2018-11-27 11:03:40.476 [WARNING]:parser: Internet Radio/pipe: DTS and PCR diff is very big (571079631) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571142631 pts 571142631 dur 2089 len 266 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571144720 pts 571144720 dur 2089 len 316 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571146809 pts 571146809 dur 2089 len 266 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571148898 pts 571148898 dur 2089 len 292 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571150987 pts 571150987 dur 2089 len 310 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571153076 pts 571153076 dur 2089 len 330 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571155165 pts 571155165 dur 2089 len 322 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571157254 pts 571157254 dur 2089 len 392 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 63000 dts 571159343 pts 571159343 dur 2089 len 398 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 63000 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:pcr: Internet Radio/pipe: change : 81806 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571161432 pts 571161432 dur 2089 len 349 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571163521 pts 571163521 dur 2089 len 342 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571165610 pts 571165610 dur 2089 len 372 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571167699 pts 571167699 dur 2089 len 447 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571169788 pts 571169788 dur 2089 len 339 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571171877 pts 571171877 dur 2089 len 400 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 81806 dts 571173966 pts 571173966 dur 2089 len 369 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 81806 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:pcr: Internet Radio/pipe: change : 96435 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 96435 dts 571176055 pts 571176055 dur 2089 len 383 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 96435 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 96435 dts 571178144 pts 571178144 dur 2089 len 384 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 96435 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 96435 dts 571180233 pts 571180233 dur 2089 len 341 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 96435 dts <unset> pts <unset> dur 0 len 0 err 1) 2018-11-27 11:03:40.476 [ TRACE]:parser: pcr drop (pkt stream 0 AAC-LATM pcr 96435 dts 571182322 pts 571182322 dur 2089 len 342 err 0) 2018-11-27 11:03:40.476 [ TRACE]:parser: deliver (pkt stream 1 AAC-LATM pcr 96435 dts <unset> pts <unset> dur 0 len 0 err 1)
Updated by Илья Ваганов over 6 years ago
2018-12-02 20:14:43.477 [ TRACE]:pcr: NTV+ R/12130.26R/Россия 1 HD: change : 1907434037
2018-12-02 20:14:43.515 [ TRACE]:pcr: NTV+ R/12130.26R/Россия 1 HD: change : 1907437222
2018-12-02 20:14:43.561 [ TRACE]:pcr: NTV+ R/12130.26R/Россия 1 HD: change : 1907440392
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907438539 pts 1907438539 dur 2160 len 576 err 0)
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907440699 pts 1907440699 dur 2160 len 576 err 0)
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907442859 pts 1907442859 dur 2160 len 576 err 0)
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907445019 pts 1907445019 dur 2160 len 576 err 0)
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907447179 pts 1907447179 dur 2160 len 576 err 0)
2018-12-02 20:14:43.574 [ TRACE]:parser: deliver (pkt stream 2 MPEG2AUDIO pcr 1907440392 dts 1907449339 pts 1907449339 dur 2160 len 576 err 0)
2018-12-02 20:14:43.599 [ TRACE]:pcr: NTV+ R/12130.26R/Россия 1 HD: change : 1907443573
2018-12-02 20:14:43.607 [ INFO]:subscription: 016A: "127.0.0.1 [ vis | Kodi Media Center ]" unsubscribing from "Россия 1 HD", hostname="127.0.0.1", userna$
2018-12-02 20:14:43.611 [ INFO]:mpegts: 12130.26R in NTV+ R - tuning on TurboSight TBS 6904 #0
2018-12-02 20:14:44.108 [ INFO]:subscription: 0170: "127.0.0.1 [ vis | Kodi Media Center ]" subscribing on channel "Первый канал HD", weight: 150, adapter$
2018-12-02 20:14:44.409 [ ALERT]:CRASH: Signal: 11 in PRG: /usr/bin/tvheadend (4.3-1610~g79ea2a42c) [9f971ad08dd0548d8144e95e64b720ec38787898] CWD: /
2018-12-02 20:14:44.409 [ ALERT]:CRASH: Fault address (nil) (Address not mapped)
2018-12-02 20:14:44.409 [ ALERT]:CRASH: Loaded libraries: linux-vdso.so.1 /usr/lib/libdvbcsa.so.1 /usr/lib/libssl.so.1.1 /usr/lib/libcrypto.so.1.1 /usr/lib$
2018-12-02 20:14:44.409 [ ALERT]:CRASH: Register dump [23]: 00000000000000000000000000000006000000000000000000007f660b5f8e2400007f65cc037b20000000000000000$
2018-12-02 20:14:44.409 [ ALERT]:CRASH: STACKTRACE
2018-12-02 20:14:44.414 [ ALERT]:CRASH: ??:0 0x55882b41988e 0x55882b279000
2018-12-02 20:14:44.418 [ ALERT]:CRASH: ??:0 0x7f663c7013c0 0x7f663c6ef000
2018-12-02 20:14:44.423 [ ALERT]:CRASH: ??:0 0x55882b3f6cdb 0x55882b279000
2018-12-02 20:14:44.428 [ ALERT]:CRASH: ??:0 0x55882b45d278 0x55882b279000
2018-12-02 20:14:44.432 [ ALERT]:CRASH: ??:0 0x55882b45daa5 0x55882b279000
2018-12-02 20:14:44.436 [ ALERT]:CRASH: ??:0 0x55882b3fec94 0x55882b279000
2018-12-02 20:14:44.442 [ ALERT]:CRASH: ??:0 0x55882b4a1da8 0x55882b279000
2018-12-02 20:14:44.446 [ ALERT]:CRASH: ??:0 0x55882b4a21f2 0x55882b279000
2018-12-02 20:14:44.453 [ ALERT]:CRASH: ??:0 0x55882b497496 0x55882b279000
2018-12-02 20:14:44.461 [ ALERT]:CRASH: ??:0 0x55882b4a2440 0x55882b279000
2018-12-02 20:14:44.466 [ ALERT]:CRASH: ??:0 0x55882b49f586 0x55882b279000
2018-12-02 20:14:44.470 [ ALERT]:CRASH: ??:0 0x55882b3d96c4 0x55882b279000
2018-12-02 20:14:49.684 [ INFO]:main: Log started
2018-12-02 20:14:49.684 [ INFO]:http: Starting HTTP server 0.0.0.0:8890
2018-12-02 20:14:49.684 [ INFO]:htsp: Starting HTSP server 0.0.0.0:8990
2018-12-02 20:14:49.694 [ INFO]:config: loaded
2018-12-02 20:14:49.694 [ INFO]:config: scanfile (re)initialization with path <none>
Updated by Daniel Wandrei over 6 years ago
Still having stuttering/decoding artefacts and finally total freezes:
2018-12-19 21:45:57.305 [WARNING]:TS: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: H264 @ #255: Invalid start code ad:78:25 2018-12-19 21:45:57.426 [ DEBUG]:capmt: tvheadend: CA_SET_DESCR adapter 0 par 0 idx 0 20ec142038885d1d 2018-12-19 21:45:57.427 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=319 hops=0 reader='KDG09' from='local' protocol='pcsc' 2018-12-19 21:45:57.427 [ DEBUG]:capmt: tvheadend: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=319 hops=0 reader=KDG09 from=local protocol=pcsc 2018-12-19 21:45:57.551 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (512192) 2018-12-19 21:45:59.620 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (527460032) 2018-12-19 21:46:01.637 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (943245632) 2018-12-19 21:46:03.737 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1308066752) 2018-12-19 21:46:05.749 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1674290432) 2018-12-19 21:46:07.823 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1961561792) 2018-12-19 21:46:09.900 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2276864192) 2018-12-19 21:46:11.916 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2716375232) 2018-12-19 21:46:13.927 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3180400832) 2018-12-19 21:46:15.959 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3649143872) 2018-12-19 21:46:17.968 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (4117662272) 2018-12-19 21:46:37.409 [ ERROR]:tsfix: transport stream AC3, DTS discontinuity. DTS = 436860928, last = 437400000 2018-12-19 21:46:37.412 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (511680) 2018-12-19 21:46:39.371 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (459946560) 2018-12-19 21:46:41.388 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (921529920) 2018-12-19 21:46:43.395 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1389316800) 2018-12-19 21:46:45.398 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1846505280) 2018-12-19 21:46:47.438 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2316924480) 2018-12-19 21:46:49.447 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2790684480) 2018-12-19 21:46:50.769 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:46:51.453 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3251251200) 2018-12-19 21:46:53.495 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3713390400) 2018-12-19 21:46:55.499 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (4189183680) 2018-12-19 21:47:00.833 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:10.942 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:16.727 [ ERROR]:tsfix: transport stream AC3, DTS discontinuity. DTS = 436863296, last = 437399488 2018-12-19 21:47:16.732 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (511168) 2018-12-19 21:47:18.647 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (453080128) 2018-12-19 21:47:20.654 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (929656768) 2018-12-19 21:47:20.957 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:22.656 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1387507648) 2018-12-19 21:47:24.661 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1852397248) 2018-12-19 21:47:26.666 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2201960128) 2018-12-19 21:47:28.676 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2672079808) 2018-12-19 21:47:30.718 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3105128128) 2018-12-19 21:47:30.977 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:32.730 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3507608128) 2018-12-19 21:47:34.803 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3877737088) 2018-12-19 21:47:36.823 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (4272052288) 2018-12-19 21:47:41.036 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:51.094 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:47:58.323 [ ERROR]:tsfix: transport stream AC3, DTS discontinuity. DTS = 436862784, last = 437398976 2018-12-19 21:47:58.327 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (510656) 2018-12-19 21:48:00.342 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (392372096) 2018-12-19 21:48:01.168 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:48:02.397 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (717647936) 2018-12-19 21:48:04.425 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1020428096) 2018-12-19 21:48:06.473 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1331315456) 2018-12-19 21:48:08.489 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (1717102976) 2018-12-19 21:48:10.544 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2050056896) 2018-12-19 21:48:11.279 [WARNING]:mpegts: too much queued input data (over 50MB) for SAT>IP DVB-C Tuner, discarding new 2018-12-19 21:48:12.547 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2438275136) 2018-12-19 21:48:14.549 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (2902297856) 2018-12-19 21:48:16.553 [WARNING]:parser: Vodafone/618MHz/Sky Sport Bundesliga 1 HD: AC3 @ #258: DTS and PCR diff is very big (3380144576)
Updated by Luis Alves over 6 years ago
In what system you're running tvheadend?
It seems that it's too slow and can't keep up... or tvh it is really hanging.
To debug a hang, you can try the latest code: start tvh with "--thrdebug 10020"
It should tell you where it hangs.
Updated by Daniel Wandrei over 6 years ago
Tvheadend is running inside a Debian 9 VM on ESXi with a Hypervisor which has a Intel 4th Generation i5 with 16 GB RAM. Raw Horsepower is not the problem here.
Will try the thrdebug command next time.
Updated by Jaroslav Kysela over 6 years ago
It looks like that the key update is invalid (wrong key) and the garbage is not parsed correctly by the tvh's elementary stream parser, but it's just a guess.
Updated by Daniel Wandrei over 6 years ago
With "key" you mean decryption key from oscam? Because I also have the same problem on unecrypted channels.