4
« am: Mai 19, 2010, 08:43:25 Nachmittag »
Nach der Treiber-Installation habe ich vdr wieder gestartet und eine Aufnahme angestossen. Kurz darauf erneut der Absturz (ERROR: video data stream broken um 22:27:48). Hier das ganze log zwischen dem Starten des vdr nach der Treiber-Installation und dem Absturz:
May 18 22:01:10 srv204 vdr: [1736] VDR version 1.7.14 started
May 18 22:01:10 srv204 vdr: [1736] switched to user 'vdr'
May 18 22:01:10 srv204 vdr: [1736] codeset is 'UTF-8' - known
May 18 22:01:10 srv204 vdr: [1736] found 25 locales in /usr/share/locale
May 18 22:01:10 srv204 vdr: [1736] loading plugin: /usr/lib/vdr/plugins/libvdr-femon.so.1.7.14
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/setup.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/sources.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/diseqc.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/channels.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/timers.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/commands.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/reccmds.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/svdrphosts.conf
May 18 22:01:10 srv204 vdr: [1736] loading /var/lib/vdr/keymacros.conf
May 18 22:01:10 srv204 vdr: [1737] video directory scanner thread started (pid=1736, tid=1737)
May 18 22:01:10 srv204 vdr: [1738] video directory scanner thread started (pid=1736, tid=1738)
May 18 22:01:10 srv204 vdr: [1736] reading EPG data from /var/cache/vdr/epg.data
May 18 22:01:10 srv204 vdr: [1738] video directory scanner thread ended (pid=1736, tid=1738)
May 18 22:01:10 srv204 vdr: [1737] video directory scanner thread ended (pid=1736, tid=1737)
May 18 22:01:10 srv204 vdr: [1736] registered source parameters for 'A - ATSC'
May 18 22:01:10 srv204 vdr: [1736] registered source parameters for 'C - DVB-C'
May 18 22:01:10 srv204 vdr: [1736] registered source parameters for 'S - DVB-S'
May 18 22:01:10 srv204 vdr: [1736] registered source parameters for 'T - DVB-T'
May 18 22:01:11 srv204 vdr: [1736] probing /dev/dvb/adapter0/frontend0
May 18 22:01:11 srv204 vdr: [1736] creating cDvbDevice
May 18 22:01:11 srv204 vdr: [1736] new device number 1
May 18 22:01:11 srv204 vdr: [1736] frontend 0/0 provides DVB-C ("Sundtek DVB-C")
May 18 22:01:11 srv204 vdr: [1739] tuner on frontend 0/0 thread started (pid=1736, tid=1739)
May 18 22:01:11 srv204 vdr: [1740] section handler thread started (pid=1736, tid=1740)
May 18 22:01:11 srv204 vdr: [1736] found 1 DVB device
May 18 22:01:11 srv204 vdr: [1736] initializing plugin: femon (1.7.7): DVB Signal Information Monitor (OSD)
May 18 22:01:11 srv204 vdr: [1736] setting primary device to 1
May 18 22:01:11 srv204 vdr: [1736] device 1 has no MPEG decoder
May 18 22:01:11 srv204 vdr: [1736] assuming manual start of VDR
May 18 22:01:11 srv204 vdr: [1736] SVDRP listening on port 2001
May 18 22:01:11 srv204 vdr: [1736] setting current skin to "sttng"
May 18 22:01:11 srv204 vdr: [1736] loading /var/lib/vdr/themes/sttng-default.theme
May 18 22:01:11 srv204 vdr: [1736] starting plugin: femon
May 18 22:01:11 srv204 vdr: [1736] ERROR: /dev/lircd: No such file or directory
May 18 22:01:11 srv204 vdr: [1736] ERROR: remote control LIRC not ready!
May 18 22:01:11 srv204 vdr: [1736] switching to channel 1
May 18 22:01:11 srv204 vdr: [1736] setting watchdog timer to 60 seconds
May 18 22:01:11 srv204 vdr: [1736] OSD size changed to 720x480 @ 1
May 18 22:01:11 srv204 vdr: [1736] ERROR: no OSD provider available - using dummy OSD!
May 18 22:01:17 srv204 vdr: [1736] max. latency time 1 seconds
May 18 22:01:39 srv204 vdr: [1736] connect from 127.0.0.1, port 40396 - accepted
May 18 22:01:51 srv204 vdr: [1736] max. latency time 12 seconds
May 18 22:01:56 srv204 vdr: [1736] closing SVDRP connection
May 18 22:03:52 srv204 vdr: [1736] connect from 127.0.0.1, port 57353 - accepted
May 18 22:03:52 srv204 vdr: [1736] timer 1 (1 2105-2225 'CSI: Miami') added
May 18 22:03:52 srv204 vdr: [1736] switching device 1 to channel 1
May 18 22:03:52 srv204 vdr: [1736] timer 1 (1 2105-2225 'CSI: Miami') start
May 18 22:03:52 srv204 vdr: [1736] Title: 'CSI: Miami' Subtitle: ''
May 18 22:03:52 srv204 vdr: [1736] executing '/usr/lib/vdr/vdr-recordingaction before "/var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec"'
May 18 22:03:52 srv204 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec as shell script
May 18 22:03:52 srv204 vdr: [1736] record /var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec
May 18 22:03:52 srv204 vdr: [1736] creating directory /var/lib/video.00/CSI#3A_Miami
May 18 22:03:52 srv204 vdr: [1736] creating directory /var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec
May 18 22:03:52 srv204 vdr: [1736] recording to '/var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec/00001.ts'
May 18 22:03:53 srv204 vdr: [1780] recording thread started (pid=1736, tid=1780)
May 18 22:03:53 srv204 vdr: [1781] receiver on device 1 thread started (pid=1736, tid=1781)
May 18 22:03:53 srv204 vdr: [1736] closing SVDRP connection
May 18 22:03:53 srv204 vdr: [1782] TS buffer on device 1 thread started (pid=1736, tid=1782)
May 18 22:03:56 srv204 vdr: [1736] timer 1 (1 2105-2225 'CSI: Miami') set to event Tue 18.05.2010 21:15-22:15 'CSI: Miami'
May 18 22:06:04 srv204 kernel: [ 600.516063] [drm] Big FIFO is enabled
May 18 22:06:56 srv204 vdr: [1736] connect from 127.0.0.1, port 58479 - accepted
May 18 22:07:14 srv204 vdr: [1736] closing SVDRP connection
May 18 22:12:14 srv204 vdr: [1736] connect from 127.0.0.1, port 36291 - accepted
May 18 22:12:31 srv204 vdr: [1736] closing SVDRP connection
May 18 22:15:02 srv204 vdr: [1740] channel 1 (RTL Television) event Tue 18.05.2010 22:15-00:00 'Monk' status 4
May 18 22:17:02 srv204 CRON[1787]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 18 22:17:32 srv204 vdr: [1736] connect from 127.0.0.1, port 54951 - accepted
May 18 22:17:49 srv204 vdr: [1736] closing SVDRP connection
May 18 22:22:49 srv204 vdr: [1736] connect from 127.0.0.1, port 53165 - accepted
May 18 22:23:02 srv204 vdr: [1736] max. latency time 13 seconds
May 18 22:23:07 srv204 vdr: [1736] closing SVDRP connection
May 18 22:25:00 srv204 vdr: [1780] recording thread ended (pid=1736, tid=1780)
May 18 22:25:00 srv204 vdr: [1736] buffer stats: 143820 (2%) used
May 18 22:25:00 srv204 vdr: [1736] timer 1 (1 2105-2225 'CSI: Miami') stop
May 18 22:25:00 srv204 vdr: [1736] executing '/usr/lib/vdr/vdr-recordingaction after "/var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec"'
May 18 22:25:00 srv204 vdr: [1782] TS buffer on device 1 thread ended (pid=1736, tid=1782)
May 18 22:25:00 srv204 vdr: [1781] buffer stats: 140248 (6%) used
May 18 22:25:00 srv204 vdr: [1781] receiver on device 1 thread ended (pid=1736, tid=1781)
May 18 22:25:00 srv204 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /var/lib/video.00/CSI#3A_Miami/2010-05-18.21.05.1-0.rec as shell script
May 18 22:26:02 srv204 vdr: [1736] deleting timer 1 (1 2105-2225 'CSI: Miami')
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sda, SMART Prefailure Attribute: 1 Raw_Read_Error_Rate changed from 253 to 100
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sda, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 67 to 65
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sda, SMART Usage Attribute: 194 Temperature_Celsius changed from 67 to 65
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdb, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 87 to 85
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdb, SMART Usage Attribute: 194 Temperature_Celsius changed from 83 to 80
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdc, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 88 to 86
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdc, SMART Usage Attribute: 194 Temperature_Celsius changed from 84 to 81
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdd, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 85 to 84
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdd, SMART Usage Attribute: 194 Temperature_Celsius changed from 81 to 79
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sde, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 86 to 84
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sde, SMART Usage Attribute: 194 Temperature_Celsius changed from 82 to 79
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdf, SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 87 to 85
May 18 22:26:19 srv204 smartd[1284]: Device: /dev/sdf, SMART Usage Attribute: 194 Temperature_Celsius changed from 83 to 79
May 18 22:26:57 srv204 vdr: [1740] changing pids of channel 284 from 110+110=2:120=deu,121=2ch;125=deu:0:130 to 110+110=2:120=deu,121=2ch;125=deu:131=deu:130
May 18 22:27:12 srv204 vdr: [1736] connect from 127.0.0.1, port 37643 - accepted
May 18 22:27:12 srv204 vdr: [1736] timer 1 (202 2150-2240 'Plusminus') added
May 18 22:27:12 srv204 vdr: [1736] switching device 1 to channel 202
May 18 22:27:12 srv204 vdr: [1736] timer 1 (202 2150-2240 'Plusminus') start
May 18 22:27:12 srv204 vdr: [1736] Title: 'Plusminus' Subtitle: 'Das ARD-Wirtschaftsmagazin mit Clemens Bratzler'
May 18 22:27:12 srv204 vdr: [1736] executing '/usr/lib/vdr/vdr-recordingaction before "/var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec"'
May 18 22:27:17 srv204 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec as shell script
May 18 22:27:17 srv204 vdr: [1736] record /var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec
May 18 22:27:17 srv204 vdr: [1736] creating directory /var/lib/video.00/Plusminus
May 18 22:27:17 srv204 vdr: [1736] creating directory /var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec
May 18 22:27:17 srv204 vdr: [1736] recording to '/var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec/00001.ts'
May 18 22:27:17 srv204 vdr: [1821] receiver on device 1 thread started (pid=1736, tid=1821)
May 18 22:27:17 srv204 vdr: [1820] recording thread started (pid=1736, tid=1820)
May 18 22:27:17 srv204 vdr: [1736] closing SVDRP connection
May 18 22:27:17 srv204 vdr: [1822] TS buffer on device 1 thread started (pid=1736, tid=1822)
May 18 22:27:17 srv204 vdr: [1736] timer 1 (202 2150-2240 'Plusminus') set to event Tue 18.05.2010 22:00-22:30 (VPS: 18.05 21:45) 'Plusminus'
May 18 22:27:48 srv204 vdr: [1820] ERROR: video data stream broken
May 18 22:27:48 srv204 vdr: [1820] initiating emergency exit
May 18 22:27:48 srv204 vdr: [1736] emergency exit requested - shutting down
May 18 22:27:48 srv204 vdr: [1736] stopping plugin: femon
May 18 22:27:48 srv204 vdr: [1820] recording thread ended (pid=1736, tid=1820)
May 18 22:27:48 srv204 vdr: [1736] buffer stats: 0 (0%) used
May 18 22:27:48 srv204 vdr: [1736] timer 1 (202 2150-2240 'Plusminus') stop
May 18 22:27:48 srv204 vdr: [1736] executing '/usr/lib/vdr/vdr-recordingaction after "/var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec"'
May 18 22:27:48 srv204 vdr: [1822] TS buffer on device 1 thread ended (pid=1736, tid=1822)
May 18 22:27:48 srv204 vdr: [1821] buffer stats: 457404 (21%) used
May 18 22:27:48 srv204 vdr: [1821] receiver on device 1 thread ended (pid=1736, tid=1821)
May 18 22:27:53 srv204 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /var/lib/video.00/Plusminus/2010-05-18.21.50.202-0.rec as shell script
May 18 22:27:53 srv204 vdr: [1736] saved setup to /var/lib/vdr/setup.conf
May 18 22:27:54 srv204 vdr: [1740] section handler thread ended (pid=1736, tid=1740)
May 18 22:27:57 srv204 vdr: [1736] ERROR: tuner on frontend 0/0 thread 1739 won't end (waited 3 seconds) - canceling it...
May 18 22:27:57 srv204 vdr: [1736] deleting plugin: femon
May 18 22:27:57 srv204 vdr: [1736] =====================
May 18 22:27:57 srv204 vdr: [1736] EPG bugfix statistics
May 18 22:27:57 srv204 vdr: [1736] =====================
May 18 22:27:57 srv204 vdr: [1736] IF SOMEBODY WHO IS IN CHARGE OF THE EPG DATA FOR ONE OF THE LISTED
May 18 22:27:57 srv204 vdr: [1736] CHANNELS READS THIS: PLEASE TAKE A LOOK AT THE FUNCTION cEvent::FixEpgBugs()
May 18 22:27:57 srv204 vdr: [1736] IN VDR/epg.c TO LEARN WHAT'S WRONG WITH YOUR DATA, AND FIX IT!
May 18 22:27:57 srv204 vdr: [1736] =====================
May 18 22:27:57 srv204 vdr: [1736] Fix#011Hits#011Channels
May 18 22:27:57 srv204 vdr: [1736] 3#011724#011SAT 1, kabel eins, ProSieben, N24, 9 Live, DASDING, SWR 4 RP, SWR 4 BW
May 18 22:27:57 srv204 vdr: [1736] 4#01114#011WDR 5, hr2, WDR 4, SWR 2, NDR Kultur, radioeins, 3sat
May 18 22:27:57 srv204 vdr: [1736] 6#0111401#011SWR Fernsehen BW, KIRAKA, Das Erste, Bayerisches FS Süd, hr-fernsehen, Bayerisches FS Nord, ...
May 18 22:27:57 srv204 vdr: [1736] 7#011616#011NDR Info, N-JOY, NDR 90,3, NDR1WelleNord, NDR 1 Nieders., NDR Info Spez., Nordwestradio, ...
May 18 22:27:57 srv204 vdr: [1736] 8#011697#011SAT 1, kabel eins, ProSieben, N24, 9 Live
May 18 22:27:57 srv204 vdr: [1736] 9#011697#011SAT 1, kabel eins, ProSieben, N24, 9 Live
May 18 22:27:57 srv204 vdr: [1736] =====================
May 18 22:27:57 srv204 vdr: [1736] max. latency time 13 seconds
May 18 22:27:57 srv204 vdr: [1736] emergency exit!
May 18 22:27:57 srv204 vdr: [1736] exiting, exit code 1
May 18 22:27:57 srv204 runvdr: restarting VDR
May 18 22:27:57 srv204 kernel: [ 1913.938094] saa7146: unregister extension 'dvb'.
May 18 22:28:00 srv204 kernel: [ 1916.030042] saa7146: register extension 'dvb'.
May 18 22:28:15 srv204 vdr: [1853] cTimeMs: using monotonic clock (resolution is 1 ns)
May 18 22:28:15 srv204 vdr: [1853] VDR version 1.7.14 started
Positiv:
Das "ständige" wechseln und umbenennen der Kanäle ist mit dem neuen Treiber deutlich besser geworden. Dennoch schreibt er ca. alle viertel/halbe Stunde Folgendes in das syslog:
May 19 03:03:13 srv204 vdr: [1857] changing name of channel 159 from 'Sky Sport 1,Sport1;Sky' to 'Champ. League,;'
May 19 03:03:13 srv204 vdr: [1857] changing name of channel 158 from 'Sky Sport 2,Sport2;Sky' to 'Basketball NBA,;'
May 19 03:03:13 srv204 vdr: [1857] changing name of channel 156 from 'Sky Bundesliga,Buli;Sky' to '2. Liga,;'
May 19 03:03:54 srv204 vdr: [1857] changing name of channel 491 from '434 - 01:00,;' to '434 - 03:15,;'
May 19 03:03:55 srv204 vdr: [1857] changing name of channel 159 from 'Champ. League,;' to 'Sky Sport 1,Sport1;Sky'
May 19 03:03:55 srv204 vdr: [1857] changing name of channel 158 from 'Basketball NBA,;' to 'Sky Sport 2,Sport2;Sky'
May 19 03:03:55 srv204 vdr: [1857] changing name of channel 156 from '2. Liga,;' to 'Sky Bundesliga,Buli;Sky'
May 19 03:04:24 srv204 vdr: [1853] connect from 127.0.0.1, port 38075 - accepted
May 19 03:04:41 srv204 vdr: [1853] closing SVDRP connection
May 19 03:09:42 srv204 vdr: [1853] connect from 127.0.0.1, port 57341 - accepted
May 19 03:09:59 srv204 vdr: [1853] closing SVDRP connection
May 19 03:15:00 srv204 vdr: [1853] connect from 127.0.0.1, port 60869 - accepted
May 19 03:15:17 srv204 vdr: [1853] closing SVDRP connection
May 19 03:17:01 srv204 CRON[2869]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 19 03:17:44 srv204 vdr: [1857] changing name of channel 159 from 'Sky Sport 1,Sport1;Sky' to 'Champ. League,;'
May 19 03:17:44 srv204 vdr: [1857] changing name of channel 158 from 'Sky Sport 2,Sport2;Sky' to 'Basketball NBA,;'
May 19 03:17:44 srv204 vdr: [1857] changing name of channel 156 from 'Sky Bundesliga,Buli;Sky' to '2. Liga,;'
May 19 03:18:25 srv204 vdr: [1857] changing name of channel 159 from 'Champ. League,;' to 'Sky Sport 1,Sport1;Sky'
May 19 03:18:25 srv204 vdr: [1857] changing name of channel 158 from 'Basketball NBA,;' to 'Sky Sport 2,Sport2;Sky'
May 19 03:18:25 srv204 vdr: [1857] changing name of channel 156 from '2. Liga,;' to 'Sky Bundesliga,Buli;Sky'
May 19 03:20:18 srv204 vdr: [1853] connect from 127.0.0.1, port 55646 - accepted
May 19 03:20:35 srv204 vdr: [1853] closing SVDRP connection