Outbound postfix routing issue

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
9 messages Options
Reply | Threaded
Open this post in threaded view
|

Outbound postfix routing issue

Jeremy Laurenson

>
> Good monring/noon/evening , all
>

>
> I have an issue getting my postfix system to deliver messages.  
> (Enclosed is the syslog, and postconf -n output)
>
> The system is set up to:
> Receive inbound SMTP port 25 connections. Working
> Escalate to a TLS session Working
> Auth against LDAP using saslauth Working
> Accept the message into the queue Working
> Route all emails to a relay host Not working
>
> I captured a sniffer trace using tcpdump with no filters and never  
> see an outbound connection.
>
>


Postfix.zip (19K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Noel Jones-2
Jeremy Laurenson wrote:

>
>>
>> Good monring/noon/evening , all
>>
>>
>> I have an issue getting my postfix system to deliver messages.
>> (Enclosed is the syslog, and postconf -n output)
>>
>> The system is set up to:
>>     Receive inbound SMTP port 25 connections.        Working
>>     Escalate to a TLS session                            Working
>>     Auth against LDAP using saslauth                    Working
>>     Accept the message into the queue                    Working
>>     Route all emails to a relay host                     Not working
>>
>> I captured a sniffer trace using tcpdump with no filters and never see
>> an outbound connection.
>>
>>
>

Turn off all the unnecessary verbose logging, and set
tls_loglevel to 0 or 1.  Then see:
http://www.postfix.org/DEBUG_README.html#logging

This will lead you to:

May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to
transport smtp: Connection refused

which in turn causes:

May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE:
to=<[hidden email]>, relay=none, delay=0.2,
delays=0.13/0.01/0/0.06, dsn=4.3.0, status=deferred (mail
transport unavailable)

Run "postfix check" to check file permissions.  Turn off
AppArmour or SELinux if running.



--
Noel Jones
Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Jeremy Laurenson
I have run the check, turned off all other verbose logging and still  
only see:
...
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0160 fa 57 7f 6c 6f b8 9a  
40|cc c9 21 bb 55 30 3d d0  .W.lo..@ ..!.U0=.
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0170 1c da 25 56 3b 04 83  
82|6c e7 27 79 bd bf 34 b9  ..%V;... l.'y..4.
May  7 16:43:34 wwwin postfix/smtpd[9866]: Read 351 chars: Message-Id:  
<247795E1-1BAF-4074-8525-E9F
May  7 16:43:34 wwwin postfix/cleanup[9879]: 1ADBC87BAE: message-id=<[hidden email]
 >
May  7 16:43:34 wwwin postfix/smtpd[9866]: Write 36 chars: 250 2.0.0  
Ok: queued as 1ADBC87BAE??
May  7 16:43:34 wwwin postfix/smtpd[9866]: write to 08091A80  
[080A39A0] (69 bytes => 69 (0x45))
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0000 17 03 01 00 40 c5 76  
88|4c c3 59 e0 9d 4b 94 ae  ....@.v. L.Y..K..
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0010 a5 a5 78 c1 5f 35 3d  
d0|6c a8 fa a6 96 ec fa f7  ..x._5=. l.......
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0020 1e 1e fb 06 46 5a 9e  
df|c9 72 1b e0 10 ba 15 ca  ....FZ.. .r......
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0030 a7 29 22 50 87 34 ea  
6a|c2 57 85 5e 73 eb 90 aa  .)"P.4.j .W.^s...
May  7 16:43:34 wwwin postfix/smtpd[9866]: 0040 ab 2b 9a 9b  
aa                                   .+...
May  7 16:43:34 wwwin postfix/smtpd[9866]: read from 08091A80  
[0809B190] (5 bytes => -1 (0xFFFFFFFF))
May  7 16:43:34 wwwin postfix/qmgr[9846]: 1ADBC87BAE: from=<[hidden email]
 >, size=622, nrcpt=1 (queue active)
May  7 16:43:34 wwwin postfix/qmgr[9846]: warning: connect to  
transport smtp: Connection refused
May  7 16:43:34 wwwin postfix/error[9882]: 1ADBC87BAE: to=<[hidden email]
 >, relay=none, delay=0.08, delays=0.06/0.01/0/0.01, dsn=4.3.0,  
status=deferred (mail transport unavailable)

I do not see an attempted SMTP connection outbound, so I do not know  
why its thinking it gets refused.
All processes are not chrooting, so I dont think its a permissions  
thing.


On May 7, 2008, at 4:13 PM, Noel Jones wrote:

> Jeremy Laurenson wrote:
>>>
>>> Good monring/noon/evening , all
>>>
>>>
>>> I have an issue getting my postfix system to deliver messages.  
>>> (Enclosed is the syslog, and postconf -n output)
>>>
>>> The system is set up to:
>>>    Receive inbound SMTP port 25 connections.        Working
>>>    Escalate to a TLS session                            Working
>>>    Auth against LDAP using saslauth                    Working
>>>    Accept the message into the queue                    Working
>>>    Route all emails to a relay host                     Not working
>>>
>>> I captured a sniffer trace using tcpdump with no filters and never  
>>> see an outbound connection.
>>>
>>>
>
> Turn off all the unnecessary verbose logging, and set tls_loglevel  
> to 0 or 1.  Then see:
> http://www.postfix.org/DEBUG_README.html#logging
>
> This will lead you to:
>
> May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to  
> transport smtp: Connection refused
>
> which in turn causes:
>
> May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE: to=<[hidden email]
> >, relay=none, delay=0.2, delays=0.13/0.01/0/0.06, dsn=4.3.0,  
> status=deferred (mail transport unavailable)
>
> Run "postfix check" to check file permissions.  Turn off AppArmour  
> or SELinux if running.
>
>
>
> --
> Noel Jones

Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Jeremy Laurenson


> I have run the check, turned off all other verbose logging and still  
> only see:
> ...
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0160 fa 57 7f 6c 6f b8 9a  
> 40|cc c9 21 bb 55 30 3d d0  .W.lo..@ ..!.U0=.
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0170 1c da 25 56 3b 04 83  
> 82|6c e7 27 79 bd bf 34 b9  ..%V;... l.'y..4.
> May  7 16:43:34 wwwin postfix/smtpd[9866]: Read 351 chars: Message-
> Id: <247795E1-1BAF-4074-8525-E9F
> May  7 16:43:34 wwwin postfix/cleanup[9879]: 1ADBC87BAE: message-id=<[hidden email]
> >
> May  7 16:43:34 wwwin postfix/smtpd[9866]: Write 36 chars: 250 2.0.0  
> Ok: queued as 1ADBC87BAE??
> May  7 16:43:34 wwwin postfix/smtpd[9866]: write to 08091A80  
> [080A39A0] (69 bytes => 69 (0x45))
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0000 17 03 01 00 40 c5 76  
> 88|4c c3 59 e0 9d 4b 94 ae  ....@.v. L.Y..K..
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0010 a5 a5 78 c1 5f 35 3d  
> d0|6c a8 fa a6 96 ec fa f7  ..x._5=. l.......
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0020 1e 1e fb 06 46 5a 9e  
> df|c9 72 1b e0 10 ba 15 ca  ....FZ.. .r......
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0030 a7 29 22 50 87 34 ea  
> 6a|c2 57 85 5e 73 eb 90 aa  .)"P.4.j .W.^s...
> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0040 ab 2b 9a 9b  
> aa                                   .+...
> May  7 16:43:34 wwwin postfix/smtpd[9866]: read from 08091A80  
> [0809B190] (5 bytes => -1 (0xFFFFFFFF))
> May  7 16:43:34 wwwin postfix/qmgr[9846]: 1ADBC87BAE: from=<[hidden email]
> >, size=622, nrcpt=1 (queue active)
> May  7 16:43:34 wwwin postfix/qmgr[9846]: warning: connect to  
> transport smtp: Connection refused
> May  7 16:43:34 wwwin postfix/error[9882]: 1ADBC87BAE: to=<[hidden email]
> >, relay=none, delay=0.08, delays=0.06/0.01/0/0.01, dsn=4.3.0,  
> status=deferred (mail transport unavailable)
>
> I do not see an attempted SMTP connection outbound, so I do not know  
> why its thinking it gets refused.
> All processes are not chrooting, so I dont think its a permissions  
> thing.
>
>
> On May 7, 2008, at 4:13 PM, Noel Jones wrote:
>
>> Jeremy Laurenson wrote:
>>>>
>>>> Good monring/noon/evening , all
>>>>
>>>>
>>>> I have an issue getting my postfix system to deliver messages.  
>>>> (Enclosed is the syslog, and postconf -n output)
>>>>
>>>> The system is set up to:
>>>>   Receive inbound SMTP port 25 connections.        Working
>>>>   Escalate to a TLS session                            Working
>>>>   Auth against LDAP using saslauth                    Working
>>>>   Accept the message into the queue                    Working
>>>>   Route all emails to a relay host                     Not working
>>>>
>>>> I captured a sniffer trace using tcpdump with no filters and  
>>>> never see an outbound connection.
>>>>
>>>>
>>
>> Turn off all the unnecessary verbose logging, and set tls_loglevel  
>> to 0 or 1.  Then see:
>> http://www.postfix.org/DEBUG_README.html#logging
>>
>> This will lead you to:
>>
>> May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to  
>> transport smtp: Connection refused
>>
>> which in turn causes:
>>
>> May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE: to=<[hidden email]
>> >, relay=none, delay=0.2, delays=0.13/0.01/0/0.06, dsn=4.3.0,  
>> status=deferred (mail transport unavailable)
>>
>> Run "postfix check" to check file permissions.  Turn off AppArmour  
>> or SELinux if running.
>>
>>
>>
>> --
>> Noel Jones
>

Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Noel Jones-2
Jeremy Laurenson wrote:
>
>

>>
>> On May 7, 2008, at 4:13 PM, Noel Jones wrote:
>>
>>> Jeremy Laurenson wrote:
>>>>>
>>>>> Good monring/noon/evening , all
>>>>>
>>>>>
>>>>> I have an issue getting my postfix system to deliver messages.
>>>>> (Enclosed is the syslog, and postconf -n output)
>>>>>
>>>>> The system is set up to:
>>>>>   Receive inbound SMTP port 25 connections.        Working
>>>>>   Escalate to a TLS session                            Working
>>>>>   Auth against LDAP using saslauth                    Working
>>>>>   Accept the message into the queue                    Working
>>>>>   Route all emails to a relay host                     Not working
>>>>>
>>>>> I captured a sniffer trace using tcpdump with no filters and never
>>>>> see an outbound connection.
>>>>>
>>>>>
>>>
>>> Turn off all the unnecessary verbose logging, and set tls_loglevel to
>>> 0 or 1.  Then see:
>>> http://www.postfix.org/DEBUG_README.html#logging
>>>
>>> This will lead you to:
>>>
>>> May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to
>>> transport smtp: Connection refused
>>>
>>> which in turn causes:
>>>
>>> May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE:
>>> to=<[hidden email]>, relay=none, delay=0.2, delays=0.13/0.01/0/0.06,
>>> dsn=4.3.0, status=deferred (mail transport unavailable)
>>>
>>> Run "postfix check" to check file permissions.  Turn off AppArmour or
>>> SELinux if running.
>>>
>>>
>>>
>>> --
>>> Noel Jones
>>
>

 >> I have run the check, turned off all other verbose logging
and still
 >> only see:
 >> ...
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0160 fa 57 7f
6c 6f b8 9a
 >> 40|cc c9 21 bb 55 30 3d d0  .W.lo..@ ..!.U0=.
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0170 1c da 25
56 3b 04 83
 >> 82|6c e7 27 79 bd bf 34 b9  ..%V;... l.'y..4.
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Read 351 chars:
Message-Id:
 >> <247795E1-1BAF-4074-8525-E9F
 >> May  7 16:43:34 wwwin postfix/cleanup[9879]: 1ADBC87BAE:
 >> message-id=<[hidden email]>
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Write 36 chars:
250 2.0.0
 >> Ok: queued as 1ADBC87BAE??
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: write to 08091A80
 >> [080A39A0] (69 bytes => 69 (0x45))
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0000 17 03 01
00 40 c5 76
 >> 88|4c c3 59 e0 9d 4b 94 ae  ....@.v. L.Y..K..
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0010 a5 a5 78
c1 5f 35 3d
 >> d0|6c a8 fa a6 96 ec fa f7  ..x._5=. l.......
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0020 1e 1e fb
06 46 5a 9e
 >> df|c9 72 1b e0 10 ba 15 ca  ....FZ.. .r......
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0030 a7 29 22
50 87 34 ea
 >> 6a|c2 57 85 5e 73 eb 90 aa  .)"P.4.j .W.^s...
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0040 ab 2b 9a 9b
 >> aa                                   .+...
 >> May  7 16:43:34 wwwin postfix/smtpd[9866]: read from 08091A80
 >> [0809B190] (5 bytes => -1 (0xFFFFFFFF))
 >> May  7 16:43:34 wwwin postfix/qmgr[9846]: 1ADBC87BAE:
 >> from=<[hidden email]>, size=622, nrcpt=1 (queue active)
 >> May  7 16:43:34 wwwin postfix/qmgr[9846]: warning: connect to
 >> transport smtp: Connection refused
 >> May  7 16:43:34 wwwin postfix/error[9882]: 1ADBC87BAE:
 >> to=<[hidden email]>, relay=none, delay=0.08,
 >> delays=0.06/0.01/0/0.01, dsn=4.3.0, status=deferred (mail
transport
 >> unavailable)
 >>
 >> I do not see an attempted SMTP connection outbound, so I
do not know
 >> why its thinking it gets refused.
 >> All processes are not chrooting, so I dont think its a
permissions thing.
 >>

Please don't top post.  You are still using verbose logging,
turn it off.

The connection refused refers to postfix qmgr process trying
to connect to the postfix smtp process.  No external
connection is ever attempted.  This looks like a permissions
issue.

Run "postfix check" to check file permissions.  Turn off
AppArmour or SELinux if running.

--
Noel Jones
Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Jeremy Laurenson

On May 7, 2008, at 5:08 PM, Noel Jones wrote:

> Jeremy Laurenson wrote:
>
>>>
>>> On May 7, 2008, at 4:13 PM, Noel Jones wrote:
>>>
>>>> Jeremy Laurenson wrote:
>>>>>>
>>>>>> Good monring/noon/evening , all
>>>>>>
>>>>>>
>>>>>> I have an issue getting my postfix system to deliver messages.  
>>>>>> (Enclosed is the syslog, and postconf -n output)
>>>>>>
>>>>>> The system is set up to:
>>>>>>  Receive inbound SMTP port 25 connections.        Working
>>>>>>  Escalate to a TLS session                            Working
>>>>>>  Auth against LDAP using saslauth                    Working
>>>>>>  Accept the message into the queue                    Working
>>>>>>  Route all emails to a relay host                     Not working
>>>>>>
>>>>>> I captured a sniffer trace using tcpdump with no filters and  
>>>>>> never see an outbound connection.
>>>>>>
>>>>>>
>>>>
>>>> Turn off all the unnecessary verbose logging, and set  
>>>> tls_loglevel to 0 or 1.  Then see:
>>>> http://www.postfix.org/DEBUG_README.html#logging
>>>>
>>>> This will lead you to:
>>>>
>>>> May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to  
>>>> transport smtp: Connection refused
>>>>
>>>> which in turn causes:
>>>>
>>>> May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE: to=<[hidden email]
>>>> >, relay=none, delay=0.2, delays=0.13/0.01/0/0.06, dsn=4.3.0,  
>>>> status=deferred (mail transport unavailable)
>>>>
>>>> Run "postfix check" to check file permissions.  Turn off  
>>>> AppArmour or SELinux if running.
>>>>
>>>>
>>>>
>>>> --
>>>> Noel Jones
>>>
>
> >> I have run the check, turned off all other verbose logging and  
> still
> >> only see:
> >> ...
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0160 fa 57 7f 6c 6f b8  
> 9a
> >> 40|cc c9 21 bb 55 30 3d d0  .W.lo..@ ..!.U0=.
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0170 1c da 25 56 3b 04  
> 83
> >> 82|6c e7 27 79 bd bf 34 b9  ..%V;... l.'y..4.
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Read 351 chars:  
> Message-Id:
> >> <247795E1-1BAF-4074-8525-E9F
> >> May  7 16:43:34 wwwin postfix/cleanup[9879]: 1ADBC87BAE:
> >> message-id=<[hidden email]>
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Write 36 chars: 250  
> 2.0.0
> >> Ok: queued as 1ADBC87BAE??
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: write to 08091A80
> >> [080A39A0] (69 bytes => 69 (0x45))
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0000 17 03 01 00 40 c5  
> 76
> >> 88|4c c3 59 e0 9d 4b 94 ae  ....@.v. L.Y..K..
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0010 a5 a5 78 c1 5f 35  
> 3d
> >> d0|6c a8 fa a6 96 ec fa f7  ..x._5=. l.......
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0020 1e 1e fb 06 46 5a  
> 9e
> >> df|c9 72 1b e0 10 ba 15 ca  ....FZ.. .r......
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0030 a7 29 22 50 87 34  
> ea
> >> 6a|c2 57 85 5e 73 eb 90 aa  .)"P.4.j .W.^s...
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0040 ab 2b 9a 9b
> >> aa                                   .+...
> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: read from 08091A80
> >> [0809B190] (5 bytes => -1 (0xFFFFFFFF))
> >> May  7 16:43:34 wwwin postfix/qmgr[9846]: 1ADBC87BAE:
> >> from=<[hidden email]>, size=622, nrcpt=1 (queue active)
> >> May  7 16:43:34 wwwin postfix/qmgr[9846]: warning: connect to
> >> transport smtp: Connection refused
> >> May  7 16:43:34 wwwin postfix/error[9882]: 1ADBC87BAE:
> >> to=<[hidden email]>, relay=none, delay=0.08,
> >> delays=0.06/0.01/0/0.01, dsn=4.3.0, status=deferred (mail transport
> >> unavailable)
> >>
> >> I do not see an attempted SMTP connection outbound, so I do not  
> know
> >> why its thinking it gets refused.
> >> All processes are not chrooting, so I dont think its a  
> permissions thing.
> >>
>
> Please don't top post.  You are still using verbose logging, turn it  
> off.
>
> The connection refused refers to postfix qmgr process trying to  
> connect to the postfix smtp process.  No external connection is ever  
> attempted.  This looks like a permissions issue.
>
> Run "postfix check" to check file permissions.  Turn off AppArmour  
> or SELinux if running.
>
> --
> Noel Jones

I have run postfix check with no results whatsoever:

root@wwwin:/home/jeremy# postfix check
root@wwwin:/home/jeremy#

SELinux and AppArmour are nit installed.

May  7 17:16:01 10.10.10.1 387593: 387600: May  7 21:16:00.310 UTC:  
%SEC-6-IPACCESSLOGP: list 101 denied udp 24.64.159.26(34591) ->  
66.161.216.62(1028), 1 packet
May  7 17:16:01 wwwin /USR/SBIN/CRON[14193]: (root) CMD (sh /var/www/
scripts/network >> /var/log/laurenson.log 2>&1)
May  7 17:16:02 wwwin postfix/qmgr[14010]: 1ADBC87BAE: from=<[hidden email]
 >, size=622, nrcpt=1 (queue active)
May  7 17:16:02 wwwin postfix/qmgr[14010]: warning: connect to  
transport smtp: Connection refused
May  7 17:16:02 wwwin postfix/qmgr[14010]: 0AEBA87D89: from=<[hidden email]
 >, size=4355, nrcpt=1 (queue active)
May  7 17:16:02 wwwin postfix/error[14184]: 0AEBA87D89: to=<[hidden email]
 >, relay=none, delay=1525, delays=1525/0/0/0.02, dsn=4.3.0,  
status=deferred (mail transport unavailable)
May  7 17:16:02 wwwin postfix/error[14185]: 1ADBC87BAE: to=<[hidden email]
 >, relay=none, delay=1948, delays=1948/0.01/0/0.02, dsn=4.3.0,  
status=deferred (mail transport unavailable)


Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Jeremy Laurenson

On May 7, 2008, at 5:17 PM, Jeremy Laurenson wrote:

>
> On May 7, 2008, at 5:08 PM, Noel Jones wrote:
>
>> Jeremy Laurenson wrote:
>>
>>>>
>>>> On May 7, 2008, at 4:13 PM, Noel Jones wrote:
>>>>
>>>>> Jeremy Laurenson wrote:
>>>>>>>
>>>>>>> Good monring/noon/evening , all
>>>>>>>
>>>>>>>
>>>>>>> I have an issue getting my postfix system to deliver messages.  
>>>>>>> (Enclosed is the syslog, and postconf -n output)
>>>>>>>
>>>>>>> The system is set up to:
>>>>>>> Receive inbound SMTP port 25 connections.        Working
>>>>>>> Escalate to a TLS session                            Working
>>>>>>> Auth against LDAP using saslauth                    Working
>>>>>>> Accept the message into the queue                    Working
>>>>>>> Route all emails to a relay host                     Not working
>>>>>>>
>>>>>>> I captured a sniffer trace using tcpdump with no filters and  
>>>>>>> never see an outbound connection.
>>>>>>>
>>>>>>>
>>>>>
>>>>> Turn off all the unnecessary verbose logging, and set  
>>>>> tls_loglevel to 0 or 1.  Then see:
>>>>> http://www.postfix.org/DEBUG_README.html#logging
>>>>>
>>>>> This will lead you to:
>>>>>
>>>>> May  7 09:32:57 wwwin postfix/qmgr[20819]: warning: connect to  
>>>>> transport smtp: Connection refused
>>>>>
>>>>> which in turn causes:
>>>>>
>>>>> May  7 09:32:57 wwwin postfix/error[21378]: 9A02887BAE: to=<[hidden email]
>>>>> >, relay=none, delay=0.2, delays=0.13/0.01/0/0.06, dsn=4.3.0,  
>>>>> status=deferred (mail transport unavailable)
>>>>>
>>>>> Run "postfix check" to check file permissions.  Turn off  
>>>>> AppArmour or SELinux if running.
>>>>>
>>>>>
>>>>>
>>>>> --
>>>>> Noel Jones
>>>>
>>
>> >> I have run the check, turned off all other verbose logging and  
>> still
>> >> only see:
>> >> ...
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0160 fa 57 7f 6c 6f  
>> b8 9a
>> >> 40|cc c9 21 bb 55 30 3d d0  .W.lo..@ ..!.U0=.
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0170 1c da 25 56 3b  
>> 04 83
>> >> 82|6c e7 27 79 bd bf 34 b9  ..%V;... l.'y..4.
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Read 351 chars:  
>> Message-Id:
>> >> <247795E1-1BAF-4074-8525-E9F
>> >> May  7 16:43:34 wwwin postfix/cleanup[9879]: 1ADBC87BAE:
>> >> message-id=<[hidden email]>
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: Write 36 chars: 250  
>> 2.0.0
>> >> Ok: queued as 1ADBC87BAE??
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: write to 08091A80
>> >> [080A39A0] (69 bytes => 69 (0x45))
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0000 17 03 01 00 40  
>> c5 76
>> >> 88|4c c3 59 e0 9d 4b 94 ae  ....@.v. L.Y..K..
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0010 a5 a5 78 c1 5f  
>> 35 3d
>> >> d0|6c a8 fa a6 96 ec fa f7  ..x._5=. l.......
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0020 1e 1e fb 06 46  
>> 5a 9e
>> >> df|c9 72 1b e0 10 ba 15 ca  ....FZ.. .r......
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0030 a7 29 22 50 87  
>> 34 ea
>> >> 6a|c2 57 85 5e 73 eb 90 aa  .)"P.4.j .W.^s...
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: 0040 ab 2b 9a 9b
>> >> aa                                   .+...
>> >> May  7 16:43:34 wwwin postfix/smtpd[9866]: read from 08091A80
>> >> [0809B190] (5 bytes => -1 (0xFFFFFFFF))
>> >> May  7 16:43:34 wwwin postfix/qmgr[9846]: 1ADBC87BAE:
>> >> from=<[hidden email]>, size=622, nrcpt=1 (queue active)
>> >> May  7 16:43:34 wwwin postfix/qmgr[9846]: warning: connect to
>> >> transport smtp: Connection refused
>> >> May  7 16:43:34 wwwin postfix/error[9882]: 1ADBC87BAE:
>> >> to=<[hidden email]>, relay=none, delay=0.08,
>> >> delays=0.06/0.01/0/0.01, dsn=4.3.0, status=deferred (mail  
>> transport
>> >> unavailable)
>> >>
>> >> I do not see an attempted SMTP connection outbound, so I do not  
>> know
>> >> why its thinking it gets refused.
>> >> All processes are not chrooting, so I dont think its a  
>> permissions thing.
>> >>
>>
>> Please don't top post.  You are still using verbose logging, turn  
>> it off.
>>
>> The connection refused refers to postfix qmgr process trying to  
>> connect to the postfix smtp process.  No external connection is  
>> ever attempted.  This looks like a permissions issue.
>>
>> Run "postfix check" to check file permissions.  Turn off AppArmour  
>> or SELinux if running.
>>
>> --
>> Noel Jones
>
> I have run postfix check with no results whatsoever:
>
> root@wwwin:/home/jeremy# postfix check
> root@wwwin:/home/jeremy#
>
> SELinux and AppArmour are nit installed.
>
> May  7 17:16:01 10.10.10.1 387593: 387600: May  7 21:16:00.310 UTC:  
> %SEC-6-IPACCESSLOGP: list 101 denied udp 24.64.159.26(34591) ->  
> 66.161.216.62(1028), 1 packet
> May  7 17:16:01 wwwin /USR/SBIN/CRON[14193]: (root) CMD (sh /var/www/
> scripts/network >> /var/log/laurenson.log 2>&1)
> May  7 17:16:02 wwwin postfix/qmgr[14010]: 1ADBC87BAE: from=<[hidden email]
> >, size=622, nrcpt=1 (queue active)
> May  7 17:16:02 wwwin postfix/qmgr[14010]: warning: connect to  
> transport smtp: Connection refused
> May  7 17:16:02 wwwin postfix/qmgr[14010]: 0AEBA87D89: from=<[hidden email]
> >, size=4355, nrcpt=1 (queue active)
> May  7 17:16:02 wwwin postfix/error[14184]: 0AEBA87D89: to=<[hidden email]
> >, relay=none, delay=1525, delays=1525/0/0/0.02, dsn=4.3.0,  
> status=deferred (mail transport unavailable)
> May  7 17:16:02 wwwin postfix/error[14185]: 1ADBC87BAE: to=<[hidden email]
> >, relay=none, delay=1948, delays=1948/0.01/0/0.02, dsn=4.3.0,  
> status=deferred (mail transport unavailable)
>
>

Noel, thanks for the pointer - problem solved. The smtp unix daemon  
was not enabled properly.
Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Ralf Hildebrandt
In reply to this post by Jeremy Laurenson
* Jeremy Laurenson <[hidden email]>:

> May  7 17:16:02 wwwin postfix/qmgr[14010]: warning: connect to transport smtp: Connection refused

master.cf has a "smtp" line?
mine:
smtp      unix  -       -       -       -       -       smtp

is any smtp process running at all?

# ps auxwww|grep smtp |grep -v smtpd
postfix   2071  0.0  0.0   5876  3012 ?        S    22:32   0:00 smtp-t unix -u -c
postfix  13841  0.0  0.0   5876  3004 ?        S    23:15   0:00 smtp -n relay -t unix -u -c -o smtp_use_tls no
postfix  14177  0.0  0.0   5876  3008 ?        S    23:16   0:00 smtp -t unix -u -c
postfix  14258  0.0  0.0   5876  3008 ?        S    23:17   0:00 smtp -t unix -u -c

--
Ralf Hildebrandt ([hidden email])          [hidden email]
Postfix - Einrichtung, Betrieb und Wartung       Tel. +49 (0)30-450 570-155
http://www.arschkrebs.de
I am your darkest, gibbering, omnipresent sweaty, shivering nightmare,
made real. There. Feel better? Now you know how evil I am, we can get
down to business.
Reply | Threaded
Open this post in threaded view
|

Re: Outbound postfix routing issue

Jeremy Laurenson
That was it... just found it myself - thanks!

On May 7, 2008, at 5:22 PM, Ralf Hildebrandt wrote:

> * Jeremy Laurenson <[hidden email]>:
>
>> May  7 17:16:02 wwwin postfix/qmgr[14010]: warning: connect to  
>> transport smtp: Connection refused
>
> master.cf has a "smtp" line?
> mine:
> smtp      unix  -       -       -       -       -       smtp
>
> is any smtp process running at all?
>
> # ps auxwww|grep smtp |grep -v smtpd
> postfix   2071  0.0  0.0   5876  3012 ?        S    22:32   0:00  
> smtp-t unix -u -c
> postfix  13841  0.0  0.0   5876  3004 ?        S    23:15   0:00  
> smtp -n relay -t unix -u -c -o smtp_use_tls no
> postfix  14177  0.0  0.0   5876  3008 ?        S    23:16   0:00  
> smtp -t unix -u -c
> postfix  14258  0.0  0.0   5876  3008 ?        S    23:17   0:00  
> smtp -t unix -u -c
>
> --
> Ralf Hildebrandt ([hidden email])          [hidden email]
> Postfix - Einrichtung, Betrieb und Wartung       Tel. +49 (0)30-450  
> 570-155
> http://www.arschkrebs.de
> I am your darkest, gibbering, omnipresent sweaty, shivering nightmare,
> made real. There. Feel better? Now you know how evil I am, we can get
> down to business.