Project

General

Profile

Bug #5326

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 Илья Ваганов almost 6 years ago. Updated almost 6 years ago.

Status:
New
Priority:
Normal
Assignee:
-
Category:
HTSP
Target version:
-
Start date:
2018-11-11
Due date:
% Done:

0%

Estimated time:
Found in version:
long time ago
Affected Versions:

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

History

#1

Updated by Mark Clarkstone almost 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?

#2

Updated by Илья Ваганов almost 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.

#3

Updated by Илья Ваганов almost 6 years ago

tvheadend-git 4.3.r1529.gc8bbae504-1 (latest)

#5

Updated by saen acro almost 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

Ждем отчета ;)

#6

Updated by Mark Clarkstone almost 6 years ago

Guys, if it's easier use Russian, just be sure to write a translation under it! :)

#7

Updated by saen acro almost 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 ;)

#8

Updated by Joe User almost 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". ;)

#9

Updated by saen acro almost 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 ;)

#10

Updated by Daniel Wandrei almost 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.

#11

Updated by Luis Alves almost 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?

#12

Updated by Илья Ваганов almost 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.

#13

Updated by Daniel Wandrei almost 6 years ago

Here is my complete log.

#14

Updated by Jaroslav Kysela almost 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

#15

Updated by Daniel Wandrei almost 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.

#16

Updated by Jaroslav Kysela almost 6 years ago

Run tvh with the log options, watch one channel for 60 seconds, stop tvh.

#17

Updated by Илья Ваганов almost 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)

#20

Updated by Илья Ваганов almost 6 years ago

2018-11-19 14:42:59.718 config: unkown subsystem 'parsers'

#22

Updated by Илья Ваганов almost 6 years ago

It will be right 'trace --parser' ;)

#24

Updated by Luis Alves almost 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...

#28

Updated by Jaroslav Kysela almost 6 years ago

No "DTS and PCR diff is very big" lines in log. Please, think about what are you trying to report.

#29

Updated by Илья Ваганов almost 6 years ago

This problem occurs periodically, but not so often. Need time to wait for... :)

#30

Updated by Daniel Wandrei almost 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.

#31

Updated by Илья Ваганов almost 6 years ago

Hi, guys! Just caught a error...

#32

Updated by Jaroslav Kysela almost 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.

#33

Updated by Daniel Wandrei almost 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".

#34

Updated by saen acro almost 6 years ago

Event "too much input data" need to be direct connected to restart service action.

#35

Updated by Daniel Wandrei almost 6 years ago

At this point, I can't restart tvheadend, the service does not respond anymore. I have to forcfully kill it.

#36

Updated by Jaroslav Kysela almost 6 years ago

@Daeniel, parser traces are missing. --trace pcr,parsers

#38

Updated by Daniel Wandrei almost 6 years ago

I can not log parsers: unknown subsystem "parsers".

#39

Updated by Илья Ваганов almost 6 years ago

Daniel Wandrei wrote:

I can not log parsers: unknown subsystem "parsers".

It will be right 'trace --pcr,parser'

#41

Updated by Jaroslav Kysela almost 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)
#42

Updated by Илья Ваганов almost 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>

#43

Updated by Daniel Wandrei almost 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)
#44

Updated by Luis Alves almost 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.

#45

Updated by Daniel Wandrei almost 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.

#46

Updated by Jaroslav Kysela almost 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.

#47

Updated by Daniel Wandrei almost 6 years ago

With "key" you mean decryption key from oscam? Because I also have the same problem on unecrypted channels.

#48

Updated by Jaroslav Kysela almost 6 years ago

Ok, then it's something different.

Also available in: Atom PDF