Resolved one specific email address recieve message twice (sent from an app through the same server)

[Alaa Mansour]

Hello everybody

so my server is (server.com)
and my client domain is (client.com)
app email address (app@client.com)

one of the persons in my client domain recive the email twice (double@client.com) he is in CC, the main target doesn't recieve it twice (target@client.com)
and not from all the parts of the app, just one part

i can't understand what is the problem.

TLS SNI server.com from unknown[188.11.98.82] not matched, using default chain
Jun  5 12:13:12 plesk postfix/smtpd[27660]: AF1A37AD: client=unknown[188.11.98.82], sasl_method=login, sasl_username=app@client.com
Jun  5 12:13:12 plesk postfix/cleanup[27527]: AF1A37AD: message-id=<>
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'limit-out' handler
Jun  5 12:13:13 plesk check-quota[27666]: Starting the check-quota filter...
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk psa-pc-remote[25084]: SKIP during call 'check-quota' handler
Jun  5 12:13:13 plesk spf[27667]: Starting the spf filter...
Jun  5 12:13:13 plesk spf[27667]: SPF status: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'spf' handler
Jun  5 12:13:13 plesk dk_sign[27668]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk dk_sign[27669]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk postfix/qmgr[67989]: AF1A37AD: from=<app@client.com>, size=528, nrcpt=2 (queue active)
Jun  5 12:13:13 plesk postfix-local[27670]: postfix-local: from=app@client.com, to=double@client.com, dirname=/var/qmail/mailnames
Jun  5 12:13:13 plesk postfix-local[27672]: postfix-local: from=app@client.com, to=target@client.com, dirname=/var/qmail/mailnames
Jun  5 12:13:13 plesk spamassassin[27673]: Starting the spamassassin filter...
Jun  5 12:13:13 plesk spamd[120013]: spamd: connection from 127.0.0.1 [127.0.0.1]:34130 to port 783, fd 6
Jun  5 12:13:13 plesk spamd[120013]: spamd: using default config for double@client.com: /var/qmail/mailnames/client.com/double/.spamassassin/user_prefs
Jun  5 12:13:13 plesk spamd[120013]: spamd: processing message (unknown) for double@client.com:30
Jun  5 12:13:13 plesk spamassassin[27675]: Starting the spamassassin filter...
Jun  5 12:13:13 plesk spamd[128908]: spamd: connection from 127.0.0.1 [127.0.0.1]:34132 to port 783, fd 6
Jun  5 12:13:13 plesk spamd[128908]: spamd: using default config for target@client.com: /var/qmail/mailnames/client.com/target/.spamassassin/user_prefs
Jun  5 12:13:13 plesk spamd[128908]: spamd: processing message (unknown) for target@client.com:30
Jun  5 12:13:13 plesk spamd[128908]: spamd: clean message (-1.1/7.0) for target@client.com:30 in 0.3 seconds, 1332 bytes.
Jun  5 12:13:13 plesk spamd[128908]: spamd: result: . -1 - ALL_TRUSTED,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,MISSING_MID,SPF_PASS,URIBL_BLOCKED scantime=0.3,size=1332,user=ma$
Jun  5 12:13:13 plesk spamd[120013]: spamd: clean message (-1.1/7.0) for double@client.com:30 in 0.3 seconds, 1332 bytes.
Jun  5 12:13:13 plesk spamd[120013]: spamd: result: . -1 - ALL_TRUSTED,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,MISSING_MID,SPF_PASS,URIBL_BLOCKED scantime=0.3,size=1332,user=lu$
Jun  5 12:13:13 plesk dk_check[27677]: Starting the dk_check filter...
Jun  5 12:13:13 plesk dk_check[27677]: DKIM verify result: Success
Jun  5 12:13:13 plesk dmarc[27679]: Starting the dmarc filter...
Jun  5 12:13:13 plesk dmarc[27679]: Store DKIM result for 'client.com' into DMARC library.
Jun  5 12:13:13 plesk dk_check[27678]: Starting the dk_check filter...
Jun  5 12:13:13 plesk dmarc[27679]: DMARC: PASS message for target@client.com
Jun  5 12:13:13 plesk postfix-local[27672]: deliveryManager: Get empty message id, delivery state will be disabled for this mail
Jun  5 12:13:13 plesk dk_check[27678]: DKIM verify result: Success
Jun  5 12:13:13 plesk dmarc[27681]: Starting the dmarc filter...
Jun  5 12:13:13 plesk dmarc[27681]: Store DKIM result for 'client.com' into DMARC library.
Jun  5 12:13:13 plesk dmarc[27681]: DMARC: PASS message for double@client.com
Jun  5 12:13:13 plesk postfix-local[27670]: deliveryManager: Get empty message id, delivery state will be disabled for this mail
Jun  5 12:13:13 plesk spamd[128906]: prefork: child states: BI
Jun  5 12:13:13 plesk dovecot: service=lda, user=target@client.com, ip=[]. sieve: msgid=unspecified: stored mail into mailbox 'INBOX'
Jun  5 12:13:13 plesk spamd[128906]: prefork: child states: II
Jun  5 12:13:13 plesk dovecot: service=lda, user=double@client.com, ip=[]. msgid=unspecified: saved mail to INBOX
Jun  5 12:13:13 plesk plesk sendmail[27688]: handlers_stderr: PASS
Jun  5 12:13:13 plesk plesk sendmail[27688]: PASS during call 'limit-out' handler
Jun  5 12:13:13 plesk plesk sendmail[27691]: handlers_stderr: PASS
Jun  5 12:13:13 plesk check-quota[27695]: Starting the check-quota filter...
Jun  5 12:13:13 plesk plesk sendmail[27688]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk plesk sendmail[27688]: SKIP during call 'check-quota' handler
Jun  5 12:13:13 plesk plesk sendmail[27691]: PASS during call 'limit-out' handler
Jun  5 12:13:13 plesk dk_sign[27696]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk check-quota[27697]: Starting the check-quota filter...
Jun  5 12:13:13 plesk plesk sendmail[27688]: handlers_stderr: PASS
Jun  5 12:13:13 plesk plesk sendmail[27688]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk plesk sendmail[27691]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk plesk sendmail[27691]: SKIP during call 'check-quota' handler
Jun  5 12:13:13 plesk dk_sign[27701]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk plesk sendmail[27691]: handlers_stderr: PASS
Jun  5 12:13:13 plesk plesk sendmail[27691]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk postfix/pickup[3714]: B08B4CEE: uid=30 from=<app@client.com>
Jun  5 12:13:13 plesk postfix/cleanup[27527]: B08B4CEE: message-id=<20200605101313.B08B4CEE@server.com>
Jun  5 12:13:13 plesk postfix/pipe[27535]: AF1A37AD: to=<double@client.com>, relay=plesk_virtual, delay=1.1, delays=0.47/0/0/0.6, dsn=2.0.0, status=sent (delivered via pl$
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk psa-pc-remote[25084]: SKIP during call 'limit-out' handler
Jun  5 12:13:13 plesk check-quota[27712]: Starting the check-quota filter...
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk psa-pc-remote[25084]: SKIP during call 'check-quota' handler
Jun  5 12:13:13 plesk spf[27713]: Starting the spf filter...
Jun  5 12:13:13 plesk spf[27713]: SPF result: pass
Jun  5 12:13:13 plesk spf[27713]: SPF status: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'spf' handler
Jun  5 12:13:13 plesk plesk sendmail[27708]: handlers_stderr: PASS
Jun  5 12:13:13 plesk dk_sign[27714]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk psa-pc-remote[25084]: handlers_stderr: PASS
Jun  5 12:13:13 plesk psa-pc-remote[25084]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk plesk sendmail[27708]: PASS during call 'limit-out' handler
Jun  5 12:13:13 plesk check-quota[27715]: Starting the check-quota filter...
Jun  5 12:13:13 plesk plesk sendmail[27708]: handlers_stderr: SKIP
Jun  5 12:13:13 plesk plesk sendmail[27708]: SKIP during call 'check-quota' handler
Jun  5 12:13:13 plesk dk_sign[27716]: Starting the dk_sign filter...
Jun  5 12:13:13 plesk plesk sendmail[27708]: handlers_stderr: PASS
Jun  5 12:13:13 plesk plesk sendmail[27708]: PASS during call 'dd51-domainkeys' handler
Jun  5 12:13:13 plesk postfix/qmgr[67989]: B08B4CEE: from=<app@client.com>, size=1930, nrcpt=1 (queue active)
Jun  5 12:13:13 plesk postfix/pickup[3714]: E89B0920: uid=30 from=<app@client.com>
Jun  5 12:13:13 plesk postfix/cleanup[27527]: E89B0920: message-id=<20200605101313.E89B0920@server.com>
Jun  5 12:13:13 plesk postfix-local[27721]: postfix-local: from=app@client.com, to=double@client.com, dirname=/var/qmail/mailnames
Jun  5 12:13:13 plesk spamassassin[27723]: Starting the spamassassin filter...
Jun  5 12:13:13 plesk spamd[120013]: spamd: connection from 127.0.0.1 [127.0.0.1]:34138 to port 783, fd 6
Jun  5 12:13:13 plesk spamd[120013]: spamd: using default config for double@client.com: /var/qmail/mailnames/client.com/double/.spamassassin/user_prefs
Jun  5 12:13:13 plesk spamd[120013]: spamd: processing message <20200605101313.B08B4CEE@server.com> for double@client.com:30
Jun  5 12:13:14 plesk spamd[120013]: spamd: clean message (-1.2/7.0) for double@client.com:30 in 0.1 seconds, 2020 bytes.
Jun  5 12:13:14 plesk spamd[120013]: spamd: result: . -1 - ALL_TRUSTED,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,SPF_PASS,URIBL_BLOCKED scantime=0.1,size=2020,user=double@cr$
Jun  5 12:13:14 plesk dk_check[27727]: Starting the dk_check filter...
Jun  5 12:13:14 plesk dk_check[27727]: DKIM verify result: Success
Jun  5 12:13:14 plesk dmarc[27728]: Starting the dmarc filter...
Jun  5 12:13:14 plesk dmarc[27728]: Store DKIM result for 'client.com' into DMARC library.
Jun  5 12:13:14 plesk dmarc[27728]: DMARC: PASS message for double@client.com
Jun  5 12:13:14 plesk spamd[128906]: prefork: child states: II
Jun  5 12:13:14 plesk dovecot: service=lda, user=double@client.com, ip=[]. msgid=<20200605101313.B08B4CEE@server.com>: saved mail to INBOX

 

[Alaa Mansour]

it was a fault of the customer, he turned on email frwarding on Plesk also to forward the email, so this is why it arrives twice