And here are the log lines from mail.log when I send a test message, just in
case that helps anyone.
Jun 18 03:15:40 mail postfix/submission/smtpd[21926]: connect from
97-123-96-141.albq.qwest.net[97.123.96.141]
Jun 18 03:15:42 mail postfix/submission/smtpd[21926]: 4E16C17A09B:
client=97-123-96-141.albq.qwest.net[97.123.96.141], sasl_method=PLAIN,
sasl_username=j...@appalachianmeats.com
Jun 18 03:15:42 mail postfix/cleanup[21933]: 4E16C17A09B: replace: header
Received: from [172.30.10.247] (97-123-96-141.albq.qwest.net
[97.123.96.141])??by mail.mydomain.com (Postfix) with ESMTPSA id
4E16C17A09B??for <recipi...@anotherdomain.com>; Sat, 18 Jun 2022 03:15:42 +
from 97-123-96-141.albq.qwest.net[97.123.96.141]; from=<u...@mydomain.com>
to=<recipi...@anotherdomain.com> proto=ESMTP helo=<[172.30.10.247]>: Received:
from [127.0.0.1] (localhost [127.0.0.1])??by mail.mydomain.com (Postfix) with
ESMTPSA id 4E16C17A09B??for <recipi...@anotherdomain.com>; Sat, 18 Jun 2022
03:15:42 +0000 (UTC)
Jun 18 03:15:42 mail postfix/cleanup[21933]: 4E16C17A09B:
message-id=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>
Jun 18 03:15:42 mail opendkim[863]: 4E16C17A09B: DKIM-Signature field added
(s=default, d=mydomain.com)
Jun 18 03:15:42 mail postfix/qmgr[2968]: 4E16C17A09B: from=<u...@mydomain.com>,
size=557, nrcpt=2 (queue active)
Jun 18 03:15:42 mail spamd[1749]: spamd: connection from ::1 [::1]:50480 to
port 783, fd 5
Jun 18 03:15:42 mail spamd[1749]: spamd: setuid to gpgit succeeded
Jun 18 03:15:42 mail spamd[1749]: spamd: processing message
<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com> for gpgit:1001
Jun 18 03:15:42 mail spamd[1750]: spamd: connection from ::1 [::1]:50482 to
port 783, fd 5
Jun 18 03:15:42 mail spamd[1750]: spamd: setuid to gpgit succeeded
Jun 18 03:15:42 mail postfix/submission/smtpd[21926]: disconnect from
97-123-96-141.albq.qwest.net[97.123.96.141] ehlo=2 starttls=1 auth=1 mail=1
rcpt=1 data=1 quit=1 commands=8
Jun 18 03:15:42 mail spamd[1750]: spamd: processing message
<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com> for gpgit:1001
Jun 18 03:15:43 mail spamd[1749]: spamd: clean message (-1.2/5.0) for
gpgit:1001 in 0.5 seconds, 1084 bytes.
Jun 18 03:15:43 mail spamd[1749]: spamd: result: . -1 -
ALL_TRUSTED,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,TVD_SPACE_RATIO,URIBL_BLOCKED
scantime=0.5,size=1084,user=gpgit,uid=1001,required_score=5.0,rhost=::1,raddr=::1,rport=50480,mid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>,autolearn=ham
autolearn_force=no
Jun 18 03:15:43 mail spamd[1128]: prefork: child states: IB
Jun 18 03:15:44 mail spamd[1750]: spamd: clean message (-1.2/5.0) for
gpgit:1001 in 1.4 seconds, 1084 bytes.
Jun 18 03:15:44 mail spamd[1750]: spamd: result: . -1 -
ALL_TRUSTED,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,TVD_SPACE_RATIO,URIBL_BLOCKED
scantime=1.4,size=1084,user=gpgit,uid=1001,required_score=5.0,rhost=::1,raddr=::1,rport=50482,mid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>,autolearn=unavailable
autolearn_force=no
Jun 18 03:15:44 mail spamd[1128]: prefork: child states: II
Jun 18 03:15:44 mail postfix/pipe[21934]: 4E16C17A09B:
to=<recipi...@anotherdomain.com>, relay=gpgit-pipe, delay=2.1,
delays=0.31/0.01/0/1.8, dsn=2.0.0, status=sent (delivered via gpgit-pipe
service)
Jun 18 03:15:44 mail postfix/pipe[21935]: 4E16C17A09B: to=<u...@mydomain.com>,
relay=gpgit-pipe, delay=2.1, delays=0.31/0.02/0/1.8, dsn=2.0.0, status=sent
(delivered via gpgit-pipe service)
Jun 18 03:15:44 mail postfix/qmgr[2968]: 4E16C17A09B: removed
Jun 18 03:15:44 mail postfix/pickup[21737]: 532FF17A09B: uid=1001
from=<u...@mydomain.com>
Jun 18 03:15:44 mail postfix/cleanup[21952]: 532FF17A09B:
message-id=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>
Jun 18 03:15:44 mail opendkim[863]: 532FF17A09B: DKIM-Signature field added
(s=default, d=mydomain.com)
Jun 18 03:15:44 mail postfix/qmgr[2968]: 532FF17A09B: from=<u...@mydomain.com>,
size=1527, nrcpt=2 (queue active)
Jun 18 03:15:44 mail postfix/pickup[21737]: 62D6A17A0A0: uid=1001
from=<u...@mydomain.com>
Jun 18 03:15:44 mail postfix/cleanup[21952]: 62D6A17A0A0:
message-id=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>
Jun 18 03:15:44 mail opendkim[863]: 62D6A17A0A0: DKIM-Signature field added
(s=default, d=mydomain.com)
Jun 18 03:15:44 mail postfix/qmgr[2968]: 62D6A17A0A0: from=<u...@mydomain.com>,
size=1535, nrcpt=2 (queue active)
Jun 18 03:15:44 mail postfix/smtpd[21957]: connect from localhost[127.0.0.1]
Jun 18 03:15:44 mail postfix/smtpd[21957]: 8094B17A0A2:
client=localhost[127.0.0.1]
Jun 18 03:15:44 mail postfix/cleanup[21954]: 8094B17A0A2:
message-id=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>
Jun 18 03:15:44 mail postfix/qmgr[2968]: 8094B17A0A2: from=<u...@mydomain.com>,
size=2430, nrcpt=3 (queue active)
Jun 18 03:15:44 mail postfix/smtpd[21957]: disconnect from localhost[127.0.0.1]
ehlo=1 mail=1 rcpt=2 data=1 quit=1 commands=6
Jun 18 03:15:44 mail amavis[19728]: (19728-09) Passed CLEAN
{RelayedInbound,RelayedOpenRelay}, [127.0.0.1] <u...@mydomain.com> ->
<recipi...@anotherdomain.com>,<u...@mydomain.com>, Message-ID:
<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>, mail_id: FZ3nWI-M__7v,
Hits: -, size: 2039, queued_as: 8094B17A0A2, dkim_sd=default:mydomain.com, 123
ms
Jun 18 03:15:44 mail postfix/smtp[21953]: 532FF17A09B:
to=<recipi...@anotherdomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.9,
delays=1.7/0.04/0/0.14, 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 8094B17A0A2)
Jun 18 03:15:44 mail postfix/smtp[21953]: 532FF17A09B: to=<u...@mydomain.com>,
relay=127.0.0.1[127.0.0.1]:10024, delay=1.9, delays=1.7/0.04/0/0.14, 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 8094B17A0A2)
Jun 18 03:15:44 mail postfix/qmgr[2968]: 532FF17A09B: removed
Jun 18 03:15:44 mail postfix/smtpd[21957]: connect from localhost[127.0.0.1]
Jun 18 03:15:44 mail postfix/smtpd[21957]: 9B45817A09B:
client=localhost[127.0.0.1]
Jun 18 03:15:44 mail postfix/cleanup[21952]: 9B45817A09B:
message-id=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>
Jun 18 03:15:44 mail postfix/qmgr[2968]: 9B45817A09B: from=<u...@mydomain.com>,
size=2643, nrcpt=3 (queue active)
Jun 18 03:15:44 mail postfix/smtpd[21957]: disconnect from localhost[127.0.0.1]
ehlo=1 mail=1 rcpt=2 data=1 quit=1 commands=6
Jun 18 03:15:44 mail amavis[19779]: (19779-09) Passed CLEAN {RelayedInbound},
[127.0.0.1] <u...@mydomain.com> -> <u...@mydomain.com>,<u...@mydomain.com>,
Message-ID: <047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>, mail_id:
h4sjymJW2jCC, Hits: -, size: 2047, queued_as: 9B45817A09B,
dkim_sd=default:mydomain.com, 170 ms
Jun 18 03:15:44 mail postfix/smtp[21956]: 62D6A17A0A0: to=<u...@mydomain.com>,
relay=127.0.0.1[127.0.0.1]:10024, delay=2, delays=1.7/0.02/0/0.18, 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 9B45817A09B)
Jun 18 03:15:44 mail postfix/smtp[21956]: 62D6A17A0A0: to=<u...@mydomain.com>,
relay=127.0.0.1[127.0.0.1]:10024, delay=2, delays=1.7/0.02/0/0.18, 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 9B45817A09B)
Jun 18 03:15:44 mail postfix/qmgr[2968]: 62D6A17A0A0: removed
Jun 18 03:15:44 mail dovecot: lmtp(21866): Connect from local
Jun 18 03:15:44 mail dovecot: lmtp(21966): Connect from local
Jun 18 03:15:44 mail dovecot:
lmtp(u...@mydomain.com)<21866><S/9pKWBDrWJqVQAANmah8A>: sieve:
msgid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>: stored mail into
mailbox 'Sent'
Jun 18 03:15:44 mail postfix/lmtp[21963]: 8094B17A0A2: to=<u...@mydomain.com>,
relay=mail.mydomain.com[private/dovecot-lmtp], delay=0.22,
delays=0.02/0.05/0.1/0.05, dsn=2.0.0, status=sent (250 2.0.0
<u...@mydomain.com> S/9pKWBDrWJqVQAANmah8A Saved)
Jun 18 03:15:44 mail dovecot: lmtp(21866): Disconnect from local: Client has
quit the connection (state=READY)
Jun 18 03:15:44 mail dovecot: lmtp(21866): Connect from local
Jun 18 03:15:44 mail dovecot:
lmtp(u...@mydomain.com)<21866><oEmDLmBDrWJqVQAANmah8A>: sieve:
msgid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>: stored mail into
mailbox 'Sent'
Jun 18 03:15:44 mail postfix/lmtp[21963]: 9B45817A09B: to=<u...@mydomain.com>,
relay=mail.mydomain.com[private/dovecot-lmtp], delay=0.3,
delays=0.01/0.1/0.03/0.16, dsn=2.0.0, status=sent (250 2.0.0
<u...@mydomain.com> oEmDLmBDrWJqVQAANmah8A Saved)
Jun 18 03:15:44 mail dovecot:
lmtp(u...@mydomain.com)<21966><KCCGLmBDrWLOVQAANmah8A>: sieve:
msgid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>: stored mail into
mailbox 'Sent'
Jun 18 03:15:44 mail postfix/lmtp[21965]: 8094B17A0A2: to=<u...@mydomain.com>,
relay=mail.mydomain.com[private/dovecot-lmtp], delay=0.42,
delays=0.02/0.14/0.09/0.17, dsn=2.0.0, status=sent (250 2.0.0
<u...@mydomain.com> KCCGLmBDrWLOVQAANmah8A Saved)
Jun 18 03:15:44 mail dovecot: lmtp(21966): Disconnect from local: Client has
quit the connection (state=READY)
Jun 18 03:15:44 mail dovecot: lmtp(21968): Connect from local
Jun 18 03:15:44 mail dovecot:
lmtp(u...@mydomain.com)<21968><T0JmOWBDrWLQVQAANmah8A>: sieve:
msgid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>: stored mail into
mailbox 'Sent'
Jun 18 03:15:44 mail postfix/lmtp[21965]: 9B45817A09B: to=<u...@mydomain.com>,
relay=mail.mydomain.com[private/dovecot-lmtp], delay=0.36,
delays=0.01/0.29/0.03/0.03, dsn=2.0.0, status=sent (250 2.0.0
<u...@mydomain.com> T0JmOWBDrWLQVQAANmah8A Saved)
Jun 18 03:15:45 mail dovecot:
lmtp(u...@mydomain.com)<21866><INy2OGBDrWJqVQAANmah8A>: sieve:
msgid=<047b66f1-3332-acc4-a681-13a4cea8a...@mydomain.com>: stored mail into
mailbox 'Sent'
Jun 18 03:15:45 mail postfix/lmtp[21963]: 9B45817A09B: to=<u...@mydomain.com>,
relay=mail.mydomain.com[private/dovecot-lmtp], conn_use=2, delay=0.37,
delays=0.01/0.3/0/0.05, dsn=2.0.0, status=sent (250 2.0.0 <u...@mydomain.com>
INy2OGBDrWJqVQAANmah8A Saved)
Jun 18 03:15:45 mail postfix/qmgr[2968]: 9B45817A09B: removed
Jun 18 03:15:45 mail postfix/smtp[21913]: 8094B17A0A2:
to=<recipi...@anotherdomain.com>,
relay=filter.anotherdomain.com[216.24.56.70]:25, delay=0.82,
delays=0.02/0/0.55/0.25, dsn=2.0.0, status=sent (250 Ok: queued as 4E14D2E40052)
Jun 18 03:15:45 mail postfix/qmgr[2968]: 8094B17A0A2: removed
Jun 18 03:15:46 mail dovecot: lmtp(21968): Disconnect from local: Remote closed
connection (state=READY)
Jun 18 03:15:47 mail dovecot: lmtp(21866): Disconnect from local: Remote closed
connection (state=READY)
Austin Witmer
> On Jun 17, 2022, at 1:14 PM, Austin Witmer <austi...@emypeople.net> wrote:
>
> Hello all!
>
> I have a server running dovecot & postfix. I have a user on my server who is
> sending email via smtp on an HP printer and because of that, a copy of the
> email is not placed in the sent folder like usually happens with clients like
> outlook and thunderbird.
>
> I posted this problem on the dovecot mailing list, and they recommended that
> I use sender_bcc_maps in postfix to bcc messages to myself and then use a
> sieve script to place them in the sent folder.
>
> I got this working now but the trouble is that it is putting five copies of
> the same email in my sent folder. Does anybody have any ideas on why this
> might be happening?
>
> Thx!
>
> Austin Witmer
