Mailing List archive

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[vdr] vdr restarting every 27 minutes: watchdog timer expired



Hi list,

i have set up my vdr box using a standard debian sarge installation,
a homebrew kernel (2.6.9 + recent CVS DVB drivers), and
vdrdevel-package from the new heise-distro (vdr 1.3.15).

I've got two DVB cards in my system, Haupauge Nexus & Nova S.
As long as I use only one adapter for VDR ("-D 0"), everything
works fine. But using both adapters, VDR restarts constantly every
27min 40sec, log message: "PANIC: watchdog timer expired - exiting!"
I found out that this is always exactly 7 minutes after the
"changing transponder data of channel..." messages.

When a record is running (tested for 3 hours tonight) there are no
"changing transponder data" messages and no watchdog timeout is happening,
so I suspect the error there.

Any idea's what's going wrong?
I'll attach a "-v 3" log of one restart...

cu
Hubert
---
Dec  8 06:50:55 erwin vdr[13746]: channel 15 (Sky News) event 06:30 'CBS' status 4
Dec  8 06:51:17 erwin vdr[13746]: changing pids of channel 1366 from 165+165:100=esl,101=eng:0 to 165+165:100=esl,101=eng:47
Dec  8 06:51:58 erwin vdr[13746]: channel 12 (CNN Int.) event 06:00 'CNN TODAY' status 4
Dec  8 06:53:46 erwin vdr[13746]: channel 8 (Super RTL) event 06:55 'Bob, der Flaschengeist' status 4
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1669 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1670 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1671 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1672 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1673 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1674 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1685 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1686 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1687 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1688 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1689 from S19.2E:12070:h:27500:3 to S19.2E:12090:v:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1065 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1066 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1067 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1068 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1069 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1070 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1071 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1719 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:20 erwin vdr[13746]: changing transponder data of channel 1720 from S19.2E:12460:h:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1669 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1670 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1671 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1672 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1673 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1674 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1685 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1686 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1687 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1688 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1689 from S19.2E:12090:v:27500:3 to S19.2E:12070:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1065 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1066 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1067 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1068 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1069 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1070 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1071 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1719 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:56:32 erwin vdr[13746]: changing transponder data of channel 1720 from S19.2E:12070:h:27500:3 to S19.2E:12460:h:27500:3
Dec  8 06:57:33 erwin vdr[13746]: channel 4 (SAT.1) event 05:30 'Sat.1-Frühstücksfernsehen' status 4
Dec  8 06:57:33 erwin vdr[13746]: channel 10 (N24) event 06:32 'N24 Wissen' status 4
Dec  8 06:57:33 erwin vdr[13746]: channel 5 (ProSieben) event 06:51 'PROMPT' status 4
Dec  8 06:57:33 erwin vdr[13746]: channel 6 (KABEL1) event 06:21 'Hart aber herzlich' status 4
Dec  8 06:58:14 erwin vdr[13746]: channel 14 (Bloomberg TV Germany) event 06:35 'Bloomberg Bonds oder Chefsache' status 4
Dec  8 06:58:46 erwin vdr[13746]: channel 11 (n-tv) event 07:00 'Der Morgen bei n-tv' status 4
Dec  8 06:58:48 erwin vdr[13746]: channel 3 (RTL Television) event 07:00 'Unter uns' status 4
Dec  8 06:58:49 erwin vdr[13746]: connect from 127.0.0.1, port 56177 - accepted
Dec  8 06:59:00 erwin vdr[13746]: closing SVDRP connection
Dec  8 07:03:28 erwin vdr[13746]: PANIC: watchdog timer expired - exiting!
Dec  8 07:03:29 erwin runvdrdevel: restarting VDR
Dec  8 07:03:34 erwin vdr[13833]: VDR version 1.3.15 started
Dec  8 07:03:34 erwin vdr[13833]: loading plugin: /usr/lib/vdrdevel/plugins/libvdr-mplayer.so.1.3.15
Dec  8 07:03:34 erwin vdr[13833]: loading plugin: /usr/lib/vdrdevel/plugins/libvdr-mp3.so.1.3.15
Dec  8 07:03:34 erwin vdr[13833]: loading plugin: /usr/lib/vdrdevel/plugins/libvdr-text2skin.so.1.3.15
Dec  8 07:03:34 erwin vdr[13833]: loading plugin: /usr/lib/vdrdevel/plugins/libvdr-tvonscreen.so.1.3.15
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/setup.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/sources.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/diseqc.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/channels.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/timers.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/commands.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/reccmds.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/svdrphosts.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/ca.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/remote.conf
Dec  8 07:03:34 erwin vdr[13833]: loading /var/lib/vdrdevel/keymacros.conf
Dec  8 07:03:34 erwin vdr[13833]: reading EPG data from /var/cache/vdrdevel/epg.data
Dec  8 07:03:34 erwin vdr[13833]: probing /dev/dvb/adapter0/frontend0
Dec  8 07:03:35 erwin vdr[13833]: ERROR (dvbdevice.c,106): Operation not permitted
Dec  8 07:03:35 erwin vdr[13833]: probing /dev/dvb/adapter1/frontend0
Dec  8 07:03:35 erwin vdr[13833]: tuner on device 1 thread started (pid=13833, tid=-1220510800)
Dec  8 07:03:35 erwin vdr[13833]: Section handler thread started (pid=13833, tid=-1228899408)
Dec  8 07:03:36 erwin vdr[13833]: ERROR (dvbdevice.c,106): Operation not permitted
Dec  8 07:03:36 erwin vdr[13833]: probing /dev/dvb/adapter2/frontend0
Dec  8 07:03:36 erwin vdr[13833]: found 2 video devices
Dec  8 07:03:36 erwin vdr[13833]: initializing plugin: mplayer (0.9.7): Media replay via MPlayer
Dec  8 07:03:36 erwin vdr[13833]: loading /var/lib/vdrdevel/plugins/mplayersources.conf
Dec  8 07:03:36 erwin vdr[13833]: WARNING: source base /dvd not found/permission denied
Dec  8 07:03:36 erwin vdr[13833]: initializing plugin: mp3 (0.9.7): A versatile audio player
Dec  8 07:03:36 erwin vdr[13833]: loading /var/lib/vdrdevel/plugins/mp3sources.conf
Dec  8 07:03:36 erwin vdr[13833]: WARNING: source base /mnt/cdfs not found/permission denied
Dec  8 07:03:36 erwin vdr[13833]: loading id3 cache from /mnt/video/id3info.cache
Dec  8 07:03:36 erwin vdr[13833]: initializing plugin: text2skin (0.0.8.1): Loader for text-based skins
Dec  8 07:03:36 erwin vdr[13833]: initializing plugin: tvonscreen (0.6.0): Shows the EPG info in form of a typical TV magazine
Dec  8 07:03:36 erwin vdr[13833]: setting primary device to 1
Dec  8 07:03:36 erwin vdr[13833]: SVDRP listening on port 2001
Dec  8 07:03:36 erwin vdr[13833]: starting plugin: mplayer
Dec  8 07:03:36 erwin vdr[13833]: starting plugin: mp3
Dec  8 07:03:36 erwin vdr[13833]: starting plugin: text2skin
Dec  8 07:03:36 erwin vdr[13833]: tuner on device 2 thread started (pid=13833, tid=-1237288016)
Dec  8 07:03:36 erwin vdr[13833]: Section handler thread started (pid=13833, tid=-1245676624)
Dec  8 07:03:36 erwin vdr[13833]: text2skin: loading /var/lib/vdrdevel/plugins/text2skin/Aluminium/Aluminium.trans
Dec  8 07:03:36 erwin vdr[13833]: text2skin: loading /var/lib/vdrdevel/plugins/text2skin/Aluminium/Aluminium.skin
Dec  8 07:03:36 erwin vdr[13833]: text2skin: loading /var/lib/vdrdevel/plugins/text2skin/elchi/elchi.trans
Dec  8 07:03:36 erwin vdr[13833]: text2skin: loading /var/lib/vdrdevel/plugins/text2skin/elchi/elchi.colors
Dec  8 07:03:36 erwin vdr[13833]: text2skin: loading /var/lib/vdrdevel/plugins/text2skin/elchi/elchi.skin
Dec  8 07:03:36 erwin vdr[13833]: starting plugin: tvonscreen
Dec  8 07:03:36 erwin vdr[13833]: loading /var/lib/vdrdevel/themes/Aluminium-default.theme
Dec  8 07:03:36 erwin vdr[13833]: remote control LIRC - keys known
Dec  8 07:03:36 erwin vdr[13833]: switching to channel 11
Dec  8 07:03:36 erwin vdr[13833]: LIRC remote control thread started (pid=13833, tid=-1254069328)
Dec  8 07:03:36 erwin vdr[13833]: setting watchdog timer to 60 seconds
Dec  8 07:03:36 erwin vdr[13833]: Text2Skin: Channel display update thread started (pid=13833, tid=-1262457936)
Dec  8 07:03:36 erwin vdr[13833]: timer 3 (35 2242-2310 'ALPHA-CENTAURI') set to event Mit 08.12.2004 22:30-22:45 (VPS: 08.12 22:30) 'Eurosport Spezial'
Dec  8 07:03:36 erwin vdr[13833]: timer 4 (4 2012-2120 'Genial daneben - Die Comedy Arena') set to event Fre 10.12.2004 20:15-21:15 'Genial daneben - Die Comedy Arena'
Dec  8 07:03:36 erwin vdr[13833]: timer 5 (4 2112-2150 'WAS GUCKST DU?!') set to event Fre 10.12.2004 21:15-21:45 'WAS GUCKST DU?!'
Dec  8 07:03:36 erwin vdr[13833]: timer 6 (5 1854-1933 'Die Simpsons') set to event Mit 08.12.2004 18:57-19:25 'Die Simpsons'
Dec  8 07:03:38 erwin vdr[13833]: channel 9 (VOX) event 06:40 'Dauerwerbesendungen' status 4
Dec  8 07:03:38 erwin vdr[13833]: channel 3 (RTL Television) event 07:00 'Unter uns' status 4
Dec  8 07:03:38 erwin vdr[13833]: channel 11 (n-tv) event 07:00 'Der Morgen bei n-tv' status 4
Dec  8 07:03:38 erwin vdr[13833]: channel 8 (Super RTL) event 06:55 'Bob, der Flaschengeist' status 4
Dec  8 07:03:38 erwin vdr[13833]: channel 7 (RTL2) event 06:50 'Infomercial' status 4
Dec  8 07:03:41 erwin vdr[13833]: Text2Skin: Channel display update thread ended (pid=13833, tid=-1262457936)
Dec  8 07:03:42 erwin vdr[13833]: max. latency time 1 seconds
Dec  8 07:06:41 erwin vdr[13833]: channel 23 (SÜDWEST BW) event 07:00 'Sesamstraße' status 4
Dec  8 07:06:41 erwin vdr[13833]: channel 13 (Phoenix) event 05:45 'der TAG' status 4
Dec  8 07:06:42 erwin vdr[13833]: channel 22 (WDR Köln) event 06:30 'Nächte der Entscheidung' status 4
Dec  8 07:06:42 erwin vdr[13833]: channel 19 (hr-fernsehen) event 06:45 'Sesamstraße' status 4
Dec  8 07:06:42 erwin vdr[13833]: channel 17 (Bayerisches FS) event 07:00 'Englisch für Anfänger' status 4
Dec  8 07:06:42 erwin vdr[13833]: channel 21 (SR Fernsehen Südwest) event 07:00 'Sesamstraße' status 4
Dec  8 07:06:42 erwin vdr[13833]: channel 1 (Das Erste) event 05:30 'ZDF-Morgenmagazin' status 4
Dec  8 07:06:35 erwin vdr[13833]: System Time = Wed Dec  8 07:06:43 2004  (1102486003)
Dec  8 07:06:35 erwin vdr[13833]: Local Time  = Wed Dec  8 07:06:35 2004  (1102485995)
Dec  8 07:06:35 erwin vdr[13833]: channel 26 (arte) event 02:43 'Sendepause' status 4
Dec  8 07:06:40 erwin vdr[13833]: changing pids of channel 1005 from 201+201:202:204 to 901+901:902:204
Dec  8 07:07:23 erwin vdr[13833]: channel 2 (ZDF) event 05:30 'ZDF-Morgenmagazin' status 4
Dec  8 07:07:23 erwin vdr[13833]: channel 24 (3sat) event 07:00 'nano' status 4
Dec  8 07:07:24 erwin vdr[13833]: channel 25 (KiKa) event 07:05 'Janoschs Traumstunde' status 4
Dec  8 07:07:24 erwin vdr[13833]: channel 30 (ZDFinfokanal) event 07:00 'AKTUELL - welt' status 4
Dec  8 07:08:05 erwin vdr[13833]: changing portal name of channel 1658 from '' to 'Direkt Portal'
Dec  8 07:08:05 erwin vdr[13833]: changing name of channel 1682 from 'D 1 - 02:15,;' to 'D 1 - 07:15,;'
Dec  8 07:08:05 erwin vdr[13833]: changing name of channel 1675 from 'D 2 - 04:00,;' to 'D 2 - 07:00,;'
Dec  8 07:08:05 erwin vdr[13833]: changing name of channel 1683 from 'D 4 - 03:45,;' to 'D 4 - 07:00,;'
Dec  8 07:08:05 erwin vdr[13833]: linking channel 1658 from none to 1676 1679 1682 1677 1678 1675 1681 1680 1683 1684
Dec  8 07:08:06 erwin vdr[13833]: changing portal name of channel 1659 from '' to 'Info Tafel'
Dec  8 07:08:06 erwin vdr[13833]: linking channel 1659 from none to 1669 1670 1671
Dec  8 07:08:06 erwin vdr[13833]: changing portal name of channel 1660 from '' to 'Info Tafel'
Dec  8 07:08:06 erwin vdr[13833]: linking channel 1660 from none to 1672 1673
Dec  8 07:08:48 erwin vdr[13833]: channel 27 (EinsExtra) event 06:45 'Report Mainz' status 4
Dec  8 07:08:48 erwin vdr[13833]: channel 29 (EinsMuXx) event 07:00 'Fliege - Die Talkshow' status 4
Dec  8 07:08:48 erwin vdr[13833]: channel 28 (EinsFestival) event 06:42 'Das Orpheus Chamber Orchestra spielt' status 4
Dec  8 07:08:48 erwin vdr[13833]: channel 20 (NDR FS MV) event 07:00 'Die Reportage' status 4




Home | Main Index | Thread Index