[vdr] Re: vdr-1.3.17: thread issues with vdr-xine and vdr-osdteletext

[Klaus Schmidinger <Klaus.Schmidinger@cadsoft.de>]

Reinhard Nissl wrote:
> Hi,
>
> after applying all osdteletext patches and poisening VDR there still 
> seems to remain a race condtion when havily zapping between channels.
>
>  From /var/log/messages:
>
> Dec 5 14:00:51 video vdr[1332]: switching to channel 15
> Dec 5 14:00:51 video vdr[1336]: channel 18 (SÜDWEST BW) event 14:00
> 'Landauer Weihnachtscircus' status 4
> Dec 5 14:00:51 video vdr[1400]: transfer thread ended (pid=1400, 
> tid=999434)
> Dec 5 14:00:51 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:51 video vdr[1402]: TS buffer on device 1 thread ended
> (pid=1402, tid=1032204)
> Dec 5 14:00:51 video vdr[1401]: buffer stats: 52076 (2%) used
> Dec 5 14:00:51 video vdr[1401]: receiver on device 1 thread ended
> (pid=1401, tid=1015819)
> Dec 5 14:00:51 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:51 video vdr[1404]: transfer thread started (pid=1404,
> tid=1064970)
> Dec 5 14:00:51 video vdr[1332]: creating directory /vtx/S19.2E-1-1073-28204
> Dec 5 14:00:51 video vdr[1405]: receiver on device 1 thread started
> (pid=1405, tid=1081355)
> Dec 5 14:00:51 video vdr[1406]: TS buffer on device 1 thread started
> (pid=1406, tid=1097740)
> Dec 5 14:00:51 video vdr[1332]: switching to channel 16
> Dec 5 14:00:52 video vdr[1336]: channel 17 (NDR FS NDS) event 13:30
> 'Weihnachten in Ostpreußen' status 4
> Dec 5 14:00:52 video vdr[1404]: transfer thread ended (pid=1404,
> tid=1064970)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1406]: TS buffer on device 1 thread ended
> (pid=1406, tid=1097740)
> Dec 5 14:00:52 video vdr[1405]: buffer stats: 39480 (1%) used
> Dec 5 14:00:52 video vdr[1405]: receiver on device 1 thread ended
> (pid=1405, tid=1081355)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1408]: transfer thread started (pid=1408,
> tid=1130506)
> Dec 5 14:00:52 video vdr[1332]: creating directory /vtx/S19.2E-1-1101-28108
> Dec 5 14:00:52 video vdr[1409]: receiver on device 1 thread started
> (pid=1409, tid=1146891)
> Dec 5 14:00:52 video vdr[1410]: TS buffer on device 1 thread started
> (pid=1410, tid=1163276)
> Dec 5 14:00:52 video vdr[1332]: switching to channel 17
> Dec 5 14:00:52 video vdr[1408]: transfer thread ended (pid=1408,
> tid=1130506)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1410]: TS buffer on device 1 thread ended
> (pid=1410, tid=1163276)
> Dec 5 14:00:52 video vdr[1409]: buffer stats: 23500 (1%) used
> Dec 5 14:00:52 video vdr[1409]: receiver on device 1 thread ended
> (pid=1409, tid=1146891)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1412]: transfer thread started (pid=1412,
> tid=1196042)
> Dec 5 14:00:52 video vdr[1332]: creating directory /vtx/S19.2E-1-1073-28226
> Dec 5 14:00:52 video vdr[1413]: receiver on device 1 thread started
> (pid=1413, tid=1212427)
> Dec 5 14:00:52 video vdr[1415]: TS buffer on device 1 thread started
> (pid=1415, tid=1245197)
> Dec 5 14:00:52 video vdr[1332]: switching to channel 18
> Dec 5 14:00:52 video vdr[1412]: transfer thread ended (pid=1412,
> tid=1196042)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1415]: TS buffer on device 1 thread ended
> (pid=1415, tid=1245197)
> Dec 5 14:00:52 video vdr[1413]: buffer stats: 41172 (1%) used
> Dec 5 14:00:52 video vdr[1413]: receiver on device 1 thread ended
> (pid=1413, tid=1212427)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1416]: transfer thread started (pid=1416,
> tid=1261578)
> Dec 5 14:00:52 video vdr[1332]: creating directory /vtx/S19.2E-1-1101-28113
> Dec 5 14:00:52 video vdr[1417]: receiver on device 1 thread started
> (pid=1417, tid=1277963)
> Dec 5 14:00:52 video vdr[1419]: receiver on device 1 thread started
> (pid=1419, tid=1310733)
> Dec 5 14:00:52 video vdr[1419]: buffer stats: 0 (0%) used
> Dec 5 14:00:52 video vdr[1420]: TS buffer on device 1 thread started
> (pid=1420, tid=1327118)
> Dec 5 14:00:52 video vdr[1421]: TS buffer on device 1 thread started
> (pid=1421, tid=1343503)
> Dec 5 14:00:52 video vdr[1332]: switching to channel 19
> Dec 5 14:00:52 video vdr[1416]: transfer thread ended (pid=1416,
> tid=1261578)
> Dec 5 14:00:52 video vdr[1332]: buffer stats: 0 (0%) used
> Dec 5 14:00:53 video vdr[1421]: TS buffer on device 1 thread ended
> (pid=1421, tid=1343503)
> Dec 5 14:00:53 video vdr[1419]: buffer stats: 60536 (2%) used
>
> At this point VDR crashed. Strange is that there are two receivers (1417 
> , 1419) and two TS buffers (1420, 1421) but only a single transfer 
> thread (1416).
My guess would be that the osdteletext uses the other receiver.

Klaus

> For this crash I've got the following backtrace:
>
> (gdb) backtrace
> #0  0x401e76e9 in free () from /lib/libc.so.6
> #1  0x080bf5cc in ~cRingBufferLinear (this=0x40297010) at ringbuffer.c:185
> #2  0x0808839f in ~cTSBuffer (this=0x8be33f0) at device.c:859
> #3  0x0808a300 in cDvbDevice::CloseDvr (this=0x8bc6898) at dvbdevice.c:1393
> #4  0x08088a49 in cDevice::Action (this=0x8bc6898) at device.c:757
> #5  0x080d054f in cThread::StartThread (Thread=0x8bc6898) at thread.c:229
> #6  0x4004e201 in pthread_start_thread () from /lib/libpthread.so.0
> #7  0x4023effa in clone () from /lib/libc.so.6
>
> Looks like the second receiver is to be freed.
>
> I think this issue is transfer thread related, so it should also appear 
> on FF cards if a recording is running on the FF card and you're zapping 
> to different channels.
>
> Bye.