Große Anzahl an Aufnahmen verloren :-(

Frank99 · 14. September 2013

Hallo,

alle meine Aufnahmen haben bzw. hatten eine Prio. von 99, VDR löscht diese somit nie um Platz zu schaffen.
Tja bis dann gestern Abend etwas angefangen hat, dann doch Platz zu schaffen...

Den PC hatte ich vor ca. einer Woche neu aufgesetzt.
yavdr stable 0.5 mit VDR 2.0.2,
letzte Updates eingespielt per apt vermutlich am Freitag, wenn diese Info wichtig ist kann ich diese nachreichen.
Der Rechner lief seit gestern Nachmittag ca. 16:00.
Es wurde auf 13th Street alle Folgen vom Law & Order Staffelmarathon bis heute morgen ~8:00 aufgenommen.
Danach ist der Rechner sauber heruntergefahren.

Heute mittag hatte ich den dann manuell wieder eingeschaltet um zu kontrollieren ob alle Timer durchgelaufen sind....
Tja und dann war auf einmal die Platte auf diesem VDR "aufgeräumt"
Den 2. vermutlich ebenfalls betroffenen VDR (vdrwo) habe ich noch nicht überprüft, dort läuft ebenfalls yavdr 0.5 stable aber noch mit dem alten VDR....

Spoiler anzeigen

Sep 13 23:22:00 frank1 vdr: [1764] XVDR: Recordings state changed (429)
Sep 13 23:22:02 frank1 vdr: [1764] XVDR: Starting garbage collection in recordings cache
Sep 13 23:22:05 frank1 vdr: video: slow down video, duping frame
Sep 13 23:22:05 frank1 vdr: video: 14:04:02.605 +39 620 0/\ms 49+6 v-buf
Sep 13 23:22:54 frank1 vdr: audio/alsa: broken driver 0 state 'RUNNING'
Sep 13 23:22:54 vdr: last message repeated 8 times
Sep 13 23:22:54 frank1 vdr: audio/alsa: broken driver 0 state 'XRUN'
Sep 13 23:22:54 frank1 vdr: audio/alsa: wait underrun error? 'Datenübergabe unterbrochen (broken pipe)'
Sep 13 23:22:55 frank1 vdr: video: slow down video, duping frame
Sep 13 23:22:55 frank1 vdr: video: 14:04:52.605 +47 612 0/\ms 58+7 v-buf
Sep 13 23:23:45 frank1 vdr: video: slow down video, duping frame
Sep 13 23:23:45 frank1 vdr: video: 14:05:42.605 +47 596 0/\ms 55+7 v-buf
Sep 13 23:24:35 frank1 vdr: video: slow down video, duping frame
Sep 13 23:24:35 frank1 vdr: video: 14:06:32.605 +47 548 0/\ms 59+7 v-buf
Sep 13 23:24:54 frank1 vdr: [1743] linking channel 81 from 2309 2317 2310 2313 2312 2311 2314 2315 to 2317 2310 2313 2312 2311 2314 2315
Sep 13 23:25:11 frank1 dhclient: DHCPREQUEST of 192.168.1.122 on eth0 to 192.168.1.1 port 67
Sep 13 23:25:11 frank1 dhclient: DHCPACK of 192.168.1.122 from 192.168.1.1
Sep 13 23:25:12 frank1 dhclient: bound to 192.168.1.122 -- renewal in 1475 seconds.
Sep 13 23:25:25 frank1 vdr: video: slow down video, duping frame
Sep 13 23:25:25 frank1 vdr: video: 14:07:22.605 +47 628 0/\ms 55+7 v-buf
Sep 13 23:26:15 frank1 vdr: video: slow down video, duping frame
Sep 13 23:26:15 frank1 vdr: video: 14:08:12.605 +47 612 0/\ms 60+7 v-buf
Sep 13 23:27:05 frank1 vdr: video: slow down video, duping frame
Sep 13 23:27:05 frank1 vdr: video: 14:09:02.605 +47 596 0/\ms 59+7 v-buf
Sep 13 23:27:55 frank1 vdr: video: slow down video, duping frame
Sep 13 23:27:55 frank1 vdr: video: 14:09:52.605 +47 612 0/\ms 59+7 v-buf
Sep 13 23:28:45 frank1 vdr: video: slow down video, duping frame
Sep 13 23:28:45 frank1 vdr: video: 14:10:42.605 +47 596 0/\ms 42+7 v-buf
Sep 13 23:29:25 frank1 vdr: [1743] channel 58 (13th Street HD) event Fre 13.09.2013 23:30-00:15 'Law & Order' status 2
Sep 13 23:29:35 frank1 vdr: video: slow down video, duping frame
Sep 13 23:29:35 frank1 vdr: video: 14:11:32.605 +47 612 0/\ms 51+7 v-buf
Sep 13 23:29:56 frank1 vdr: [1743] channel 58 (13th Street HD) event Fre 13.09.2013 23:30-00:15 'Law & Order' status 4
Sep 13 23:30:25 frank1 vdr: video: slow down video, duping frame
Sep 13 23:30:25 frank1 vdr: video: 14:12:22.605 +47 628 0/\ms 63+7 v-buf
Sep 13 23:31:15 frank1 vdr: video: slow down video, duping frame
Sep 13 23:31:15 frank1 vdr: video: 14:13:12.605 +47 612 0/\ms 53+7 v-buf
Sep 13 23:32:05 frank1 vdr: video: slow down video, duping frame
Sep 13 23:32:05 frank1 vdr: video: 14:14:02.605 +47 628 0/\ms 47+7 v-buf
Sep 13 23:32:55 frank1 vdr: video: slow down video, duping frame
Sep 13 23:32:55 frank1 vdr: video: 14:14:52.585 +27 612 0/\ms 43+7 v-buf
Sep 13 23:33:45 frank1 vdr: video: slow down video, duping frame
Sep 13 23:33:45 frank1 vdr: video: 14:15:42.605 +47 628 0/\ms 45+7 v-buf
Sep 13 23:34:35 frank1 vdr: video: slow down video, duping frame
Sep 13 23:34:35 frank1 vdr: video: 14:16:32.605 +47 644 0/\ms 44+7 v-buf
Sep 13 23:35:25 frank1 vdr: video: slow down video, duping frame
Sep 13 23:35:25 frank1 vdr: video: 14:17:22.605 +47 628 0/\ms 47+7 v-buf
Sep 13 23:36:15 frank1 vdr: video: slow down video, duping frame
Sep 13 23:36:15 frank1 vdr: video: 14:18:12.605 +47 644 0/\ms 55+7 v-buf
Sep 13 23:37:05 frank1 vdr: video: slow down video, duping frame
Sep 13 23:37:05 frank1 vdr: video: 14:19:02.605 +47 628 0/\ms 49+7 v-buf
Sep 13 23:37:55 frank1 vdr: video: slow down video, duping frame
Sep 13 23:37:55 frank1 vdr: video: 14:19:52.605 +47 612 0/\ms 39+7 v-buf
Sep 13 23:38:45 frank1 vdr: video: slow down video, duping frame
Sep 13 23:38:45 frank1 vdr: video: 14:20:42.605 +47 628 0/\ms 52+7 v-buf
Sep 13 23:39:35 frank1 vdr: video: slow down video, duping frame
Sep 13 23:39:35 frank1 vdr: video: 14:21:32.605 +47 644 0/\ms 53+7 v-buf
Sep 13 23:39:55 frank1 vdr: [1743] changing name of channel 2312 from '360 - 22:15,;' to '360 - 00:00,;'
Sep 13 23:40:00 frank1 vdr: [3079] recording thread ended (pid=1393, tid=3079)
Sep 13 23:40:00 frank1 vdr: [1393] buffer stats: 650856 (3%) used
Sep 13 23:40:00 frank1 vdr: [1393] timer 1 (58 2232-2340 'Law & Order~Der Tod macht Fehler') stop
Sep 13 23:40:00 frank1 vdr: [1393] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/Law_&_Order/Der_Tod_macht_Fehler/2013-09-13.22.32.58-0.rec"'
Sep 13 23:40:00 frank1 vdr: [3237] dbus2vdr: System: emit signal /Status de.tvdr.vdr.status Recording (1, '', '/srv/vdr/video.00/Law_&_Order/Der_Tod_macht_Fehler/2013-09-13.22.32.58-0.rec', false)
Sep 13 23:40:00 frank1 vdr: [3237] dbus2vdr: System: emit signal /Status de.tvdr.vdr.status TimerChange ('', 'tcMod')
Sep 13 23:40:00 frank1 vdr: [3242] dbus2vdr: NetworkHandler: emit signal /Status de.tvdr.vdr.status TimerChange ('', 'tcMod')
Sep 13 23:40:25 frank1 vdr: video: slow down video, duping frame
Sep 13 23:40:25 frank1 vdr: video: 14:22:22.605 +47 628 0/\ms 51+7 v-buf
Sep 13 23:40:35 frank1 rpc.mountd[1658]: authenticated unmount request from 192.168.1.133:668 for /srv/audio (/srv/audio)
Sep 13 23:40:35 frank1 rpc.mountd[1658]: authenticated unmount request from 192.168.1.133:670 for /srv/share/vdr (/srv/share/vdr)
Sep 13 23:40:56 frank1 avahi-mounter[1646]: umounting /media/Musik/vdrwo/ on server logout
Sep 13 23:40:56 frank1 avahi-mounter[1646]: umounting /media/Bilder/vdrwo/ on server logout
Sep 13 23:40:56 frank1 avahi-mounter[1646]: umounting /srv/vdr/video.00/vdrwo/ on server logout
Sep 13 23:40:56 frank1 vdr: [1766] dbus2vdr: handle_method_call: sender ':1.15', object '/Recordings', interface 'de.tvdr.vdr.recording', method 'Update'
Sep 13 23:40:56 frank1 vdr: [3252] dbus2vdr: do_work on /Recordings.Update
Sep 13 23:40:56 frank1 vdr: [3253] video directory scanner thread started (pid=1393, tid=3253, prio=high)
Sep 13 23:40:56 frank1 avahi-mounter[1646]: umounting /media/Video/vdrwo/ on server logout
Sep 13 23:40:56 frank1 vdr: [1764] XVDR: Recordings state changed (451)
Sep 13 23:40:57 frank1 vdr: [1764] XVDR: Recordings state changed (460)
Sep 13 23:40:57 frank1 vdr: [1764] XVDR: Recordings state changed (469)
Sep 13 23:40:57 frank1 vdr: [1764] XVDR: Recordings state changed (476)
Sep 13 23:40:57 frank1 vdr: [3253] video directory scanner thread ended (pid=1393, tid=3253)
Sep 13 23:40:57 frank1 vdr: [1764] XVDR: Recordings state changed (479)
Sep 13 23:40:59 frank1 vdr: [1764] XVDR: Starting garbage collection in recordings cache
Sep 13 23:40:59 frank1 vdr: [1764] XVDR: removing outdated recording (00fe8ac5) '/srv/vdr/video.00/vdrwo/Horseland,_die_Pferderanch/Der_Abschied/2013-09-13.17.03.25-0.rec' from cache
Sep 13 23:40:59 frank1 vdr: [1764] XVDR: removing outdated recording (023aec1d) '/srv/vdr/video.00/OLDSD1/Rosa Luxemburg/2004-06-25.22.19.50.99.rec' from cache
Sep 13 23:40:59 frank1 vdr: [1764] XVDR: removing outdated recording (025063a4) '/srv/vdr/video.00/Hart_of_Dixie/Schnee_in_Bluebell/2013-07-16.01.16.9-0.rec' from cacheSep 13 23:40:59 frank1 vdr: [1764] XVDR: removing outdated recording (02df27fb) '/srv/vdr/video.00/Revenge/Sühne/2013-07-22.21.51.18-0.rec' from cache

ab dieser Stelle kommen noch viele, viele weitere Aufnahmen

Spoiler anzeigen

Sep 14 12:41:32 frank1 vdr: [1858] XVDR: Recordings state changed (86)
Sep 14 12:41:32 frank1 vdr: audio/alsa: start delay 336ms
Sep 14 12:41:33 frank1 vdr: video: decoder buffer empty, duping frame (6156/3) 0 v-buf
Sep 14 12:41:33 frank1 vdr: video: slow down video, duping frame
Sep 14 12:41:33 frank1 vdr: video: 18:11:19.586 +773 372 0/\ms 7+0 v-buf
Sep 14 12:41:34 frank1 vdr: [1858] XVDR: Starting garbage collection in recordings cache
Sep 14 12:41:34 frank1 vdr: video/vdpau: synced after 123 frames
Sep 14 12:41:48 frank1 vdr: [softhddev] empty video packet 11 bytes
Sep 14 12:41:55 frank1 vdr: [2680] Text2Skin: menu display update thread ended (pid=1255, tid=2680)
Sep 14 12:41:56 frank1 vdr: [2683] Text2Skin: channelInfo display update thread started (pid=1255, tid=2683, prio=high)
Sep 14 12:41:56 frank1 vdr: [2683] Text2Skin: channelInfo display update thread ended (pid=1255, tid=2683)
Sep 14 12:41:56 frank1 vdr: [2684] Text2Skin: menu display update thread started (pid=1255, tid=2684, prio=high)
Sep 14 12:42:03 frank1 vdr: [2684] Text2Skin: menu display update thread ended (pid=1255, tid=2684)
Sep 14 12:42:22 frank1 vdr: video: slow down video, duping frame
Sep 14 12:42:22 frank1 vdr: video: 18:12:08.646 +32 299 0/\ms 26+2 v-buf
Sep 14 12:43:05 frank1 vdr: [2693] remove deleted recordings thread started (pid=1255, tid=2693, prio=low)
Sep 14 12:43:05 frank1 vdr: [2693] removing recording /srv/vdr/video.00/Law_&_Order/%Geschmackloser_Vertuschungsversuch/2013-09-10.13.46.58-0.del
Sep 14 12:43:05 frank1 vdr: [2693] removing /srv/vdr/video.00/Law_&_Order/%Geschmackloser_Vertuschungsversuch/2013-09-10.13.46.58-0.del/index
...
Sep 14 12:43:12 frank1 vdr: [2693] removing /srv/vdr/video.00/Hart_of_Dixie/Schnee_in_Bluebell
Sep 14 12:43:12 frank1 vdr: [2693] removing /srv/vdr/video.00/Hart_of_Dixie
Sep 14 12:43:12 frank1 vdr: [2693] ERROR (tools.c,507): /srv/vdr/video.00/TRANSPORT_SEAGATE: Datei oder Verzeichnis nicht gefunden
Sep 14 12:43:12 frank1 vdr: [2693] remove deleted recordings thread ended (pid=1255, tid=2693)

Undelete kann ich auf einem Linux Filesystem wohl vergessen...

Aber was war da los?

Bye,
Frank

IG88 · 14. September 2013

hi,

also der vdr thread der da löscht kümmert sich nur um die *.del verzeichnisse, also welche die vorher schon auf zu löschen gesetzt wurden, das ist also nicht die ursache

" XVDR: removing outdated recording"
das sieht komisch aus, das xvdr plugin kümmert isch hier um das löschen von aufnahmen - ich vermute mal erst werden sie durch xvdr auf *.del gesetzt und vdr kümmert sich später darum die auch wirklich zu löschen
eigentlich ist das xvdr plugin dafür gedacht den zugriff auf vdr funktionne über xbmc zu ermöglichen, entweder hat da jemand über xbmc etwas ausgelöst oder das plugin läuft amok

Moorviper · 14. September 2013

das gabs schon mal beim xvdr plugin das aufnahmen gelöscht wurden

gda · 14. September 2013

Also erstmal steht da nur, dass er die Aufnahmen aus dem recording cache löscht. Von Platte steht da nichts. Ich würde ja erst mal auf der Platte nachsehen.

Gerald

jg72 · 14. September 2013

Und auch unter Linux gibt es Tools, um die gelöschten Aufnahmen retten zu können. Wichtigste Vorraussetzung: Es wird nicht mehr darauf geschrieben!

Was für ein Dateisystem ist denn da drauf? Ext3/4?

bsari · 14. September 2013

Zitat von Frank99

Undelete kann ich auf einem Linux Filesystem wohl vergessen...

Nicht unbedingt. Ich habe einmal eine Menge Daten mit ext3grep wiegerhergestellt.

steffen_b · 15. September 2013

XVDR: removing outdated recording (00fe8ac5) ... from cache

Sieht mir eher danach aus,als wenn es da schon gelöscht war - und der Aufnahmen-Cache angeglichen wurde.

FJe · 15. September 2013

moin,

woher kommt denn die erste Zeile?

"Sep 13 23:22:00 frank1 vdr: [1764] XVDR: Recordings state changed (429)"
und
"Sep 14 12:41:32 frank1 vdr: [1858] XVDR: Recordings state changed (86)"

sagt er da nicht, dass von irgendwoher ein Lösch-Kommando kam?
Was war vorher?

Bye
Frank

TomJoad · 16. September 2013

Ich will hier nicht vorlaut sein, aber eine Prio 99 schützt gar nichts, nur eine Lifetime von 99 (siehe man 5 vdr).
Vielleicht schaust du dir das nochmal an, bevor hier weiter spekuliert wird. Die Prio sortiert nur die Löschreihenfolge von Aufnahmen, deren Lifetime abgelaufen ist.

FJe · 16. September 2013

moin,

hab mich eben auch erschreckt.
Mein vdr im wz meldete

Code

Sep 16 20:10:27 yvwz vdr: [1512] XVDR: removing outdated recording (7c753de7) '/srv/vdr/video.00/yvsz/%Alf%/Alf/In_letzter_Minute/2013-04-02.19.18.31-0.rec' from cache
Sep 16 20:10:27 yvwz vdr: [1512] XVDR: removing outdated recording (7de3dfad) '/srv/vdr/video.00/yvsz/%Castle%/Castle/2013.05.31-20:15-Fr/2013-05-31.20.13.25-0.rec' from cache
usw.

Dachte auch erst, es wäre ein Löschen von Aufnahmen.
Allerdings war es wohl nur, dass der XVDR die per avahi-mount gelesenen Aufnahmen aus seinem Cache entfernte, weil der Server gerade nicht lief.
Ein Löschen der Aufnahmen hat er nicht gemacht, das hab ich gecheckt.

Sah es zufällig im webif beim log-check. hab sofort vdr gestoppt und jetzt in der order.conf erst mal -xvdr eingetragen, bis ich weiss, ob das ungefährlich ist.
Ist das normal? Und wird er nix löschen, wenn er aus dem cache geworfen hat und dann der avahi server wiederkommt und die Aufnahmen wieder da sind?
Sollte man derzeit auf vnsi wechseln?

Wäre für eine beruhigende Antwort dankbar.

Bye
Frank

Große Anzahl an Aufnahmen verloren :-(

Jetzt mitmachen!

Teilen