Project

General

Profile

Bug #3383

AAC parser fails

Added by Bengt Madeberg almost 9 years ago. Updated almost 9 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
-
Category:
SAT>IP
Target version:
-
Start date:
2015-12-05
Due date:
% Done:

100%

Estimated time:
Found in version:
HTS Tvheadend 4.1-1159~g4484874
Affected Versions:

Description

Since I upgraded I have buffering issues all the time when watching channels that comes from an another tvh installation thats runs like a SAT>IP server.

tvh (SAT>IP server), HTS Tvheadend 4.1-1076~g96be488
tvh (SAT/IP client and main tvh server), HTS Tvheadend 4.1-1159~g4484874

The intervalls between buffering are sometimes very short less than 30 s. The buffering period is also very short but no problem to notice.

I don't see any streaming errors in any of the two tvh instances. But I can see drops and skips increasing in kodi.

I did not have this issue with tvheadend_4.1-589~g1d62c3a (SAT>IP server and main tvh server).

Any buffer that changed or I can change ?

Files

syslog.txt (767 KB) syslog.txt Bengt Madeberg, 2015-12-14 17:17
mux6.ts (163 MB) mux6.ts Bengt Madeberg, 2015-12-16 18:03
main_smile[1].png (791 Bytes) main_smile[1].png Bengt Madeberg, 2016-01-12 00:31

History

#1

Updated by Jaroslav Kysela almost 9 years ago

Provide some logs - for example debug log for the service subsystem - there should be visible when packets are received .

#2

Updated by Bengt Madeberg almost 9 years ago

Ok upgraded to HTS Tvheadend 4.1-1221~g66e5d27 todau and the problem is still there maybe not so frequent but frquent enough to be annaying. Added "service" to the logging. I can se drops in kodi when this happens.

Dec 13 12:02:22 server1a tvheadend31446: parser: transport stream AAC, DTS discontinuity. DTS = 34440179, last = 34326899

Dec 13 11:55:56 server1a tvheadend[31446]: subscription: 002C: "192.168.0.65 [ muy | Kodi Media Center ]" unsubscribing from "TV4 Göteborg", hostname="192.168.0.65", username="muy", client="Kodi Media Center" 
Dec 13 11:55:56 server1a tvheadend[31446]: service: 9: TV4 HD Göteborg si 0x4f67e10 SAT>IP DVB-T Tuner #1 (192.168.0.93:9983) weight 0 prio 10 error 0
Dec 13 11:55:56 server1a tvheadend[31446]: mpegts: 570MHz in Teracom_sat_ip - tuning on SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)
Dec 13 11:55:56 server1a tvheadend[31446]: subscription: 002D: "192.168.0.65 [ muy | Kodi Media Center ]" subscribing on channel "TV4 HD Göteborg", weight: 150, adapter: "SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)", network: "Teracom_sat_
ip", mux: "570MHz", provider: "TV4 AB", service: "TV4 HD Göteborg", profile="htsp", hostname="192.168.0.65", username="muy", client="Kodi Media Center" 
Dec 13 11:55:59 server1a tvheadend[31446]: service: Teracom_sat_ip/570MHz/TV4 HD Göteborg: Status changed to [Hardware input]
Dec 13 11:55:59 server1a tvheadend[31446]: service: Teracom_sat_ip/570MHz/TV4 HD Göteborg: Status changed to [Hardware input] [Input on service]
Dec 13 11:55:59 server1a tvheadend[31446]: service: Teracom_sat_ip/570MHz/TV4 HD Göteborg: Status changed to [Hardware input] [Input on service] [Demuxed packets]
Dec 13 11:55:59 server1a tvheadend[31446]: service: Teracom_sat_ip/570MHz/TV4 HD Göteborg: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets]
Dec 13 11:56:15 server1a tvheadend[31446]: subscription: 0008: "epggrab" unsubscribing
Dec 13 11:56:16 server1a tvheadend[31446]: service: 8: 11464V in Astra2 si 0x3816550 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:56:16 server1a tvheadend[31446]: service: 6: 11464V in Astra2 si 0x36162b0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:56:16 server1a tvheadend[31446]: service: 5: 11464V in Astra2 si 0x31d1730 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:56:20 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 1850099, last = 1750259
Dec 13 11:56:24 server1a tvheadend[31446]: subscription: 0001: "epggrab" unsubscribing
Dec 13 11:56:25 server1a tvheadend[31446]: service: 8: 11464V in Astra2 si 0x335bd10 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:56:25 server1a tvheadend[31446]: service: 6: 11464V in Astra2 si 0x3660520 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:56:25 server1a tvheadend[31446]: service: 5: 11464V in Astra2 si 0x548ebb0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:57:29 server1a tvheadend[31446]: service: 8: 11464V in Astra2 si 0x38068e0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:57:29 server1a tvheadend[31446]: service: 6: 11464V in Astra2 si 0x4423340 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:57:29 server1a tvheadend[31446]: service: 5: 11464V in Astra2 si 0x380bee0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:57:45 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 9487859, last = 9395699
Dec 13 11:58:33 server1a tvheadend[31446]: service: 8: 11464V in Astra2 si 0x37682e0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:33 server1a tvheadend[31446]: service: 6: 11464V in Astra2 si 0x3691860 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:33 server1a tvheadend[31446]: service: 5: 11464V in Astra2 si 0x36ebd90 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:47 server1a tvheadend[31446]: epggrab: EIT: DVB Grabber - data completion timeout for 11386V in Astra2
Dec 13 11:58:47 server1a tvheadend[31446]: epggrab: UK: Freesat - data completion timeout for 11386V in Astra2
Dec 13 11:58:47 server1a tvheadend[31446]: subscription: 0002: "epggrab" unsubscribing
Dec 13 11:58:48 server1a tvheadend[31446]: service: 5: 11464V in Astra2 si 0x4dc81a0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 0 prio 10 error 0
Dec 13 11:58:48 server1a tvheadend[31446]: service: 8: 11464V in Astra2 si 0x36162b0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:48 server1a tvheadend[31446]: service: 6: 11464V in Astra2 si 0x31ced50 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:48 server1a tvheadend[31446]: mpegts: 11464V in Astra2 - tuning on TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0
Dec 13 11:58:48 server1a tvheadend[31446]: subscription: 0032: "epggrab" subscribing to mux "11464V", weight: 4, adapter: "TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0", network: "Astra2", service: "Raw PID Subscription" 
Dec 13 11:58:48 server1a tvheadend[31446]: service: 8: 11836V in Astra2 si 0x36ebd90 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:48 server1a tvheadend[31446]: service: 6: 11836V in Astra2 si 0x3691860 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:48 server1a tvheadend[31446]: service: 5: 11836V in Astra2 si 0x37682e0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:58:49 server1a tvheadend[31446]: service: 11464V in Astra2: Status changed to [Demuxed packets]
Dec 13 11:58:49 server1a tvheadend[31446]: service: 11464V in Astra2: Status changed to [Demuxed packets] [Reassembled packets]
Dec 13 11:59:28 server1a tvheadend[31446]: epggrab: UK: Freesat - data completion timeout for 11264H in Astra2
Dec 13 11:59:28 server1a tvheadend[31446]: epggrab: EIT: DVB Grabber - data completion timeout for 11264H in Astra2
Dec 13 11:59:28 server1a tvheadend[31446]: subscription: 000D: "epggrab" unsubscribing
Dec 13 11:59:29 server1a tvheadend[31446]: service: 8: 11836V in Astra2 si 0x3603540 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 0 prio 10 error 0
Dec 13 11:59:29 server1a tvheadend[31446]: service: 6: 11836V in Astra2 si 0x37c2390 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:59:29 server1a tvheadend[31446]: service: 5: 11836V in Astra2 si 0x54644d0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:59:29 server1a tvheadend[31446]: mpegts: 11836V in Astra2 - tuning on TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0
Dec 13 11:59:29 server1a tvheadend[31446]: subscription: 0034: "epggrab" subscribing to mux "11836V", weight: 4, adapter: "TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0", network: "Astra2", service: "Raw PID Subscription" 
Dec 13 11:59:29 server1a tvheadend[31446]: service: 8: 11260V in Astra2 si 0x3812560 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:59:29 server1a tvheadend[31446]: service: 6: 11260V in Astra2 si 0x3120360 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:59:29 server1a tvheadend[31446]: service: 5: 11260V in Astra2 si 0x3731280 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 11:59:32 server1a tvheadend[31446]: service: 11836V in Astra2: Status changed to [Demuxed packets]
Dec 13 11:59:32 server1a tvheadend[31446]: service: 11836V in Astra2: Status changed to [Demuxed packets] [Reassembled packets]
Dec 13 12:00:02 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 21756659, last = 21554579
Dec 13 12:01:13 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 28150259, last = 27895578
Dec 13 12:01:31 server1a tvheadend[31446]: epggrab: UK: Freesat - data completion timeout for 11307V in Astra2
Dec 13 12:01:31 server1a tvheadend[31446]: epggrab: EIT: DVB Grabber - data completion timeout for 11307V in Astra2
Dec 13 12:01:31 server1a tvheadend[31446]: subscription: 0018: "epggrab" unsubscribing
Dec 13 12:01:32 server1a tvheadend[31446]: service: 6: 11260V in Astra2 si 0x4e78840 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 0 prio 10 error 0
Dec 13 12:01:32 server1a tvheadend[31446]: service: 8: 11260V in Astra2 si 0x4234a40 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:01:32 server1a tvheadend[31446]: service: 5: 11260V in Astra2 si 0x42075a0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:01:32 server1a tvheadend[31446]: mpegts: 11260V in Astra2 - tuning on TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0
Dec 13 12:01:32 server1a tvheadend[31446]: subscription: 0036: "epggrab" subscribing to mux "11260V", weight: 4, adapter: "TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0", network: "Astra2", service: "Raw PID Subscription" 
Dec 13 12:01:32 server1a tvheadend[31446]: service: 8: 10788H in Astra2 si 0x380ea40 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:01:32 server1a tvheadend[31446]: service: 6: 10788H in Astra2 si 0x377a670 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:01:32 server1a tvheadend[31446]: service: 5: 10788H in Astra2 si 0x3210a80 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:01:34 server1a tvheadend[31446]: service: 11260V in Astra2: Status changed to [Demuxed packets]
Dec 13 12:01:34 server1a tvheadend[31446]: service: 11260V in Astra2: Status changed to [Demuxed packets] [Reassembled packets]
Dec 13 12:02:22 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 34440179, last = 34326899
Dec 13 12:03:48 server1a tvheadend[31446]: subscription: 002B: "epggrab" unsubscribing
Dec 13 12:03:49 server1a tvheadend[31446]: parser: transport stream AAC, DTS discontinuity. DTS = 42181619, last = 42043379
Dec 13 12:03:49 server1a tvheadend[31446]: service: 8: 10788H in Astra2 si 0x37ce4f0 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:03:49 server1a tvheadend[31446]: service: 6: 10788H in Astra2 si 0x37e4b30 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:03:49 server1a tvheadend[31446]: service: 5: 10788H in Astra2 si 0x31d3540 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:04:30 server1a tvheadend[31446]: subscription: 0028: "epggrab" unsubscribing
Dec 13 12:04:31 server1a tvheadend[31446]: service: 8: 10788H in Astra2 si 0x37bc340 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:04:31 server1a tvheadend[31446]: service: 6: 10788H in Astra2 si 0x3731280 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
Dec 13 12:04:31 server1a tvheadend[31446]: service: 5: 10788H in Astra2 si 0x31ce540 TurboSight TBS 6904 DVBS/S2 frontend : DVB-S #0 weight 4 prio 10 error 0
#3

Updated by Bengt Madeberg almost 9 years ago

And still no errors in the status tabs.

#4

Updated by Jaroslav Kysela almost 9 years ago

OK. Provide the log when one service is streamed and DTS discontinuity errors occur with "--trace parser" . https://tvheadend.org/projects/tvheadend/wiki/Traces

#5

Updated by Bengt Madeberg almost 9 years ago

Ok, don't know if I did it correct but here is the log. This time I got many drops but not all got into the log, only one...

Dec 14 00:36:18 server1a tvheadend[1904]: subscription: 000F: "192.168.0.65 [ muy | Kodi Media Center ]" unsubscribing from "TV4 HD Göteborg", hostname="192.168.0.65", username="muy", client="Kodi Media Center" 
Dec 14 00:36:20 server1a tvheadend[1904]: mpegts: 570MHz in Teracom_sat_ip - tuning on SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)
Dec 14 00:36:20 server1a tvheadend[1904]: subscription: 0012: "192.168.0.65 [ muy | Kodi Media Center ]" subscribing on channel "TV4 HD Göteborg", weight: 150, adapter: "SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)", network: "Teracom_sat_ip", mux: "570MHz", provider: "TV4 AB", service: "TV4 HD Göteborg", profile="htsp", hostname="192.168.0.65", username="muy", client="Kodi Media Center" 
Dec 14 00:37:02 server1a tvheadend[1904]: parser: transport stream AAC, DTS discontinuity. DTS = 3490560, last = 3348480
#6

Updated by Jaroslav Kysela almost 9 years ago

Provide traces..

#8

Updated by Bengt Madeberg almost 9 years ago

I did a normal kodi htsp connection to the SAT>IP "server". I have the same issues with drops. So it's not SAT>IP related. It seems to be related to AAC. Another channel using AC3 !? does bot have the problem. Just some skips which seems to be non-visable. I will investigate further and provide traces

#9

Updated by Jaroslav Kysela almost 9 years ago

  • Subject changed from Cascading of tvh with SAT>IP causes client (kodi) buffering to AAC parser fails

OK: 'parser: AAC skip byte e7' - it's bad - a bug in the parser. Could you provide me the mpeg-ts stream for this service when AAC decoding fails? You may give me the full mux (play link in the mux grid) - cca 30 seconds - use wget or curl to save this stream to a file.

#10

Updated by Bengt Madeberg almost 9 years ago

Ok, here is one attempt hopefully some errors. Tvh did go into dead lock once, not in this stream but in another attempt so maybe its related to this problem ?

https://tvheadend.org/issues/3381#change-16855

The version for tvh is HTS Tvheadend 4.1-1076~g96be488 for the ts stream.

Hmm there seems to be some upload problems ?

#11

Updated by Bengt Madeberg almost 9 years ago

The file is 167 MB and max size is 488.281 MB but I can't upload it. Hopefully it's here.

http://www.filedropper.com/mux6

#12

Updated by Mark Clarkstone almost 9 years ago

Hmm there seems to be some upload problems ?

This website is behind cloudflare and uploads are limited to 100MB, try uploading directly to the server via http://pam.exsilia.net/issues/3383.

#13

Updated by Bengt Madeberg almost 9 years ago

2nd try.

#14

Updated by Bengt Madeberg almost 9 years ago

Any useful info in the ts file or should I provide more info ?

#15

Updated by Jaroslav Kysela almost 9 years ago

It's fine - just xmas working hours here :-)

#16

Updated by Bengt Madeberg almost 9 years ago

Sorry for bringing it up again but is there any quick fix for this bug ? Otherwise I have to downgrade to 589. This issue occurred after that version I think. So once in the past it was not any problem. All major HD channels in the Swedish T2 network use AAC (and sometimes AC3) and all these drops and skips sometimes causing buffering are really annoying when watching live TV. Recordings suffer not from buffering but parts that totally disappears like someone do a short fast forward.

#17

Updated by Jaroslav Kysela almost 9 years ago

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

Applied in changeset commit:tvheadend|bd36886ec9d64667dee25eed55378ed27a35ec35.

#18

Updated by Jaroslav Kysela almost 9 years ago

Fixed in v4.1-1353-gbd36886 .

#19

Updated by Bengt Madeberg almost 9 years ago

Great, thanks :) . Upgraded both client and server. Will do some testing...

Also available in: Atom PDF