[vdr] stream broken w. 1.3.18

To: <vdr@linuxtv.org>
Subject: [vdr] stream broken w. 1.3.18
From: Rainer Zocholl <UseNet-Posting-Nospam-74308-@zocki.toppoint.de>
Date: 14 Jan 2005 17:01:00 +0100
>received: by zocki.toppoint.de (CrossPoint/FreeXP v3.40 RC3 (EMS) @ 3108030030 R/C6515);14 Jan 2005 17:01:29 +0100
Content-transfer-encoding: 8bit
Content-type: text/plain; charset=ISO-8859-1
List-help: <mailto:ecartis@linuxtv.org?subject=help>
List-id: vdr <vdr.mail>
List-owner: <mailto:listmaster@convergence.de>
List-post: <mailto:vdr@linuxtv.org>
List-software: Ecartis version 1.0.0
List-subscribe: <mailto:ecartis@linuxtv.org?subject=subscribe%20vdr>
List-unsubscribe: <mailto:ecartis@linuxtv.org?subject=unsubscribe%20vdr>
Organization: http://www.toppoint.de
Reply-to: vdr@linuxtv.org
Sender: vdr-bounce@linuxtv.org


With 1.3.18 there are again problems with "budget" cards.

i have 3 DVB-FF 1.5/2.1 and one DVB-T AverMedia 771
recently i recorded one sat channel (ESA ;-) )
and tried to watch other programs on the DVB-T card.
Every some 10sec a noticed a drop an found an emergy exit in
vdr logfile.

Too i recently mailed that multicordings failed.


With 1.3.17 and all the thread safe patches i did not notice that
effects anymore.
Now they seems to be back.

Currently i have not replaced the DVB with the version 
on Klaus ftp servier but using teh Version of Dec 15th.



 grep -i "emergency exit\!" /var/log/syslog

gives  that event times:

 18:58:11
 18:59:04
 18:59:56
 19:00:48
 19:29:34
 19:30:27
 19:31:19
 19:32:12
 19:33:05
 19:33:55
 19:34:46
 19:36:31
 19:37:23
 19:38:13
 19:39:06
 19:39:58
 19:40:50
 19:41:43
 19:42:35
 19:43:26
 19:44:16
 19:45:09
 19:46:00
 19:46:53
 19:47:44
 19:48:35
 19:49:27
 19:50:21
 19:51:13
 19:52:06
 19:52:59
 19:53:50
 19:54:42
 19:55:32
 19:56:24
 19:57:14
 19:58:07
 19:59:00

 20:15:32
 20:16:26
 20:17:20
 20:18:14
 20:19:07
 20:20:01
 20:20:54
 20:21:46
 20:23:28
 20:24:21
 20:25:12
 20:26:06
 20:27:00
 20:27:54
 20:28:46
 20:29:37
 20:30:30
 20:31:24
 20:32:14
 20:33:07
 20:34:01
 20:34:55
 20:35:46
 20:36:39
 20:37:30
 20:38:23
 20:39:15
 20:40:08
 20:41:02
 20:41:56
 20:42:49
 20:43:41
 20:44:35
 20:45:26
 20:46:17
 20:47:11
 20:48:03
 20:48:55
 20:49:46
 20:50:38
 20:51:31
 20:52:23
 20:53:15
 20:54:07

 08:17:05
 08:18:06
 08:19:02
 08:20:00



Some details:

("7x" is DVB-T)

8:18 28992 loading /video0/themes/sttng-default.theme
8:18 msi lircd 0.7.0[1419 accepted new client on /dev/lircd
8:18 29009 LIRC remote control thread started (pid=29009, tid=147466)
8:18 28992 remote control LIRC - keys known
8:18 28992 switching to channel 75
8:21 msi kernel: setting parameters for 771
8:22 29015 transfer thread started (pid=29015, tid=163851)
8:22 29016 receiver on device 4 thread started (pid=29016, tid=180236)
8:22 28992 creating directory /vtx/T-8468-514-514
8:22 29017 TS buffer on device 4 thread started (pid=29017, tid=196621)
8:22 28992 setting watchdog timer to 60 seconds
8:22 28992 switching device 1 to channel 272
8:22 28992 buffer stats: 0 (0%) used
8:22 28992 timer 132 (272 0700-2345 'ESA') start
8:22 28992 waiting for EPG info...
8:23 29008 changing pids of channel 76 from 561+561:562=deu,563=2ch:567 to 561+561:562=deu:567
8:26 28992 no EPG info available
8:26 28992 record /video0/ESA/2005-01-14.07:00.50.07.rec
8:28 28992 SpinUpDisk took 1.94 seconds
8:28 28992 recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/003.vdr'
8:28 29029 file writer thread started (pid=29029, tid=229390)
8:28 29030 recording thread started (pid=29030, tid=245775)
8:28 28992 switching to channel 74
8:28 29031 receiver on device 1 thread started (pid=29031, tid=262160)
8:28 29032 TS buffer on device 1 thread started (pid=29032, tid=278545)
8:28 29015 transfer thread ended (pid=29015, tid=163851)
8:28 29017 TS buffer on device 4 thread ended (pid=29017, tid=196621)
8:28 29016 buffer stats: 68244 (3%) used
8:28 29016 receiver on device 4 thread ended (pid=29016, tid=180236)
8:28 28992 buffer stats: 341032 (16%) used
8:29 msi kernel: setting parameters for 771
8:29 29034 transfer thread started (pid=29034, tid=294923)
8:29 28992 creating directory /vtx/T-8468-2561-128
8:29 29035 receiver on device 4 thread started (pid=29035, tid=311308)
8:29 29036 TS buffer on device 4 thread started (pid=29036, tid=327693)
8:29 msi kernel: setting parameters for 771
8:29 28992 max. latency time 7 seconds
8:29 28992 switching to channel 73
8:29 msi kernel: setting parameters for 771
8:29 29034 transfer thread ended (pid=29034, tid=294923)
8:29 msi kernel: setting parameters for 771
8:29 28992 buffer stats: 0 (0%) used
8:29 29036 TS buffer on device 4 thread ended (pid=29036, tid=327693)
8:29 29035 buffer stats: 0 (0%) used
8:29 29035 receiver on device 4 thread ended (pid=29035, tid=311308)
8:29 28992 buffer stats: 0 (0%) used
8:29 29037 transfer thread started (pid=29037, tid=344075)
8:29 28992 creating directory /vtx/T-8468-2561-2
8:29 29038 receiver on device 4 thread started (pid=29038, tid=360460)
8:29 29039 TS buffer on device 4 thread started (pid=29039, tid=376845)
.....

...
8:32 28992 switching to channel 74
8:32 29037 transfer thread ended (pid=29037, tid=344075)
8:32 28992 buffer stats: 117688 (5%) used
8:32 29039 TS buffer on device 4 thread ended (pid=29039, tid=376845)
8:32 29038 buffer stats: 123516 (5%) used
8:32 29038 receiver on device 4 thread ended (pid=29038, tid=360460)
8:32 28992 buffer stats: 0 (0%) used
8:32 29045 transfer thread started (pid=29045, tid=409611)
8:32 29046 receiver on device 4 thread started (pid=29046, tid=425996)
8:32 29047 TS buffer on device 4 thread started (pid=29047, tid=442381)

8:47 28992 executing command '/usr/bin/tail -6 /var/log/messages'
8:53 msi postfix/local[28829 rewrite stream disconnect
8:53 msi postfix/local[28829 idle timeout -- exiting
8:59 29029 ERROR: video data stream broken
8:59 29029 initiating emergency exit
8:59 28992 emergency exit requested - shutting down
8:59 29030 recording thread ended (pid=29030, tid=245775)
8:59 29032 TS buffer on device 1 thread ended (pid=29032, tid=278545)
8:59 29031 buffer stats: 188 (0%) used
8:59 29031 receiver on device 1 thread ended (pid=29031, tid=262160)
8:59 29029 file writer thread ended (pid=29029, tid=229390)
8:59 28992 buffer stats: 752 (0%) used
8:59 28992 timer 132 (272 0700-2345 'ESA') stop
9:00 29045 transfer thread ended (pid=29045, tid=409611)
9:00 28992 buffer stats: 82156 (3%) used
9:00 28992 saved setup to /video0/setup.conf
9:00 28996 tuner on device 1 thread ended (pid=28996, tid=16386)

9:00 28997 Section handler thread ended (pid=28997, tid=32771)
9:00 29001 tuner on device 2 thread ended (pid=29001, tid=49156)
9:00 29002 Section handler thread ended (pid=29002, tid=65541)
9:00 29004 tuner on device 3 thread ended (pid=29004, tid=81926)
9:01 29005 Section handler thread ended (pid=29005, tid=98311)
9:01 29007 tuner on device 4 thread ended (pid=29007, tid=114696)
9:01 29046 receiver on device 4 thread ended (pid=29046, tid=425996)
9:01 29008 Section handler thread ended (pid=29008, tid=131081)
9:01 28992 stopping plugin: undelete
9:01 28992 undelete: cPluginUndelete::~cPluginUndelete
9:01 28992 stopping plugin: osdteletext
9:01 28992 buffer stats: 0 (0%) used
9:01 28992 =====================
9:01 28992 EPG bugfix statistics
9:01 28992 =====================
9:01 28992 IF SOMEBODY WHO IS IN CHARGE OF THE EPG DATA FOR ONE OF THE LISTED
9:01 28992 CHANNELS READS THIS: PLEASE TAKE A LOOK AT THE FUNCTION cEvent::FixEpgBugs()
9:01 28992 IN VDR/epg.c TO LEARN WHAT'S WRONG WITH YOUR DATA, AND FIX IT!
9:01 28992 =====================
9:01 28992 Fix^IHits^IChannels
9:01 28992 0^I3^IEinsExtra, arte
9:01 28992 1^I4^IRTL TELE Letzebuerg
9:01 28992 2^I18^IZDF, 3sat, DLF-Köln, DLR-Berlin, Doku/KiKa
9:01 28992 3^I6^IKABEL1, ProSieben, N24
9:01 28992 5^I2^IDoku/KiKa, Phoenix
9:01 28992 6^I2^IVIVA PLUS, Bloomberg TV Germany
9:02 28992 7^I94^IVIVA PLUS, CNN Int., FRANCE 5, n-tv, Sky News, RTL TELE Letzebuerg, RTPI, TV5 Europe, ...
9:02 28992 =====================
9:02 28992 max. latency time 7 seconds
9:02 28992 exiting
9:02 28992 emergency exit!

9:02 msi lircd 0.7.0[1419 removed client
9:02 msi logger[29094 /root/video/VDR/runvdr restarting VDR
9:12 29128 VDR version 1.3.18 started
...

9:12 29128 reading EPG data from /video0/epg.data
9:13 29128 probing /dev/dvb/adapter0/frontend0
9:13 29132 tuner on device 1 thread started (pid=29132, tid=16386)
9:13 29128 probing /dev/dvb/adapter1/frontend0
9:13 29133 Section handler thread started (pid=29133, tid=32771)
9:14 29137 tuner on device 2 thread started (pid=29137, tid=49156)
9:14 29138 Section handler thread started (pid=29138, tid=65541)
9:14 29128 probing /dev/dvb/adapter2/frontend0
9:14 29140 tuner on device 3 thread started (pid=29140, tid=81926)
9:14 29141 Section handler thread started (pid=29141, tid=98311)
9:14 29128 probing /dev/dvb/adapter3/frontend0
9:14 29143 tuner on device 4 thread started (pid=29143, tid=114696)
9:14 29128 found 4 video devices
9:14 29128 initializing plugin: osdteletext (0.4.1): Displays teletext on the OSD
9:14 29128 initializing plugin: undelete (0.0.2): undelete for recordings
9:14 29128 setting primary device to 1
9:14 29128 SVDRP listening on port 2001
9:14 29128 starting plugin: osdteletext
9:14 29128 starting plugin: undelete
9:14 29128 undelete: cPluginUndelete::Start undeleteconfigfile=undelete.conf
9:14 29128 undelete: INFO: config file not found: [/video0/plugins/]undelete.conf

9:15 29145 LIRC remote control thread started (pid=29145, tid=147466)
9:15 29128 remote control LIRC - keys known
9:15 29128 switching to channel 74
9:17 msi kernel: setting parameters for 771
9:18 29151 transfer thread started (pid=29151, tid=163851)
9:18 29128 creating directory /vtx/T-8468-2561-128
9:18 29152 receiver on device 4 thread started (pid=29152, tid=180236)
9:18 29153 TS buffer on device 4 thread started (pid=29153, tid=196621)
9:18 29128 setting watchdog timer to 60 seconds

9:21 29128 switching device 1 to channel 272
9:21 29128 buffer stats: 0 (0%) used
9:21 29128 timer 132 (272 0700-2345 'ESA') start
9:21 29128 waiting for EPG info...
9:25 29128 no EPG info available
9:25 29128 record /video0/ESA/2005-01-14.07:00.50.07.rec
9:26 29128 SpinUpDisk took 1.90 seconds
9:26 29128 recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/004.vdr'
9:26 29168 file writer thread started (pid=29168, tid=229390)
9:26 29169 recording thread started (pid=29169, tid=245775)
9:26 29170 receiver on device 1 thread started (pid=29170, tid=262160)
9:26 29171 TS buffer on device 1 thread started (pid=29171, tid=278545)
9:27 29128 max. latency time 8 seconds
9:28 29128 switching to channel 227
9:28 29151 transfer thread ended (pid=29151, tid=163851)
9:28 29153 TS buffer on device 4 thread ended (pid=29153, tid=196621)
9:28 29152 buffer stats: 63168 (3%) used
9:28 29152 receiver on device 4 thread ended (pid=29152, tid=180236)
9:28 29128 buffer stats: 63544 (3%) used
9:28 29128 info: Channel not available!
9:29 29128 switching to channel 74
9:29 29175 transfer thread started (pid=29175, tid=294923)
9:29 29176 receiver on device 4 thread started (pid=29176, tid=311308)
9:29 29177 TS buffer on device 4 thread started (pid=29177, tid=327693)
9:36 29128 switching to channel 74
9:36 29175 transfer thread ended (pid=29175, tid=294923)
9:36 29128 buffer stats: 84976 (4%) used
9:36 29177 TS buffer on device 4 thread ended (pid=29177, tid=327693)
9:36 29176 buffer stats: 88736 (4%) used
9:36 29176 receiver on device 4 thread ended (pid=29176, tid=311308)
9:36 29128 buffer stats: 0 (0%) used
9:36 29189 transfer thread started (pid=29189, tid=360459)
9:36 29190 receiver on device 4 thread started (pid=29190, tid=376844)
9:36 29191 TS buffer on device 4 thread started (pid=29191, tid=393229)
9:59 29168 ERROR: video data stream broken
9:59 29168 initiating emergency exit
9:59 29128 emergency exit requested - shutting down
9:59 29169 recording thread ended (pid=29169, tid=245775)
9:59 29171 TS buffer on device 1 thread ended (pid=29171, tid=278545)
9:59 29170 buffer stats: 42864 (2%) used
9:59 29170 receiver on device 1 thread ended (pid=29170, tid=262160)
9:59 29168 file writer thread ended (pid=29168, tid=229390)
9:59 29128 buffer stats: 43240 (0%) used
9:59 29128 timer 132 (272 0700-2345 'ESA') stop
9:59 29189 transfer thread ended (pid=29189, tid=360459)
0:00 29128 buffer stats: 89112 (4%) used
0:00 29128 saved setup to /video0/setup.conf
0:00 29132 tuner on device 1 thread ended (pid=29132, tid=16386)
0:00 msi lircd 0.7.0[1419 removed client
Rainer---<=====>                         Vertraulich
             //
           //                              
         <=====>--------------ocholl, Kiel, Germany ------------

Follow-Ups:
- [vdr] Re: stream broken w. 1.3.18
  - From: Rainer Zocholl <UseNet-Posting-Nospam-74308-@zocki.toppoint.de>

Prev by Date: [vdr] Re: vdr-1.3.18, language selection weirdness.
Next by Date: [vdr] Re: vdr 1.3.18 cCondWait::SleepMs
Previous by thread: [vdr] vdr-1.3.18: aLPCM replay broken
Next by thread: [vdr] Re: stream broken w. 1.3.18
Index(es):
- Date
- Thread

Home | Main Index | Thread Index