Sporadische Backup-Fehler [GELÖST]

[Jolinar]

Auf einem Server, den ich mit betreue, wirft die Backup-Funktion ab und zu Fehler aus.
Backup-Protokoll:

Code: Select all

[11-Feb-2019 10:50:01] DEBUG --> start backup process, task id: "1092"
[11-Feb-2019 10:50:01] DEBUG --> prepare processing dir
[11-Feb-2019 10:50:01] DEBUG --> deleteDirectory(): the directory "/backup/processing/task-1092/" does not exists
[11-Feb-2019 10:50:01] DEBUG --> write pid file
[11-Feb-2019 10:50:01] DEBUG --> estimate disk space needed for this job
[11-Feb-2019 10:50:04] DEBUG --> write "allocated" file
[11-Feb-2019 10:50:04] DEBUG --> free: 4,816.91 MiB
[11-Feb-2019 10:50:04] DEBUG --> reserve: 500.90 MiB (5%)
[11-Feb-2019 10:50:04] DEBUG --> this backup task: 1,908.43 MiB
[11-Feb-2019 10:50:04] DEBUG --> all running backup tasks: 1,908.43 MiB
[11-Feb-2019 10:50:04] DEBUG --> generate archive directories
[11-Feb-2019 10:50:04] DEBUG --> backup type is "server"
[11-Feb-2019 10:50:04] DEBUG --> backup config
[11-Feb-2019 10:50:09] DEBUG --> backup user databases
[11-Feb-2019 10:50:09] DEBUG --> backup non-user databases
[11-Feb-2019 10:50:10] DEBUG --> backup home directories
[11-Feb-2019 10:50:10] DEBUG --> backup mail directories
[11-Feb-2019 10:50:28] DEBUG --> create one backup file
[11-Feb-2019 10:50:28] DEBUG --> file name is /backup/processing/task-1092/server-srv22-20190211105028.7z
[11-Feb-2019 10:50:28] DEBUG --> compressing file
[11-Feb-2019 10:50:28] DEBUG --> ... with compressing level set to: "ultra"
[11-Feb-2019 10:51:05] ERROR --> canceled backup process: failed_to_compress (137)
[11-Feb-2019 10:51:05] INFO  --> deleteDirectory(): perform rm -rf /backup/processing/task-1092/
====

Platz ist lt. Protokoll noch genügend frei.
Der Fehler tritt wie gesagt nur sporadisch auf (nach Durchsicht des Protokolls betrifft es etwa 20% der Backups), der Server ist zumindest bei manuell ausgelösten Backups fast im Idle (Load Average um die 0,3).
Der Server ist KVM-virtualisiert und läuft (noch) mit Debian 8.

Ich hab grad nicht wirklich eine Idee, wo ich mit der Fehlersuche ansetzen soll...Hat vielleicht jemand einen Denkanstoß für mich?

[Alexander]

[11-Feb-2019 10:51:05] ERROR --> canceled backup process: failed_to_compress (137)

Die 137 ist der Return-Code von "7z". Wurde der Prozess ggf. vom OOM-Killer gekillt?

Vorab-Lösung: Ggf. schafft eine niedrigere Kompression erstmal Abhilfe.

[Jolinar]

Die 137 ist der Return-Code von "7z". Wurde der Prozess ggf. vom OOM-Killer gekillt?

Vorab-Lösung: Ggf. schafft eine niedrigere Kompression erstmal Abhilfe.

Du scheinst mit deiner Vermutung richtig zu liegen. Bei stärkster Kompression liegt der Speicherverbrauch bei >=1800/2000MB, bei normaler Kompression bei ~1400/2000MB.
Habe jetzt 4 Backupläufe fehlerfrei bei normaler Kompression, werde aber zur Verifizierung noch ein paar weitere Backups anstoßen.

Was sich mir allerdings nicht wirklich logisch erschließt, ist die Tatsache, daß der Großteil der nächtlichen Backups ohne Fehler durchläuft. Die Zeitfenster für Server- und User-Backup sind so gelegt, daß sie nicht mit anderen Wartungsaufgaben (Updates, etc.) kollidieren sollten. Auf dem Server laufen auch keine weiteren Webseiten, der dient ausschließlich als Mailserver.
Aber das gehört wohl zu den vielen unergründlichen Geheimnissen der IT.