Wegvallende letters in username.

[Briolet]

Ik heb eens in het maillog (/volume1/@maillog) gekeken en vond daar een paar heel vreemde entries. Bug in de software? Dit is al maanden gaande, en ik zie pas aan het log dat alles niet vlekkenloos verloopt.

Mijn account op de nas heet ‘MijnNaam’, dus met twee hoofdletters erin. Nu zie ik heel regelmatig in het log staan: “postfix: SLIBUserRealNameGet(user=ijnNaam) failed”. Zie de 3 regels in het log die ik met '<----' gemarkeerd heb.

Het lijkt of er ergens iets mis gaat met een hoofdletter in het begin. De volgende regel haalt hij dan vaak wel via een andere manier de goede naam op. En een andere keer vind ik een disconnect in het log en lukt het wel weer bij een volgende poging.

Klinkt dit bekend  bij iemand?

Code: [Selecteer]

Oct 20 11:00:45 GedeeldeData postfix/smtpd[2721]: connect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]
Oct 20 11:00:45 GedeeldeData postfix/smtpd[2721]: Anonymous TLS connection established from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]: TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits)
Oct 20 11:00:45 GedeeldeData postfix/smtpd[2721]: postfix: SLIBUserRealNameGet(user=ijnNaam) failed  <——
Oct 20 11:00:48 GedeeldeData postfix/smtpd[2721]: 65DBE18EDE: client=xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx], sasl_method=PLAIN, sasl_username=MijnNaam
Oct 20 11:00:48 GedeeldeData postfix/cleanup[2728]: 65DBE18EDE: hold: header Received: from [10.0.1.20] (xxx.cm-3-2c.dynamic.ziggo.nl [217.121.xxx.xxx])??(using TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits))??(No client certificate requested)??(Authenticated sender from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]; from=<info@mijndomein.nl> to=<info@zijndomein-1.nl> proto=ESMTP helo=<[10.0.1.20]>
Oct 20 11:00:48 GedeeldeData postfix/cleanup[2728]: 65DBE18EDE: message-id=<7E1817A9-4E32-4F0C-A403-CCAF999B8E1B@mijndomein.nl>
Oct 20 11:01:25 GedeeldeData postfix/qmgr[3310]: C33C518E97: from=<info@mijndomein.nl>, size=534979, nrcpt=1 (queue active)
Oct 20 11:01:28 GedeeldeData postfix/smtp[2764]: Untrusted TLS connection established to smtp.ziggo.nl[212.54.42.9]:587: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits)
Oct 20 11:01:29 GedeeldeData postfix/smtp[2764]: C33C518E97: to=<info@zijndomein-1.nl>, relay=smtp.ziggo.nl[212.54.42.9]:587, delay=42, delays=37/2.7/0.94/0.72, dsn=2.0.0, status=sent (250 OK id=1Xg8qG-0005lk-V8)
Oct 20 11:01:29 GedeeldeData postfix/qmgr[3310]: C33C518E97: removed
Oct 20 11:01:50 GedeeldeData postfix/smtpd[2721]: disconnect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]


Code: [Selecteer]

Oct 20 14:53:13 GedeeldeData postfix/smtpd[15702]: connect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]
Oct 20 14:53:14 GedeeldeData postfix/smtpd[15702]: Anonymous TLS connection established from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]: TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits)
Oct 20 14:53:14 GedeeldeData postfix/smtpd[15702]: postfix: SLIBUserRealNameGet(user=ijnNaam) failed  <——
Oct 20 14:53:16 GedeeldeData postfix/smtpd[15702]: disconnect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]
Oct 20 14:53:42 GedeeldeData postfix/anvil[3406]: statistics: max connection rate 4/86400s for (25:217.121.xxx.xxx) at Oct 20 14:53:14
Oct 20 14:53:42 GedeeldeData postfix/anvil[3406]: statistics: max connection count 1 for (25:217.121.xxx.xxx) at Oct 20 14:53:14

Oct 20 14:55:43 GedeeldeData postfix/smtpd[15832]: connect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]
Oct 20 14:55:44 GedeeldeData postfix/smtpd[15832]: Anonymous TLS connection established from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]: TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits)
Oct 20 14:55:44 GedeeldeData postfix/smtpd[15832]: postfix: SLIBUserRealNameGet(user=ijnNaam) failed  <——
Oct 20 14:55:46 GedeeldeData postfix/smtpd[15832]: 8BFEE32BB4: client=xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx], sasl_method=PLAIN, sasl_username=MijnNaam
Oct 20 14:55:46 GedeeldeData postfix/cleanup[15839]: 8BFEE32BB4: hold: header Received: from [10.0.1.20] (xxx.cm-3-2c.dynamic.ziggo.nl [217.121.xxx.xxx])??(using TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits))??(No client certificate requested)??(Authenticated sender from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]; from=<info@mijndomein.nl> to=<info@zijndomein-2.nl> proto=ESMTP helo=<[10.0.1.20]>
Oct 20 14:55:46 GedeeldeData postfix/cleanup[15839]: 8BFEE32BB4: message-id=<5EA0FF22-FEDD-4582-B409-40D5E6C47A09@mijndomein.nl>
Oct 20 14:56:50 GedeeldeData postfix/smtpd[15832]: disconnect from xxx.cm-3-2c.dynamic.ziggo.nl[217.121.xxx.xxx]
Oct 20 14:56:57 GedeeldeData postfix/qmgr[3310]: 621DA32923: from=<info@mijndomein.nl>, size=115087, nrcpt=1 (queue active)
Oct 20 14:57:00 GedeeldeData postfix/smtp[15993]: Untrusted TLS connection established to smtp.ziggo.nl[212.54.34.9]:587: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits)
Oct 20 14:57:01 GedeeldeData postfix/smtp[15993]: 621DA32923: to=<info@zijndomein-2.nl>, relay=smtp.ziggo.nl[212.54.34.9]:587, delay=75, delays=71/2.8/0.9/0.54, dsn=2.0.0, status=sent (250 OK id=1XgCWC-0007Wr-Vh)
Oct 20 14:57:01 GedeeldeData postfix/qmgr[3310]: 621DA32923: removed
Oct 20 15:03:42 GedeeldeData postfix/anvil[3406]: statistics: max connection rate 12/86400s for (465:217.121.xxx.xxx) at Oct 20 14:55:44
Oct 20 15:03:42 GedeeldeData postfix/anvil[3406]: statistics: max connection count 1 for (465:217.121.xxx.xxx) at Oct 20 14:55:44
Oct 20 15:03:42 GedeeldeData postfix/anvil[3406]: statistics: max sasl_sender rate 3/86400s for (MijnNaam) at Oct 20 14:55:46


[Briolet]

Het ligt toch niet aan de hoofdletters. Ik heb een useraccount aangemaakt die volledig lowercase was en ook daarmee valt de eerste letter weg als een SMTP verbinding gemaakt wordt.

Maar bij de externe aanlegpogingen zie ik dat wegvallen nooit. Zou het dan aan mijn eigen Apple Mail client kunnen liggen?

Code: [Selecteer]

Jun 12 14:57:32 GedeeldeData postfix/smtpd[26910]: postfix: SLIBUserRealNameGet(user=password) failed
Jun 12 14:57:35 GedeeldeData postfix/smtpd[26910]: postfix: SLIBUserRealNameGet(user=123456) failed
Jun 12 14:57:38 GedeeldeData postfix/smtpd[26910]: postfix: SLIBUserRealNameGet(user=qwerty) failed
Jun 12 15:00:28 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=laser) failed
Jun 12 15:00:31 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=laser1) failed
Jun 12 15:00:34 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=laser123) failed
Jun 12 15:00:36 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=password) failed
Jun 12 15:00:38 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=123456) failed
Jun 12 15:00:41 GedeeldeData postfix/smtpd[27016]: postfix: SLIBUserRealNameGet(user=qwerty) failed
Jun 12 15:06:41 GedeeldeData postfix/smtpd[27216]: postfix: SLIBUserRealNameGet(user=mary) failed
Jun 12 15:06:44 GedeeldeData postfix/smtpd[27216]: postfix: SLIBUserRealNameGet(user=mary1) failed
Jun 12 15:06:47 GedeeldeData postfix/smtpd[27216]: postfix: SLIBUserRealNameGet(user=mary123) failed
Jun 12 15:06:50 GedeeldeData postfix/smtpd[27216]: postfix: SLIBUserRealNameGet(user=password) failed
Jun 12 15:06:53 GedeeldeData postfix/smtpd[27216]: postfix: SLIBUserRealNameGet(user=123456) failed
Jun 12 15:25:41 GedeeldeData postfix/smtpd[27769]: postfix: SLIBUserRealNameGet(user=service) failed
Jun 12 15:25:44 GedeeldeData postfix/smtpd[27769]: postfix: SLIBUserRealNameGet(user=service1) failed
Jun 12 15:25:46 GedeeldeData postfix/smtpd[27769]: postfix: SLIBUserRealNameGet(user=service123) failed
Jun 12 15:25:49 GedeeldeData postfix/smtpd[27769]: postfix: SLIBUserRealNameGet(user=123456) failed
Jun 12 15:25:52 GedeeldeData postfix/smtpd[27769]: postfix: SLIBUserRealNameGet(user=password) failed
Jun 12 15:38:23 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=supervisor) failed
Jun 12 15:38:26 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=supervisor1) failed
Jun 12 15:38:29 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=supervisor123) failed
Jun 12 15:38:32 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=password) failed
Jun 12 15:38:36 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=123456) failed
Jun 12 15:38:39 GedeeldeData postfix/smtpd[28156]: postfix: SLIBUserRealNameGet(user=qwerty) failed
Jun 12 15:51:19 GedeeldeData postfix/smtpd[28532]: postfix: SLIBUserRealNameGet(user=user2) failed
Jun 12 15:51:22 GedeeldeData postfix/smtpd[28532]: postfix: SLIBUserRealNameGet(user=user21) failed
Jun 12 15:51:25 GedeeldeData postfix/smtpd[28532]: postfix: SLIBUserRealNameGet(user=user2123) failed

Aan die 'inbraakpogingen' zie je dat een sterk wachtwoord bijna niet nodig is als je geen standaard accountnaam gebruikt. Ik zie wel een stuk of duizend van dit soort vruchteloze pogingen in het maillog.   

[Briolet]

Ik hen het net een mailtje met android versuurd. Dan komt de regel "postfix: SLIBUserRealNameGet(user=ijnNaam)" niet eens voor in het log. Dat is blijkbaar een onderdeel van een niet noodzakelijke inlegprotocol. Dus ik krijg het gevoel dat het probleem in mijn mail cliënt zit. (Het gebeurt zowel met Snow Leopard en Mavericks als met het 1 week nieuw Yosemite)

[Robert Koopman]

Ik heb even in mijn mail log gekeken maar het een lange stroom data.
Hoe krijg jij het zo mooi in beeld?
Ik kon zo niet veel bekijken.

[Briolet]

Op de mac staat een heel mooi programma om logfiles te bekijken. Dat mis ik op de nas, dus heb ik een truukje toegepast. Ik had een share waar al logfiles naar toe geschreven werden. In die share heb ik een hard-link naar de originele logfile bijgezet. Nu ziet mijn mac software de logfile en kan ik hem met de 'Console' bekijken. In dat programma, dat speciaal voor logfiles geschreven is, heb ik opties om heel snel regels uit een brij van data te filteren.

Een hard-link of een simlink van het systeemlog op die share neer te zetten gaf echter een foutmelding. (Zal wel een rechtenkwestie zijn)

[compuline]

Beste,

Bij mij treedt hetzelfde probleem op.
Heeft iemand hier ooit een oorzaak voor gevonden ?

alvast dank,
Bas

[Briolet]

Nee. Twee jaar verder, maar dit staat nog steeds in mijn log. De methode "SLIBUserRealNameGet" wordt niet door elk mail programma gebruikt. Apple mail gebruikt het, maar Thunderburd op mijn mac niet en ook niet het GMail programma op mijn Android telefoon.

In het log zie ik al mijn macs zo ongeveer 1x per uur zo'n falende inlog doen als ze in slaapstand staan. Dit wordt echter niet gecorrigeerd, wat hoogst irritant is, omdat hij dan iets achterwege laat wat hij had willen doen. b.v. beter op de hoogte blijven van nieuwe mail, hoewel binnenkomende mail doorgaans direct zichtbaar is.

Alleen bij het versturen van mail gaat het eerst mis via "SLIBUserRealNameGet "en dan goed met de tweede aanmeld methode: "sasl_method=PLAIN, sasl_username="

De "SLIBUserRealNameGet" zie ik ook vaak terug komen bij inbraak pogingen, maar dan missen er geen tekens. Ergens lijkt het me dan dat de bug bij Apple mail ligt. Maar ook weer vreemd dat het na 2 jaar nog steeds niet gefixed is.

En als ik Google vind ik slechts één vraag hierover zonder antwoord:

b.v. http://serverfault.com/questions/756151/postfix-slibuserrealnameget-failed

[compuline]

Hoi,

Heb nog een link gevonden en vraag bij hen uitstaan, ik zal je hiervan op de hoogte houden.

zou dit wegvallen een reden kunnen zijn waarom mijn mails vanuit apple mail geen dkim signature krijgen ?
de bron ziet er als volgt uit.

Authentication-Results:xxxxxx.xxx; dkim=permerror (bad message/signature format)
Content-Type: text/plain
Content-Transfer-Encoding: 7bit
Subject: bas
Message-Id: <5CF7CFF2-B523-44C0-998F-C4D3C9142EB4@xxxxxxxx.xxx>

maillog:
postfix: SLIBUserRealNameGet(user=xx) failed
postfix/smtpd[13749]: 01F4F187F: client=unknown[xxx.xxx.xxx.xxx], sasl_method=PLAIN, sasl_username=xxx
postfix/cleanup[13756]: 01F4F187F: message-id=<5CF7CFF2-B523-44C0-998F-C4D3C9142EB4@xxxxxx.xxx>
opendkim[7616]: 01F4F187F: can't determine message sender; accepting
opendmarc[7625]: 01F4F187F: RFC5322 requirement error: missing From field; accepting
postfix/qmgr[7862]: 01F4F187F: from=<xxx@xxxxxx.xxx>, size=328, nrcpt=1 (queue active)
dovecot: lda(xxx): sieve: msgid=<5CF7CFF2-B523-44C0-998F-C4D3C9142EB4@xxxxxx.xxx>: stored mail into mailbox 'INBOX'
postfix/local[13757]: 01F4F187F: to=<xxx@xxxxxx.xxx>, relay=local, delay=1.2, delays=0.35/0.17/0/0.68, dsn=2.0.0, status=sent (delivered to command: /var/packages/MailServer/target/libexec/dovecot/dovecot-lda -f "$SENDER" -a "$RECIPIENT")
postfix/qmgr[7862]: 01F4F187F: removed
postfix/smtpd[13749]: disconnect from unknown[xxx.xxx.xxx.xxx]

Blijkbaar wordt de sender niet gevonden waardoor dkim ook stopt.
of zie ik dat verkeerd ?

groet,
bas

[Briolet]

dkim heeft in elk geval niets met de mail cliënt van doen. Dat wordt er pas op de nas aan toegevoegd. Bij mij heeft die ondertekening al vanaf het begin gewerkt. Ik zou bijna zeggen dat ergens een configuratiefout zit.

Mijn mail log van een verzonden mail, via Apple Mail, ziet er als volgt uit:

Code: [Selecteer]

postfix/smtpd[20333]: postfix: SLIBUserRealNameGet(user=riolet) failed
postfix/smtpd[20333]: 1B2F96071: client=iMac-DDR2.local[10.0.1.20], sasl_method=PLAIN, sasl_username=Briolet
postfix/cleanup[20649]: 1B2F96071: message-id=<BB688B77-7223-4E18-9E80-D8702A682768@mijndomein.nl>
postfix/qmgr[24597]: 1B2F96071: from=<info@mijndomein.nl>, size=4469, nrcpt=1 (queue active)
postfix/smtp[20650]: Trusted TLS connection established to smtp.ziggo.nl[212.54.34.9]:587: TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
postfix/smtp[20650]: 1B2F96071: to=<info@zijndomein.de>, relay=smtp.ziggo.nl[212.54.34.9]:587, delay=0.88, delays=0.27/0.03/0.16/0.43, dsn=2.0.0, status=sent (250 OK id=1cIva5-0006uL-Tn)
postfix/qmgr[24597]: 1B2F96071: removed
postfix/smtpd[20333]: disconnect from iMac-DDR2.local[10.0.1.20] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6

Hierin geen DKIM en DMARC regels omdat die geen error geven bij mij. Is je "from" domein wel gelijk aan het DKIM domein? Als dat niet zo is laat hij de DKIM ondertekening gewoon weg. (Maar geeft bij mijn weten nog steeds geen fout in het log)

Edit:
Even getest door briolet@hetnet.nl als afzender te gebruiken naar mijn GMail adres. Die komt gewoon aan zonder DKIM signature en met hetzelfde maillog als hierboven.

[off topic]In de mail header staat dan:

Code: [Selecteer]

Received-Spf: neutral (google.com: 212.54.42.166 is neither permitted nor denied by best guess record for domain of briolet@hetnet.nl) client-ip=212.54.42.166;Eigenlijk belachelijk dat knp zijn mail zo slecht beschermd. Dit is gewoon een fake afzender, want ik heb niet eens een 'hetnet' account.[/off-topic]

[compuline]

Hoi,

standaard stonden er bij in de header_checks:
/^X-Originating-IP:/ IGNORE
/^Received:.*\[192\.168\..*/ IGNORE
/^Received:.*\[10\..*/ IGNORE
/^Received:.*\[169\..*/ IGNORE
/^Received:.*\[127\.0\.0\.1/ IGNORE
/^X-Mailer:/ IGNORE
/^User-Agent:/ IGNORE

wanneer je de regel van het localnetwerk of verwijderd of WARN wordt blijkbaar de from van apple mail
client met rust gelaten en wel herkent door opendkim.

header van mail naar gmail: met spf,dkim en dmarc.
helaas ook interne gegevens, zie ik. hoe zou ik die wegkunnen krijgen. door een header_check ?

Received: from beijertjes.com ([86.86.74.247])
        by mx.google.com with ESMTPS id k11si23107048wmb.125.2016.12.21.01.53.01
        for <basbeijer@gmail.com>
        (version=TLS1_2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128);
        Wed, 21 Dec 2016 01:53:02 -0800 (PST)
Received-SPF: pass (google.com: domain of bas@beijertjes.com designates 86.86.74.247 as permitted sender) client-ip=86.86.74.247;
Authentication-Results: mx.google.com;
       dkim=pass header.i=@beijertjes.com;
       spf=pass (google.com: domain of bas@beijertjes.com designates 86.86.74.247 as permitted sender) smtp.mailfrom=bas@beijertjes.com;
       dmarc=pass (p=NONE dis=NONE) header.from=beijertjes.com
Received: from [192.168.2.19] (beijertjes.com [86.86.74.247])
   (using TLSv1 with cipher ECDHE-RSA-AES256-SHA (256/256 bits))
   (No client certificate requested)
   (Authenticated sender: bas)
   by beijertjes.com (Postfix) with ESMTPSA id F330444E60
   for <basbeijer@gmail.com>; Wed, 21 Dec 2016 10:53:00 +0100 (CET)
Authentication-Results: beijertjes.com; dmarc=none header.from=beijertjes.com
DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=beijertjes.com;
   s=default; t=1482313981;
   bh=AUzyPliMYEMU77EoZmxVUAFd88eGPAwsJRe51uPKUKo=;
   h=From:Subject:Date:To;
   b=TXaWNNKPywsPhlHsrb9qr4W1gb6LumD6KbOTDhhxlP88gb7puOBgV/8zA8wSe8PiY
    a/lecz8VJgK+ttwf6HYTElezGnVChQxyWQNRp51i4zQKxLnPvtHC50XLw+l4LEofr4
    Vv13C5QJZFvZGm6GdY1Omqa88hbfV3xLvOJO4Q6Q=


helaas nog geen oplossing voor de wegvallende letters. Misschien later, hoewel de kans groot is
dat er iets anders leuks opduikt wat de volledige aandacht opeist

dank voor je hulp in iedere geval.

gr,
bas