Sent mail occasionally not stored in sent folder
Hi,
we encountered weird problem with OS X server behaviour where user sent email via smtp server and it is not stored in his/her mailbox.
This issue is happening time to time I would say almost randomly.
Do you have any idea why this is happening and where is the sent mail?
I picked a case and gathered relevant logs:
Thank you in advance
Server OS 10.10.1 server 4.0.3
Amavis.log
Apr 1 17:10:28 smtp.server.tld /Applications/Server.app/Contents/ServerRoot/usr/bin/amavisd[99344]: (99344-14) ESMTP:[127.0.0.1]:10024 /Library/Server/Mail/Data/scanner/amavis/tmp/amavis-20150401T162847-99344-AfuMa tsy: <sender@server.tld> -> <receiver@server.tld>,<cc@server.tld> SIZE=42753 Received: from smtp.server.tld ([127.0.0.1]) by localhost (smtp.server.tld [127.0.0.1]) (amavisd-new, port 10024) with ESMTP; Wed, 1 Apr 2015 17:10:28 +0200 (CEST)
Apr 1 17:10:28 smtp.server.tld /Applications/Server.app/Contents/ServerRoot/usr/bin/amavisd[99344]: (99344-14) Checking: JjUbZ6HVURz7 [31.7.245.146] <sender@server.tld> -> <receiver@server.tld>,<cc@server.tld>
Apr 1 17:10:28 smtp.server.tld /Applications/Server.app/Contents/ServerRoot/usr/bin/amavisd[99344]: (99344-14) Open relay? Nonlocal recips but not originating: receiver@server.tld
Apr 1 17:10:28 smtp.server.tld /Applications/Server.app/Contents/ServerRoot/usr/bin/amavisd[99344]: (99344-14) FWD from <sender@server.tld> -> <receiver@server.tld>,<cc@server.tld>,BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 920CA6DC5A3
Apr 1 17:10:28 smtp.server.tld /Applications/Server.app/Contents/ServerRoot/usr/bin/amavisd[99344]: (99344-14) Passed CLEAN {RelayedInbound,RelayedOpenRelay}, [31.7.245.146]:47116 [31.7.245.146] <sender@server.tld> -> <receiver@server.tld>,<cc@server.tld>, Queue-ID: 1D4EE6DC589, Message-ID: <1B41DA13-C36E-44A7-BF40-D470D106B262@server.tld>, mail_id: JjUbZ6HVURz7, Hits: 0.732, size: 42753, queued_as: 920CA6DC5A3, 493 ms
mail.log
Apr 1 17:10:28 smtp.server.tld postfix/smtp[1487]: 1D4EE6DC589: to=<receiver@server.tld>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.55, delays=0.03/0.02/0/0.49, 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 920CA6DC5A3)
Apr 1 17:10:36 smtp.server.tld postfix/smtp[1493]: 920CA6DC5A3: to=<receiver@server.tld>, relay=mail.receiverserver.com[11.22.33.44]:25, delay=7.4, delays=0.06/0.03/0.37/7, dsn=2.0.0, status=sent (250 OK id=1YdKHr-0006of-5z)
filter na 920CA6DC5A3
Apr 1 17:10:28 smtp.server.tld postfix/smtpd[1491]: 920CA6DC5A3: client=localhost[127.0.0.1]
Apr 1 17:10:28 smtp.server.tld postfix/cleanup[1486]: 920CA6DC5A3: message-id=<1B41DA13-C36E-44A7-BF40-D470D106B262@server.tld>
Apr 1 17:10:28 smtp.server.tld postfix/qmgr[215]: 920CA6DC5A3: from=<sender@server.tld>, size=43163, nrcpt=2 (queue active)
Apr 1 17:10:28 smtp.server.tld postfix/smtp[1487]: 1D4EE6DC589: to=<receiver@server.tld>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.55, delays=0.03/0.02/0/0.49, 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 920CA6DC5A3)
Apr 1 17:10:28 smtp.server.tld postfix/smtp[1487]: 1D4EE6DC589: to=<cc@server.tld>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.55, delays=0.03/0.02/0/0.49, 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 920CA6DC5A3)
Apr 1 17:10:28 smtp.server.tld postfix/pipe[1495]: 920CA6DC5A3: to=<cc@smtp.server.tld>, orig_to=<cc@server.tld>, relay=dovecot, delay=0.16, delays=0.06/0.01/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
Apr 1 17:10:36 smtp.server.tld postfix/smtp[1493]: 920CA6DC5A3: to=<receiver@server.tld>, relay=mail.receiverserver.com[11.22.33.44]:25, delay=7.4, delays=0.06/0.03/0.37/7, dsn=2.0.0, status=sent (250 OK id=1YdKHr-0006of-5z)
Apr 1 17:10:36 smtp.server.tld postfix/qmgr[215]: 920CA6DC5A3: removed
Mac mini, OS X Yosemite (10.10.1), Mac mini 5,1 - server