Problème amavisd sur macOS Server survenu le 31.01.2018

Mes recherches de l’origine du problème m’ont permis de trouver l’heure exacte de l’arrêt et la cause.
Une longue recherche sur Internet m’a permis de voir que nous ne sommes pas seuls.

Le problème est survenu à 2:30 du matin, lors de l’exécution journalière du script de maintenance et mise à jour du mécanisme antivirus antispam (nommé amavis).
Il y a un bug dans la version diffusée le 30.01

Le bug est répertorié et devrait être corrigé dans la mise à jour d’aujourd’hui


Résumé technique et trace :

Recherche de période :

dernier mail passé correctement dans le amavis
Jan 31 02:29:11 mail.dscL.fr mds[78]: (/)(Error) IndexPath in oid_t _oidParentForOid(SI_PersistentIDStore_t, oid_t, const MDCredential *):stat succeeded, getattrlist returned error 2 for 362262583 (/.vol/16777226/362262583 = /Library/Server/Mail/Data/scanner/amavis/tmp/amavis-20180131T021426-55024-6Pvr4rd6/parts/email.txt)

Jan 31 02:29:58 mail.dscL.fr postfix/smtp[55693]: D1D191597CA63: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=0.4, delays=0.06/0/0/0.34, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 3C93D1597CA77)

premier mail bloqué dans la file d’attente à cause de la panne
Jan 31 02:30:10 mail.dscL.fr postfix/smtp[55690]: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused
Jan 31 02:30:10 mail.dscL.fr postfix/smtp[55690]: 414011597CB34: to=, relay=none, delay=0.17, delays=0.17/0/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)

L’arrêt du service s’est donc produit le 31.01 entre 5h28 et 6h02

Pas de trace d’erreur dans /var/log/mail.log

Dans /var/log/system.log, on trouve la trace de l’arrêt du service amavisd :

Jan 31 02:30:07 mail com.apple.xpc.launchd[1] (org.amavis.amavisd[61822]): Service exited with abnormal code: 255
Jan 31 02:30:08 mail com.apple.xpc.launchd[1] (org.amavis.amavisd[55890]): Service exited with abnormal code: 255
Jan 31 02:30:08 mail com.apple.xpc.launchd[1] (org.amavis.amavisd): Service only ran for 0 seconds. Pushing respawn out by 10 seconds.

(le script de maintenance de amavis est exécuté tous les jours à 2:30. Il ne dure que quelques secondes. Le système s’est arrêté anormalement après 7 secondes à 2:30:07

Visiblement, nous ne sommes pas les seuls !
https://discussions.apple.com/thread/8262888

https://bz.apache.org/SpamAssassin/show_bug.cgi?id=7540

Publié dans Non classé.