moin,
mein Haupt-VDR, den ich als Aufnahmeserver nutze schaltet sich seit einigen
Tagen nicht mehr von selbst ab, wenn er von Hand angeschaltet wurde.
Hat bis vor ca. 5-8 Tagen immer super zuverlässig getan, doch einem größeren
dist-upgradevor ca. 1 Woche macht er das nicht mehr.
Habe in der syslog und den upstart logs jetzt lange gesucht und vermisse dieses
Statement seit einiger Zeit:
Jan 7 10:28:46 yvwz vdr: video: 10:35:15.060 +15 369 0/\ms 7+7 v-buf
Jan 7 10:29:16 yvwz vdr: [1351] next timer event at Thu Jan 8 22:13:00 2015
Jan 7 10:29:16 yvwz vdr: [1351] executing '/usr/lib/vdr/vdr-shutdown.wrapper 1420751580 128624 3 "Dr. House~2015.01.08-22:15-Do" 0'
Jan 7 10:29:16 yvwz vdr: [1351] saved setup to /var/lib/vdr/setup.conf
Jan 7 10:29:16 yvwz vdr-shutdown: executing /usr/share/vdr/shutdown-hooks/S90.acpiwakeup as shell script
Das sollte der normale Eintrag im Log sein, wenn der vdr nach Inaktivität von selbst abschaltet.
Aktuell hab ich die Situation, dass ich ihn Abends anmache und laufen lasse, damit in anderen Zimmern auf die
Aufnahmen zugegriffen werden kann, da er sich ja bisher nach 300 min. zuverlässig beendete.
Allerdings läuft er jetzt morgens immer noch.
syslog-Passagen:
Jan 11 22:41:01 yvwz vdr: video: 20:32:57.329 +34 370 0/\ms 7+7 v-buf
Jan 11 22:42:00 yvwz vdr: [4626] recording thread ended (pid=1166, tid=4626)
Jan 11 22:42:00 yvwz vdr: [1166] buffer stats: 388784 (1%) used
Jan 11 22:42:00 yvwz vdr: [1166] timer 7 (5 2011-2242 'In Time - Deine Zeit läuft ab') stop
Jan 11 22:42:00 yvwz vdr: [1166] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/In_Time_-_Deine_Zeit_läuft_ab/2015-01-11.20.11.5-0.rec"'
Jan 11 22:42:00 yvwz recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/In_Time_-_Deine_Zeit_läuft_ab/2015-01-11.20.11.5-0.rec
Jan 11 22:42:00 yvwz vdr: [1294] VNSI: Timers state changed (11)
Jan 11 22:42:00 yvwz vdr: [1294] VNSI: Requesting clients to reload timers
Jan 11 22:42:01 yvwz vdr: [5969] epg data writer thread started (pid=1166, tid=5969, prio=low)
Jan 11 22:42:01 yvwz vdr: video: 20:33:57.329 +34 370 0/\ms 9+7 v-buf
Jan 11 22:42:01 yvwz vdr: [5969] epg data writer thread ended (pid=1166, tid=5969)
Jan 11 22:43:01 yvwz vdr: video: 20:34:57.329 +34 370 0/\ms 8+7 v-buf
Jan 11 22:43:13 yvwz vdr: [1166] deleting timer 7 (5 2011-2242 'In Time - Deine Zeit läuft ab')
Jan 11 22:43:13 yvwz vdr: [1294] VNSI: Timers state changed (12)
Jan 11 22:43:13 yvwz vdr: [1294] VNSI: Requesting clients to reload timers
Jan 11 22:44:01 yvwz vdr: video: 20:35:57.329 +34 338 0/\ms 7+7 v-buf
Jan 11 22:44:33 yvwz vdr: [1270] read incomplete section - len = 79, r = 639
Jan 11 22:45:01 yvwz vdr: video: 20:36:57.329 +34 402 0/\ms 10+7 v-buf
Jan 11 22:45:16 yvwz vdr: [1270] channel 3 (RTL Television) event Son 11.01.2015 22:45-23:30 'Spiegel TV Magazin' status 4
Jan 11 22:46:01 yvwz vdr: video: 20:37:57.329 +34 306 0/\ms 4+7 v-buf
Jan 11 22:47:01 yvwz vdr: video: 20:38:57.329 +34 338 0/\ms 9+7 v-buf
Jan 11 22:48:01 yvwz vdr: video: 20:39:57.329 +34 402 0/\ms 9+7 v-buf
Jan 11 22:49:01 yvwz vdr: video: 20:40:57.329 +34 402 0/\ms 14+7 v-buf
Jan 11 22:50:01 yvwz vdr: video: 20:41:57.329 +34 338 0/\ms 11+7 v-buf
Jan 11 22:51:01 yvwz vdr: video: 20:42:57.329 +34 370 0/\ms 7+7 v-buf
Jan 11 22:52:01 yvwz vdr: video: 20:43:57.329 +34 306 0/\ms 7+7 v-buf
Jan 11 22:52:02 yvwz vdr: [6041] epg data writer thread started (pid=1166, tid=6041, prio=low)
Jan 11 22:52:02 yvwz vdr: [6041] epg data writer thread ended (pid=1166, tid=6041)
Jan 11 22:53:01 yvwz vdr: video: 20:44:57.329 +34 370 0/\ms 7+7 v-buf
Jan 11 22:54:01 yvwz vdr: video: 20:45:57.329 +34 274 0/\ms 5+7 v-buf
Jan 11 22:55:01 yvwz vdr: video: 20:46:57.329 +34 306 0/\ms 5+7 v-buf
Jan 11 22:56:01 yvwz vdr: video: 20:47:57.329 +34 370 0/\ms 6+7 v-buf
Jan 11 22:57:01 yvwz vdr: video: 20:48:57.329 +34 306 0/\ms 5+7 v-buf
Jan 11 22:58:01 yvwz vdr: video: 20:49:57.329 +34 370 0/\ms 19+7 v-buf
Jan 11 22:59:01 yvwz vdr: video: 20:50:57.329 +34 274 0/\ms 9+7 v-buf
Jan 11 23:00:01 yvwz vdr: video: 20:51:57.329 +34 306 0/\ms 19+7 v-buf
Jan 11 23:00:02 yvwz vdr: [1270] read incomplete section - len = 742, r = 3876
Alles anzeigen
--- usw bis das NAS sich herunterfährt ----
aus avahi-linker.log
2015-01-12 02:31:05,827 INFO service removed: 2 0 Audio on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,828 DEBUG removing Audio on ds413 on ds413.local
2015-01-12 02:31:05,828 DEBUG unlinking /media/Musik/ds413_nfs
2015-01-12 02:31:05,828 INFO service removed: 2 0 dvblink on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,828 DEBUG removing None
2015-01-12 02:31:05,828 INFO service removed: 2 0 Photo on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,829 DEBUG removing Photo on ds413 on ds413.local
2015-01-12 02:31:05,829 DEBUG unlinking /media/Bilder/ds413_nfs
2015-01-12 02:31:05,829 INFO service removed: 2 0 syn1 on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,829 DEBUG removing None
2015-01-12 02:31:05,829 INFO service removed: 2 0 syn2 on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,829 DEBUG removing None
2015-01-12 02:31:05,830 INFO service removed: 2 0 VDR-Recordings on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,830 DEBUG removing VDR-Recordings on ds413 on ds413.local
2015-01-12 02:31:05,830 DEBUG unlinking /media/vdr/ds413(for yvwz)
2015-01-12 02:31:05,830 INFO service removed: 2 0 Movies on ds413 _nfs._tcp local 4
2015-01-12 02:31:05,830 DEBUG removing Movies on ds413 on ds413.local
2015-01-12 02:31:05,830 DEBUG unlinking /media/Video/ds413_nfs
2015-01-12 02:31:06,084 INFO Update recdir via dbus: 0 update of recordings triggered
Alles anzeigen
Einträge im syslog um die Zeit:
Jan 12 02:31:00 yvwz vdr: video: 24:22:56.689 +17 353 0/\ms 7+7 v-buf
Jan 12 02:31:06 yvwz vdr: [7847] video directory scanner thread started (pid=1166, tid=7847, prio=high)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Recordings state changed (43428)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Requesting clients to reload recordings list
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Recordings state changed (46620)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Requesting clients to reload recordings list
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Recordings state changed (49869)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Requesting clients to reload recordings list
Jan 12 02:31:06 yvwz vdr: [7847] video directory scanner thread ended (pid=1166, tid=7847)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Recordings state changed (50982)
Jan 12 02:31:06 yvwz vdr: [1294] VNSI: Requesting clients to reload recordings list
Jan 12 02:32:00 yvwz vdr: video: 24:23:56.689 +17 385 0/\ms 7+7 v-buf
Jan 12 02:32:24 yvwz vdr: [7862] epg data writer thread started (pid=1166, tid=7862, prio=low)
Jan 12 02:32:25 yvwz vdr: [7862] epg data writer thread ended (pid=1166, tid=7862)
Jan 12 02:33:00 yvwz vdr: video: 24:24:56.689 +17 353 0/\ms 7+7 v-buf
Jan 12 02:34:00 yvwz vdr: video: 24:25:56.689 +17 321 0/\ms 23+7 v-buf
Jan 12 02:35:00 yvwz vdr: video: 24:26:56.689 +17 385 0/\ms 24+7 v-buf
Jan 12 02:36:00 yvwz vdr: video: 24:27:56.689 +17 353 0/\ms 23+7 v-buf
Jan 12 02:37:00 yvwz vdr: [1301] EPGSearch: timer conflict check started
Alles anzeigen
hat also das Abhängen NAS soweit funktioniert, der vdr läuft jetzt einsam alleine.
Es gibt jedoch im syslog nur Meldungen wie diese
Jan 12 03:37:00 yvwz vdr: video: 25:28:56.649 -22 129 0/\ms 0+7 v-buf
Jan 12 03:37:00 yvwz vdr: [1300] EPGSearch: search timer update started
bis ich morgens dann die Powertaste drückte (vorher letzte Meldung im syslog):
Jan 12 07:16:03 yvwz vdr: [1270] channel 18 (zdf_neo) event Mon 12.01.2015 07:10-07:55 (VPS: 12.01. 07:10) 'Verrückte Natur (3)' status 4
Jan 12 07:16:28 yvwz vdr: [1270] read incomplete section - len = 269, r = 659
Alle andere logs geben auch nix her.
Habe extra von avahi.mounter auf avahi-linker umgestellt, aber das scheint alles zu gehen
und eine "hängender" Mount nicht die Ursache zu sein.
Habe auch keine Überwachung laufen a la NFS/SSH o.ä., sonst würde man ja auch die Meldung im
syslog oder vdr.log sehen.
Kennt jemand den Effekt oder hat noch Tipp, wo ich suchen kann?
Seufz
Frank