Amavis stirbt alle 2 Wochen

[bytegetter]

Hallo zusammen,

Früher nur alle paar Monate ist Amavis nun bereits nach 1.5 Wochen gestorben. Das fällt mir immer daran auf, dass ich keine eMails mehr erhalte.

Code: Select all

Sep 25 06:01:26 admin3 amavis[11231]: (11231-09) Passed CLEAN {RelayedInbound}, [79.140.117.252]:8609 [79.140.117.252] <nas@xxxxx.de> -> <admin@xxxxx.de>, Queue-ID: 843AAFF117, mail_id: 7ytTkma3b7ij, Hits: -2.293, size: 1214, queued_as: 838A2FF296, 64700 ms
Sep 25 06:01:26 admin3 amavis[11852]: (11852-01) Passed CLEAN {RelayedInbound}, [79.140.117.252]:7755 [79.140.117.252] <nas@xxxxx.de> -> <admin@xxxxx.de>, Queue-ID: B4672FF138, mail_id: MWDLEIP8Vs3C, Hits: -2.293, size: 1214, queued_as: 84F39FF2C8, 64640 ms
Sep 25 06:01:26 admin3 amavis[11231]: (11231-09) (!!)TROUBLE in check_mail, but must continue (1): update_snmp FAILED: update_snmp_variables: BDB S db_cursor: BDB0061 PANIC: Invalid argument, . at (eval 94) line 107.
Sep 25 06:01:26 admin3 amavis[11852]: (11852-01) (!!)TROUBLE in check_mail, but must continue (1): update_snmp FAILED: update_snmp_variables: BDB S c_put: BDB0060 PANIC: fatal region error detected; run recovery, . at (eval 94) line 147.
Sep 25 06:01:26 admin3 amavis[11852]: (11852-01) (!!)TROUBLE in process_request: register_proc: BDB N db_cursor: BDB0060 PANIC: fatal region error detected; run recovery, . at (eval 94) line 220.
Sep 25 06:01:26 admin3 amavis[11852]: (11852-01) (!)Requesting process rundown after fatal error
Sep 25 06:01:26 admin3 amavis[11231]: (11231-09) (!!)TROUBLE in process_request: register_proc: BDB N db_cursor: BDB0060 PANIC: fatal region error detected; run recovery, . at (eval 94) line 220.
Sep 25 06:01:26 admin3 amavis[11231]: (11231-09) (!)Requesting process rundown after fatal error
Sep 25 06:01:26 admin3 postfix/smtp[12056]: 843AAFF117: to=<admin@xxxxx.de>, orig_to=<nas@xxxxx.de>, relay=127.0.0.1[127.0.0.1]:10024, delay=65, delays=0.25/0.15/0/65, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to end of DATA command))
Sep 25 06:01:26 admin3 postfix/smtp[12057]: B4672FF138: to=<admin@xxxxx.de>, orig_to=<nas@xxxxx.de>, relay=127.0.0.1[127.0.0.1]:10024, delay=65, delays=0.32/0.02/0.01/65, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shuttin
g down, closing channel (in reply to end of DATA command))
Sep 25 06:01:26 admin3 amavis[11231]: (11231-09) (!)TempDir removal: tempdir is to be PRESERVED: /var/lib/amavis/tmp/amavis-20200925T054731-11231-DhKEF2dT
Sep 25 06:01:26 admin3 amavis[11852]: (11852-01) (!)TempDir removal: tempdir is to be PRESERVED: /var/lib/amavis/tmp/amavis-20200925T060021-11852-UJQOMk8M
Sep 25 06:01:27 admin3 amavis[11852]: (11852-01) (!)_DIE: register_proc: BDB N db_cursor: BDB0060 PANIC: fatal region error detected; run recovery, . at (eval 94) line 220.
Sep 25 06:01:27 admin3 amavis[11852]: (11852-01) (!)Amavis::END: DB unregistering failed: 
Sep 25 06:01:27 admin3 amavis[11231]: (11231-09) (!)_DIE: register_proc: BDB N db_cursor: BDB0060 PANIC: fatal region error detected; run recovery, . at (eval 94) line 220.
Sep 25 06:01:27 admin3 amavis[11231]: (11231-09) (!)Amavis::END: DB unregistering failed: 
Sep 25 06:01:27 admin3 postfix/smtpd[12102]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Sep 25 06:01:27 admin3 amavis[12110]: (!!)TROUBLE in child_init_hook: BDB can't connect db env. at /var/lib/amavis/db: BDB0087 DB_RUNRECOVERY: Fatal error, run database recovery, No such file or directory. at (eval 94) line 338.
Sep 25 06:01:27 admin3 amavis[12110]: (!)_DIE: Suicide in child_init_hook: BDB can't connect db env. at /var/lib/amavis/db: BDB0087 DB_RUNRECOVERY: Fatal error, run database recovery, No such file or directory. at (eval 94) line 338.

Platz sollte auf der VM mehr als genug sein:

Code: Select all

root@admin3:/var/log# df -h
Dateisystem                 Größe Benutzt Verf. Verw% Eingehängt auf
udev                         2,0G       0  2,0G    0% /dev
tmpfs                        395M     21M  375M    6% /run
/dev/mapper/admin3--vg-root   28G     11G   16G   42% /
tmpfs                        2,0G       0  2,0G    0% /dev/shm
tmpfs                        5,0M       0  5,0M    0% /run/lock
tmpfs                        2,0G       0  2,0G    0% /sys/fs/cgroup
/dev/sda1                    236M     84M  140M   38% /boot
tmpfs                        395M       0  395M    0% /run/user/0
root@admin3:/var/log# free -h
              total        used        free      shared  buff/cache   available
Mem:          3,9Gi       2,3Gi       309Mi        26Mi       1,3Gi       1,3Gi
Swap:         4,0Gi       5,0Mi       4,0Gi
root@admin3:/var/log# 

Wie bekomme ich heraus, was da nicht passt?

Gruß,
Bytegetter

[andromeda]

Irgendein Problem mit der Amavis db. Da es nur sehr sporadisch ist... kannst den Dienst per monit überwachen und dann automatisch neu starten lassen.

[OlliTheDarkness]

Sieht für mich nach ner zerschossenen Datenbank aus ...
Die VM mal öffters unsauber abgeschaltet (?)

Du könntest folgendes probieren:

Amavis stoppen, Inhalt von /var/lib/amavis/db sichern, den Inhalt des Ordners löschen und Amavis wieder starten.

Dann wird die DB in 99% sauber neu angelegt.

Schlimmstenfalls einmal Server reboot aber eig. nicht nötig.

Wenn es nen DB defekt ist, wovon ich ausgehe, sollte das Thema damit vom Tisch sein.