vdr[75260]: double free or corruption (out)

MarkusE · 24. Mai 2021

Hi,

Hatte ich jetzt mit 2.5.4, nach dem umschalten eines Kanals. Vermutlich nicht reproduzierbar.

Code

May 24 08:19:03 vdr1 vdr: [75260] switching to channel 32 S19.2E-1-1201-28325 (alt_WDR HD Köln)
May 24 08:19:03 vdr1 vdr: [76880] device 2 TS buffer thread ended (pid=75260, tid=76880)
May 24 08:19:03 vdr1 vdr: [76879] buffer stats: 282940 (1%) used
May 24 08:19:13 vdr1 vdr[75260]: double free or corruption (out)
May 24 08:19:13 vdr1 systemd[1]: vdr.service: Main process exited, code=dumped, status=6/ABRT

wirbel · 24. Mai 2021

Hast du evtl Patche angewendet, die Header verändern, ohne danach vdr und alle Plugins neu zu bauen?

MarkusE · 24. Mai 2021

Zitat von wirbel

Hast du evtl Patche angewendet, die Header verändern, ohne danach vdr und alle Plugins neu zu bauen?

Das kann es tatsächlich sein. ich habe mal alles neu gebaut.

MarkusE · 9. Juni 2021

Hi,

Das neu bauen half nicht. Der Fehler tritt wieder auf. Vermutlich ist es der Sundtek Treiber:

Zitat

#0 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50

set = {__val = {0, 80, 4, 94792703908451, 140632690873040, 4, 4, 7904, 140632933895072, 140633426445392, 8, 140633703069807, 94792703906928, 8, 7307199742750123385, 140633426445392}}

pid = <optimized out>

tid = <optimized out>

#1 0x00007fe7d5e80859 in __GI_abort () at abort.c:79

save_stage = 1

act =

{__sigaction_handler = {sa_handler = 0x62696c2f7273752f, sa_sigaction = 0x62696c2f7273752f}, sa_mask = {__val = {31, 31, 7904, 140632933894856, 140633426445392, 8, 140633703069807, 94792703906928, 8, 140633704570923, 140633426445392, 140632933894608, 0, 62, 140632690964048, 140633426444800}}, sa_flags = 62, sa_restorer = 0x3e}

sigs = {__val = {32, 0 <repeats 15 times>}}

#2 0x00007fe7d5eeb3ee in __libc_message (action=action@entry=do_abort, fmt=fmt@entry=0x7fe7d6015285 "%s\n") at ../sysdeps/posix/libc_fatal.c:155

ap = {{gp_offset = 24, fp_offset = 0, overflow_arg_area = 0x7fe7c56d4320, reg_save_area = 0x7fe7c56d42b0}}

fd = <optimized out>

list = <optimized out>

nlist = <optimized out>

cp = <optimized out>

#3 0x00007fe7d5ef347c in malloc_printerr (str=str@entry=0x7fe7d6017670 "double free or corruption (out)") at malloc.c:5347

#4 0x00007fe7d5ef5120 in _int_free (av=0x7fe7d6046b80 <main_arena>, p=0x7fe7a80e85b0, have_lock=<optimized out>) at malloc.c:4314

size = 384

fb = <optimized out>

nextchunk = 0x7fe7a80e8730

nextsize = <optimized out>

nextinuse = <optimized out>

prevsize = <optimized out>

bck = <optimized out>

fwd = <optimized out>

__PRETTY_FUNCTION__ = "_int_free"

#5 0x00007fe7d662b1e9 in () at /opt/lib/libmediaclient.so

#6 0x00007fe7d66233cd in close () at /opt/lib/libmediaclient.so

#7 0x00005636a56e6651 in cSectionHandler::Del(cFilterData const*) (this=0x5636a639d610, FilterData=FilterData@entry=0x7fe799ba22c0) at sections.c:108

fh = 0x7fe79bca04b0

#8 0x00005636a5690650 in cFilter::Del(unsigned short, unsigned char, unsigned char) (this=this@entry=0x5636a63a6330, Pid=<optimized out>, Tid=Tid@entry=2 '\002', Mask=Mask@entry=255 '\377')

at filter.c:229

fd = 0x7fe799ba22c0

#9 0x00005636a56cd3d7 in cPatFilter::SwitchToNextPmtPid() (this=this@entry=0x5636a63a6330) at pat.c:410

#10 0x00005636a56cebe9 in cPatFilter::Process(unsigned short, unsigned char, unsigned char const*, int)

(this=0x5636a63a6330, Pid=<optimized out>, Tid=<optimized out>, Data=0x7fe7c56d4dc0 "\002\260;n\322\301", Length=<optimized out>) at pat.c:479

pmt =

{<SI::NumberedSection> = {<SI::CRCSection> = {<SI::Section> = {<SI::Object> = {<SI::Parsable> = {_vptr.Parsable = 0x5636a5798bb0 <vtable for SI::PMT+16>, parsed = true}, data = {data_ = 0x7fe799c63f80, off = 0}}, <No data fields>}, <No data fields>}, <No data fields>}, commonDescriptors = {<SI::Loop> = {<SI::VariableLengthPart> = {<SI::Object> = {<SI::Parsable> = {_vptr.Parsable = 0x5636a57927d0 <vtable for SI::DescriptorLoop+16>, parsed = false}, data = {data_ = 0x7fe799c63f80, off = 12}}, length = 0}, <No data fields>}, domain = SI::SI}, streamLoop = {<SI::Loop> = {<SI::VariableLengthPart> = {<SI::Object> = {<SI::Parsable> = {_vptr.Parsable = 0x5636a57958c0 <vtable for SI::StructureLoop<SI::PMT::Stream>+16>, parsed = false}, data = {data_ = 0x7fe799c63f80, off = 12}}, length = 46}, <No data fields>}, <No data fields>}, s = 0x7fe7c56d4dc0}

StateKey = {stateLock = 0x3d600010000, write = 100, state = 0, timedOut = 240}

Channels = <optimized out>

Channel = <optimized out>

ChannelsModified = <optimized out>

MutexLock = {mutex = 0x5636a63a63e8, locked = true}

#11 0x00005636a56e6b56 in cSectionHandler::Action() (this=0x5636a639d610) at sections.c:211

fi = 0x5636a63a6330

pid = 330

tid = 2

len = 62

buf = "\002\260;n\322\301\000\000\341K\360\000$\341K\360\003R\001\037\021\341L\360\tR\001 \n\004deu\000\021\341M\360\tR\001!\n\004deu\000\005\341R\360\005o\003\000\020\340BC,\270\005Die besten Zoogeschichten aus NRWi\003\373\062\031P\016\365\v\tdeuHD-VideoP\f\362\003\002deustereoP\033\362\003\004deuohne AudiodeskriptionP\027\364B\021deuDolby Digital 2.0P\031\363 \006deuohne DV"...

r = <optimized out>

fh = <optimized out>

ThreadLock = {thread = 0x5636a639d610, locked = true}

i = <optimized out>

DeviceHasLock = true

NumFilters = <optimized out>

pfd = <optimized out>

oldStatusCount = 6031

#12 0x00005636a570fde5 in cThread::StartThread(cThread*) (Thread=0x5636a639d610) at thread.c:301
#13 0x00007fe7d6567609 in start_thread (arg=<optimized out>) at pthread_create.c:477

ret = <optimized out>

pd = <optimized out>

unwind_buf = {cancel_jmp_buf = {{jmp_buf = {140633426454272, -2587623203588688243, 140732040950878, 140732040950879, 140732040950880, 140633426452224, 2583202227297040013, 2583173699283433101}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}

not_first_call = 0

#14 0x00007fe7d5f7d293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

Alles anzeigen

wirbel · 10. Juni 2021

Ja. Ist closed source bei sowas nicht wunderbar? *g*

MarkusE · 10. Juni 2021

Ich habe auch eine Cine S2. Ich habe den Treiber de-aktiviert. Zu instabil. Da hilft auch kein open source ...

wirbel · 10. Juni 2021

Interessant, bei mir laufen DD C/T2 Karten zumindest bestens.

Sundtek · 14. Juni 2021

Zitat von MarkusE

Hi,

Das neu bauen half nicht. Der Fehler tritt wieder auf. Vermutlich ist es der Sundtek Treiber:

https://support.sundtek.de/chat.php

Du kannst ja mal vorbeischauen, dann kann ich mir das eventuell bei Dir ansehen. Aktuell ist da eigentlich kein Problem bekannt (und alle Kunden der letzten 12 Jahre verwenden den gleichen Treiber).

MarkusE · 15. Juni 2021

Hallo,

Ich habe https://support.sundtek.de/chat.php ausprobiert. Wenn ich auf diese Seite gehe, kommt eine Fehlermeldung:

Chromium: "webchat.freenode.net hat die Verbindung abgelehnt."

Firefox: https://support.mozilla.org/de…as=u&utm_source=inproduct

Sundtek · 17. Juni 2021

Der Webchat geht jetzt wieder der Freenode Link wurde auf libera.chat geändert

(bin aber für heut nicht mehr online).

Der Web-Chat ist nun auf irc.libera.chat #sundtek

MarkusE · 20. Juni 2021

Hallo,

Das mit dem Chat funktioniert irgendwie nicht.

Es sind 2 Sundtek SkyTV Ultimate (DVB-S/S2), die ich 2011 gekauft habe.

Könnte ich einen Treiber bekommen, der aus dieser Zeit stammt, z.B. von 2013 ? Dann würde ich versuchen, ob der mit mit ubuntu 20.04 focal noch läuft.

~Markus

Sundtek · 22. Juni 2021

Wüsste nicht was da bei dem Chat nicht klappen sollte.

Wenn ich Zugriff habe kann ich anschauen was da gemacht wird (im VDR Code bzw. in unserem Treiber).

Wir supporten (natürlich) auch nur die aktuelle Treiberversion, da diese allen zur Verfügung steht.

Zwischen 2013 - 2021 sind die Unterschiede zu groß (vielleicht nicht im Treiber, aber im Framework).

Nachtrag

wie unterhalb erwähnt der Link, Kanal #sundtek

9000H · 22. Juni 2021

https://web.libera.chat/

Sundtek · 25. Juni 2021

Die Probleme sehen mir ein bißchen größer aus, und vor allem im Bereich des PAT Filters.

(die ersten beiden IOCTLs wurden entfernt da sie nicht initialisiert ok sind (und mittendrinnen wurden auch einige entfernt um das 10.000 Zeichen limit nicht zu übersteigen):

https://pastebin.com/inurDVwX

Code

==234899== 
==234899== Invalid free() / delete / delete[] / realloc()
==234899==    at 0x483D1CF: operator delete(void*, unsigned long) (in /usr/lib/x86_64-linux-gnu/valgrind/vgpreload_memcheck-amd64-linux.so)
==234899==    by 0x27D171: cListBase::Del(cListObject*, bool) (tools.c:2227)
==234899==    by 0x22B3D6: cPatFilter::SwitchToNextPmtPid() (pat.c:410)
==234899==    by 0x22CBE8: cPatFilter::Process(unsigned short, unsigned char, unsigned char const*, int) (pat.c:479)
==234899==    by 0x244B55: cSectionHandler::Action() (sections.c:211)
==234899==    by 0x26DDE4: cThread::StartThread(cThread*) (thread.c:301)
==234899==    by 0x4B03608: start_thread (pthread_create.c:477)
==234899==    by 0x5153292: clone (clone.S:95)
==234899==  Address 0xee13bc0 is 0 bytes inside a block of size 32 free'd
==234899==    at 0x483D1CF: operator delete(void*, unsigned long) (in /usr/lib/x86_64-linux-gnu/valgrind/vgpreload_memcheck-amd64-linux.so)
==234899==    by 0x27D171: cListBase::Del(cListObject*, bool) (tools.c:2227)
==234899==    by 0x1EDF1E: SetStatus (filter.c:189)
==234899==    by 0x1EDF1E: cFilter::SetStatus(bool) (filter.c:178)
==234899==    by 0x22D21D: cPatFilter::SetStatus(bool) (pat.c:350)
==234899==    by 0x24476A: cSectionHandler::SetStatus(bool) (sections.c:152)
==234899==    by 0x1CC2B8: cDevice::SetChannel(cChannel const*, bool) (device.c:891)
==234899==    by 0x1CC7C5: cDevice::SwitchChannel(cChannel const*, bool) (device.c:809)
==234899==    by 0x1E845B: cEITScanner::Process() (eitscan.c:168)
==234899==    by 0x1AA833: main (vdr.c:1519)
==234899==  Block was alloc'd at
==234899==    at 0x483BE63: operator new(unsigned long) (in /usr/lib/x86_64-linux-gnu/valgrind/vgpreload_memcheck-amd64-linux.so)
==234899==    by 0x1EE488: cFilter::Add(unsigned short, unsigned char, unsigned char, bool) (filter.c:218)
==234899==    by 0x22CBE8: cPatFilter::Process(unsigned short, unsigned char, unsigned char const*, int) (pat.c:479)
==234899==    by 0x244B55: cSectionHandler::Action() (sections.c:211)
==234899==    by 0x26DDE4: cThread::StartThread(cThread*) (thread.c:301)
==234899==    by 0x4B03608: start_thread (pthread_create.c:477)
==234899==    by 0x5153292: clone (clone.S:95)
==234899== 
==234899== Invalid read of size 8
==234899==    at 0x22B3DE: Next (tools.h:654)
==234899==    by 0x22B3DE: Next (tools.h:665)
==234899==    by 0x22B3DE: cPatFilter::SwitchToNextPmtPid() (pat.c:411)
==234899==    by 0x22CBE8: cPatFilter::Process(unsigned short, unsigned char, unsigned char const*, int) (pat.c:479)
==234899==    by 0x244B55: cSectionHandler::Action() (sections.c:211)
==234899==    by 0x26DDE4: cThread::StartThread(cThread*) (thread.c:301)
==234899==    by 0x4B03608: start_thread (pthread_create.c:477)
==234899==    by 0x5153292: clone (clone.S:95)
==234899==  Address 0x10 is not stack'd, malloc'd or (recently) free'd
==234899== 
==234899== 
==234899== Process terminating with default action of signal 11 (SIGSEGV): dumping core
==234899==  Access not within mapped region at address 0x10
==234899==    at 0x22B3DE: Next (tools.h:654)
==234899==    by 0x22B3DE: Next (tools.h:665)
==234899==    by 0x22B3DE: cPatFilter::SwitchToNextPmtPid() (pat.c:411)
==234899==    by 0x22CBE8: cPatFilter::Process(unsigned short, unsigned char, unsigned char const*, int) (pat.c:479)
==234899==    by 0x244B55: cSectionHandler::Action() (sections.c:211)
==234899==    by 0x26DDE4: cThread::StartThread(cThread*) (thread.c:301)
==234899==    by 0x4B03608: start_thread (pthread_create.c:477)
==234899==    by 0x5153292: clone (clone.S:95)
==234899==  If you believe this happened as a result of a stack
==234899==  overflow in your program's main thread (unlikely but
==234899==  possible), you can try to increase the size of the
==234899==  main thread stack using the --main-stacksize= flag.
==234899==  The main thread stack size used in this run was 8388608.
==234899== 
==234899== HEAP SUMMARY:
==234899==     in use at exit: 85,790,583 bytes in 1,288,520 blocks
==234899==   total heap usage: 2,467,507 allocs, 1,178,989 frees, 2,204,044,461 bytes allocated
==234899== 
==234899== LEAK SUMMARY:
==234899==    definitely lost: 20,056 bytes in 496 blocks
==234899==    indirectly lost: 448 bytes in 14 blocks
==234899==      possibly lost: 2,240 bytes in 7 blocks
==234899==    still reachable: 85,767,839 bytes in 1,288,003 blocks
==234899==         suppressed: 0 bytes in 0 blocks
==234899== Rerun with --leak-check=full to see details of leaked memory
==234899== 
==234899== Use --track-origins=yes to see where uninitialised values come from
==234899== For lists of detected and suppressed errors, rerun with: -s
==234899== ERROR SUMMARY: 27 errors from 20 contexts (suppressed: 0 from 0)
Speicherzugriffsfehler (Speicherabzug geschrieben)

Alles anzeigen

ist da irgendetwas bekannt?

MarkusE · 26. Juni 2021

Hallo,

Markus (Sundtek) hat sich gestern die Mühe gemacht, mehrere Stunden in meinem System den Fehler zu suchen. Danke dafür!

Er kam zu dem Ergebnis, dass der Fehler wohl bei vdr liegt. ("Der PAT Filter wird wohl initialisiert nachdem die Filter Instanz im VDR bereits gelöscht wurde.")

Anscheinend macht vdr Version 2.5.4, Probleme, (z.B. auch bei EXIP418 SatIP Box von Kathrein).

Bleibt die Frage, ob der Fix in vdr Version 2.5.5 den Fehler behebt, oder nur dafür sorgt, dass der Fehler seltener auftritt.

Ich habe also mit vdr Version 2.5.5 auch getestet, und hier das Ergebnis:

vdr Version 2.5.4: Der Fehler tritt regelmäßig auf, ca. alle 10 min. Dazu muss der Anwender nichts machen
vdr Version 2.5.5: Wenn der Anwender nichts macht, tritt der Fehler auch nicht auf (zumindest nicht in der Zeit, die ich bisher getestet habe). Durch Kanalwechsel kann der Fehler aber ausgelöst werden (ca. 30-40 Kanalwechsel)

~ Markus

Sundtek · 26. Juni 2021

Kannst du vdr mit valgrind starten?

MarkusE · 27. Juni 2021

Ich denke, ich habe den Fehler gefunden. Ich muss aber noch testen

kls · 2. Juli 2021

MarkusE Kannst du bitte testen, ob das mit der Version 2.5.6, die ich heute ins GIT eingespielt habe, immer noch auftritt?

Und bitte ohne jegliche Plugins (ausser für Empfang bzw. Ausgabe nötigen) testen.

vdr[75260]: double free or corruption (out)

Jetzt mitmachen!

Teilen