Hi folks.
I'm using some middleware to generate an email that's sent to Mac OSX Server's
installation of postfix. The email never gets through, and I don't know why.
I've had some suggestions which I will post below, but nothing seems to work.
I am not authenticating, and I've tried authentication which does not work
either. I don't know postfix at all, so I'm trying to see what could be the
problem by posting these tidbits of feedback and logs here. Any input
appreciated. I'm stumped as to why this isn't working.
192.168.1.4 is my workstation
192.168.1.7 is the server on 10.8.2 Server
SMTP log from Server.app
Jan 3 15:23:03 alpha.mydomain.com postfix/postscreen[4262]: DNSBL rank 2 for
[188.2.165.91]:4368
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4213]: connect from
cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:04 alpha.mydomain.com postfix/postscreen[4262]: CONNECT from
[192.168.1.4]:63328 to [192.168.1.7]:25
Jan 3 15:23:04 alpha.mydomain.com postfix/postscreen[4262]: WHITELISTED
[192.168.1.4]:63328
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4325]: connect from
unknown[192.168.1.4]
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4325]: disconnect from
unknown[192.168.1.4]
Here is a similar report:
postfix/postscreen[660]: CONNECT from [192.168.1.4]:52700 to [192.168.1.7]:25
Jan 4 06:08:59 alpha.mydomain.com postfix/postscreen[660]: WHITELISTED
[192.168.1.4]:52700
Jan 4 06:08:59 alpha.mydomain.com postfix/smtpd[661]: connect from
unknown[192.168.1.4]
Jan 4 06:08:59 alpha.mydomain.com postfix/smtpd[661]: lost connection after
AUTH from unknown[192.168.1.4]
Jan 4 06:08:59 alpha.mydomain.com postfix/smtpd[661]: disconnect from
unknown[192.168.1.4]
Jan 4 06:09:11 alpha.mydomain.com postfix/smtpd[669]: disconnect from
unknown[192.168.1.4]
Jan 4 06:09:19 alpha.mydomain.com postfix/postscreen[660]: CONNECT from
[192.168.1.4]:52708 to [192.168.1.7]:25
Jan 4 06:09:19 alpha.mydomain.com postfix/postscreen[660]: WHITELISTED
[192.168.1.4]:52708
Jan 4 06:09:19 alpha.mydomain.com postfix/smtpd[661]: connect from
unknown[192.168.1.4]
Jan 4 06:09:19 alpha.mydomain.com postfix/smtpd[661]: lost connection after
AUTH from unknown[192.168.1.4]
Jan 4 06:09:19 alpha.mydomain.com postfix/smtpd[661]: disconnect from
unknown[192.168.1.4]
/library/logs/mail/mailaccess.log:
Jan 3 15:23:23 alpha.mydomain.com log[1532]: imap-login: ID sent: name=Mac OS
X Mail, version=6.2 (1499), os=Mac OS X, os-version=10.8.2 (12C60),
vendor=Apple Inc.: rip=192.168.1.4, lip=192.168.1.7
Jan 3 15:23:23 alpha.mydomain.com log[1532]: imap-login: Login:
user=<my_username>, method=CRAM-MD5, rip=192.168.1.4, lip=192.168.1.7, mpid=1598
/var/log/system.log:
Jan 3 15:23:28 alpha.mydomain.com mdworker[4331]: Unable to talk to lsboxd
Jan 3 15:23:28 alpha.mydomain.com mdworker[4330]: Unable to talk to lsboxd
Jan 3 15:23:28 alpha.mydomain.com sandboxd[4333] ([4331]): mdworker(4331) deny
mach-lookup com.apple.ls.boxd
Jan 3 15:23:28 alpha.mydomain.com sandboxd[4333] ([4330]): mdworker(4330) deny
mach-lookup com.apple.ls.boxd
Jan 3 15:23:28 alpha kernel[0]: Sandbox: sandboxd(4333) deny mach-lookup
com.apple.coresymbolicationd
Jan 3 15:24:12 alpha.mydomain.com filecoordinationd[126]: NSFileCoordinator
only handles URLs that use the file: scheme. This one does not:
(null)
Jan 3 15:24:17 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] for authorization created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (2,0)
Jan 3 15:24:17 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Library/PrivilegedHelperTools/com.apple.serverd' [98] for authorization
created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (100000,0)
Jan 3 15:24:24 alpha.mydomain.com BBEdit[707]:
CVCGDisplayLink::setCurrentDisplay didn't find a valid display - falling back
to 60Hz
Jan 3 15:24:27 --- last message repeated 1 time ---
Jan 3 15:24:27 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] for authorization created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (2,0)
Jan 3 15:24:27 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Library/PrivilegedHelperTools/com.apple.serverd' [98] for authorization
created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (100000,0)
Jan 3 15:24:51 alpha.mydomain.com postfix/smtpd[4325]: warning: hostname
customer-qro-153-195.megared.net.mx does not resolve to address
189.198.153.195: nodename nor servname provided, or not known
Jan 3 15:24:57 alpha.mydomain.com postfix/smtpd[4213]: warning: hostname
96-38-164-142.dhcp.reno.nv.charter.com does not resolve to address
96.38.164.142: nodename nor servname provided, or not known
Jan 3 15:24:58 alpha.mydomain.com postfix/smtpd[4325]: warning: hostname
customer-qro-153-195.megared.net.mx does not resolve to address
189.198.153.195: nodename nor servname provided, or not known
Jan 3 15:25:07 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] for authorization created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (2,0)
Jan 3 15:25:07 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Library/PrivilegedHelperTools/com.apple.serverd' [98] for authorization
created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (100000,0)
Jan 3 15:25:17 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] for authorization created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (2,0)
Jan 3 15:25:17 alpha.mydomain.com com.apple.SecurityServer[19]: Succeeded
authorizing right 'system.privilege.admin' by client
'/Library/PrivilegedHelperTools/com.apple.serverd' [98] for authorization
created by
'/Applications/Server.app/Contents/ServerRoot/usr/libexec/ServerEventAgent'
[108] (100000,0)
Jan 3 15:25:26 alpha.mydomain.com postfix/smtpd[4325]: warning: hostname
customer-qro-153-195.megared.net.mx does not resolve to address
189.198.153.195: nodename nor servname provided, or not known
Jan 3 15:25:55 alpha.mydomain.com BBEdit[707]:
CVCGDisplayLink::setCurrentDisplay didn't find a valid display - falling back
to 60Hz
/var/log/mail.log:
Jan 3 15:23:03 alpha.mydomain.com postfix/postscreen[4262]: DNSBL rank 2 for
[188.2.165.91]:4368
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4213]: connect from
cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:04 alpha.mydomain.com postfix/postscreen[4262]: CONNECT from
[192.168.1.4]:63328 to [192.168.1.7]:25
Jan 3 15:23:04 alpha.mydomain.com postfix/postscreen[4262]: WHITELISTED
[192.168.1.4]:63328
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4325]: connect from
unknown[192.168.1.4]
Jan 3 15:23:04 alpha.mydomain.com postfix/smtpd[4325]: disconnect from
unknown[192.168.1.4]
Jan 3 15:23:06 alpha.mydomain.com postfix/smtpd[4213]: lost connection after
DATA from cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:06 alpha.mydomain.com postfix/smtpd[4213]: disconnect from
cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:13 alpha.mydomain.com postfix/postscreen[4262]: CONNECT from
[188.2.165.91]:4778 to [192.168.1.7]:25
Jan 3 15:23:13 alpha.mydomain.com postfix/dnsblog[4320]: addr 188.2.165.91
listed by domain zen.spamhaus.org as 127.0.0.4
Jan 3 15:23:13 alpha.mydomain.com postfix/dnsblog[4320]: addr 188.2.165.91
listed by domain zen.spamhaus.org as 127.0.0.10
Jan 3 15:23:13 alpha.mydomain.com postfix/postscreen[4262]: DNSBL rank 2 for
[188.2.165.91]:4778
Jan 3 15:23:13 alpha.mydomain.com postfix/smtpd[4325]: connect from
cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:15 alpha.mydomain.com postfix/smtpd[4325]: lost connection after
DATA from cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:15 alpha.mydomain.com postfix/smtpd[4325]: disconnect from
cable-188-2-165-91.dynamic.sbb.rs[188.2.165.91]
Jan 3 15:23:28 alpha.mydomain.com postfix/postscreen[4262]: CONNECT from
[188.2.165.91]:1183 to [192.168.1.7]:25
In the /Library/Server/Mail/Config/postfix/main.cf config file, I was told to
add the following to the end:
mynetworks = 127.0.0.0/8, [::1]/128, 192.168.1.0/24
smtpd_helo_restrictions = permit_sasl_authenticated, permit_mynetworks,
reject_non_fqdn_helo_hostname, reject_invalid_helo_hostname
Cheers
_____________
Rich in Toronto
