LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Networking (https://www.linuxquestions.org/questions/linux-networking-3/)
-   -   Help! Cannot send mail anymore (Postfix) ! (https://www.linuxquestions.org/questions/linux-networking-3/help-cannot-send-mail-anymore-postfix-470847/)

theYinYeti 08-04-2006 03:32 PM

Help! Cannot send mail anymore (Postfix) !
 
My config is such:
RECEIVE: ISP(POP3) -> PopFile -> Fetchmail -> Postfix (@sedentaire.local) -> BincIMAP -> Mail client.
SEND: Mail client -> Postfix (@sedentaire.local) -> ISP(SMTP).

Mail worked. It still works for receiving but not for sending. Nothing changed, except maybe if postfix was part of a recent "security and bugfix update". That's where I am...

I'm ready to try ANYTHING. If you find something peculiar or bad in my config, just say so. Please.

Postfix' config is:
Code:

==> main.cf <==
readme_directory = /usr/share/doc/postfix-2.2.5/README_FILES
html_directory = /usr/share/doc/postfix-2.2.5/html
sendmail_path = /usr/sbin/sendmail.postfix
setgid_group = postdrop
command_directory = /usr/sbin
manpage_directory = /usr/share/man
daemon_directory = /usr/lib/postfix
newaliases_path = /usr/bin/newaliases
mailq_path = /usr/bin/mailq
queue_directory = /var/spool/postfix
mail_owner = postfix
mydestination = $myhostname, localhost, nomade, salon, sedentaire, localhost.$mydomain, $mydomain, nomade.$mydomain, salon.$mydomain
mynetworks = 192.168.1.0/30, 127.0.0.0/8
relayhost = smtp.ISP.fr
sender_canonical_maps = hash:/etc/postfix/canonical-sender
recipient_canonical_maps = hash:/etc/postfix/canonical-recipient
home_mailbox = Maildir/
disable_mime_input_processing = yes
header_checks = regexp:/etc/postfix/header_checks
delay_warning_time = 12h
disable_dns_lookup = yes
smtpd_banner = $myhostname ESMTP $mail_name ($mail_version) (Mandriva Linux)
unknown_local_recipient_reject_code = 450
smtp-filter_destination_concurrency_limit = 2
lmtp-filter_destination_concurrency_limit = 2
smtpd_sasl_path = /etc/postfix/sasl:/usr/lib/sasl2

==> canonical-sender <==
yves yves@ISP.fr
iris iris@ISP.fr
sharedmail yves@ISP.fr

==> canonical-recipient <==
yves yves@localhost
iris iris@localhost
yves@ISP.fr yves@localhost
iris@ISP.fr iris@localhost

==> header_checks <==
/^X-Text-Classification: shared/ REDIRECT sharedmail@localhost

It already happened once some time ago; it was my ISP not allowing mail relaying; they solved the issue at that time. However, that time, I had a report which said something like "please use your ISP's SMTP server". This time though, I have no report; a typical returned mail looks like this:
Code:

This is the Postfix program at host sedentaire.local.

I'm sorry [...] could not be delivered to [...] It's attached below.

For further assistance, please send mail to <postmaster>
If you do so, please include this problem report. [...]

        --- Delivery report unavailable ---

Reporting-MTA: dns; sedentaire.local
X-Postfix-Queue-ID: 2C20C26BE7
X-Postfix-Sender: rfc822; yves@ISP.fr
Arrival-Date: Tue, 25 Jul 2006 17:09:54 +0000 (UTC)

Subject: test mail
From:    yves@ISP.fr (Yves)
Date:    Tue, 25 Jul 2006 19:09:53 +0200
To:      me@somewhere-else.com

test mail.

On the other hand, this works alright:
Code:

# telnet smtp.ISP.fr 25
Trying xxx.xxx.xxx.xxx...
Connected to smtp.ISP.fr (xxx.xxx.xxx.xxx).
Escape character is '^]'.
220 realname.ISP.fr ESMTP Postfix
HELO sedentaire.local
250 realname.ISP.fr
MAIL FROM: yves@ISP.fr
250 Ok
RCPT TO: me@somewhere-else.com
250 Ok
DATA
354 End data with <CR><LF>.<CR><LF>
Subject: test telnet+HELO+MAIL+RCPT+DATA
Test 5
.
250 Ok: queued as 1BBA025602
QUIT
221 Bye
Connection closed by foreign host.

I got the mail!
So this time, I think it comes from me, my Postfix to be precise.

However, it worked before! Not knowing what to do, I looked at the output of "sendmail -q". Here it is:
Code:

/var/log# find . -type f | xargs tail -n 0 -f
==> ./mail/info <==
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 0C88A26BE0: from=<root@sedentaire.local>, size=425, nrcpt=1 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 17DF3FC3C: from=<yves@ISP.fr>, size=585, nrcpt=2 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 300B725464: from=<iris@ISP.fr>, size=32114, nrcpt=1 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: A7FF226C17: from=<yves@ISP.fr>, size=436, nrcpt=1 (queue active)

==> ./mail/errors <==
Aug  4 20:11:42 sedentaire postfix/smtp[5741]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5742]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5744]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5743]: fatal: unknown service: smtp/tcp

==> ./syslog <==
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 0C88A26BE0: from=<root@sedentaire.local>, size=425, nrcpt=1 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 17DF3FC3C: from=<yves@ISP.fr>, size=585, nrcpt=2 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: 300B725464: from=<iris@ISP.fr>, size=32114, nrcpt=1 (queue active)
Aug  4 18:11:42 sedentaire postfix/qmgr[5150]: A7FF226C17: from=<yves@ISP.fr>, size=436, nrcpt=1 (queue active)
Aug  4 20:11:42 sedentaire postfix/smtp[5741]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5742]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5744]: fatal: unknown service: smtp/tcp
Aug  4 20:11:42 sedentaire postfix/smtp[5743]: fatal: unknown service: smtp/tcp

==> ./mail/warnings <==
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5741 exit status 1
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: /usr/lib/postfix/smtp: bad command startup -- throttling
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5742 exit status 1
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5744 exit status 1
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5743 exit status 1

==> ./syslog <==
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5741 exit status 1
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: /usr/lib/postfix/smtp: bad command startup -- throttling
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5742 exit status 1
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5744 exit status 1
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: private/smtp socket: malformed response
Aug  4 18:11:43 sedentaire postfix/qmgr[5150]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  4 20:11:43 sedentaire postfix/master[5143]: warning: process /usr/lib/postfix/smtp pid 5743 exit status 1

An idea? I accept even seemingly stupid ideas; it may give me other ideas :)

Yves.

peter_robb 08-04-2006 04:33 PM

Your smtp service is refusing to start for some reason.

Change your master.cf to read..
Code:

smtp  unix  -  -    n  -  -  smtp -v
run postfix reload, and look at a message going out.

theYinYeti 08-05-2006 05:35 AM

Start of result:
Code:

# tail -n 0 -f /var/log/syslog
Aug  5 10:07:28 sedentaire postfix/qmgr[5879]: 0C88A26BE0: from=<root@sedentaire.local>, size=425, nrcpt=1 (queue active)
Aug  5 10:07:28 sedentaire postfix/qmgr[5879]: 17DF3FC3C: from=<yves@ISP.fr>, size=585, nrcpt=2 (queue active)
Aug  5 10:07:28 sedentaire postfix/qmgr[5879]: 300B725464: from=<iris@ISP.fr>, size=32114, nrcpt=1 (queue active)
Aug  5 10:07:28 sedentaire postfix/qmgr[5879]: A7FF226C17: from=<yves@ISP.fr>, size=436, nrcpt=1 (queue active)
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  mail
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  ipv4
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: name_mask: ipv4
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  Postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  postdrop
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $myhostname, localhost, nomade, salon, sedentaire, localhost.$mydomain, $mydomain, nomade.$mydomain, salon.$mydomain -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $myhostname -> sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  smtp.ISP.fr
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  /usr/lib/postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  /usr/sbin
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  /var/spool/postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  pid
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  all

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  double-bounce
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  nobody
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  hash:/etc/postfix/aliases
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  20050719
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  2.2.5
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  hash
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  deferred, defer

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $mydestination -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $relay_domains -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  TZ MAIL_CONFIG
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  subnet

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  +=
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  -=+
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  debug_peer_list,fast_flush_domains,mynetworks,permit_mx_backup_networks,qmqpd_authorized_clients,relay_domains,smtpd_access_maps

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  bounce
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  cleanup
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  defer
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  pickup
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  qmgr
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  rewrite
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  showq
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  error
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  flush
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  verify
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  trace


Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand ${config_directory}/prng_exch -> /etc/postfix/prng_exch

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $smtpd_tls_cert_file ->

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $smtpd_tls_dcert_file ->

Aug  5 12:07:49 sedentaire last message repeated 6 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $smtp_tls_cert_file ->

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $smtp_tls_dcert_file ->

Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  yes
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  1000s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  1000s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  18000s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  18000s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  192.168.1.0/30, 127.0.0.0/8
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: inet_addr_local: configured 2 IPv4 addresses
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  30s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  30s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire last message repeated 7 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  120s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  120s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  180s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  180s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  600s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  600s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  20s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  20s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  2s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  2s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  resource, software


Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  postmaster

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  noplaintext, noanonymous

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $var_smtp_sasl_opts ->

Aug  5 12:07:49 sedentaire last message repeated 2 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: expand $myhostname -> sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  dns

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  scache

Aug  5 12:07:49 sedentaire last message repeated 2 times
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: dict_eval: const  canonical, virtual

Aug  5 12:07:49 sedentaire postfix/smtp[5938]: process generation: 25 (25)
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: match_string: fast_flush_domains ~? debug_peer_list
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: match_string: fast_flush_domains ~? fast_flush_domains
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: name_mask: canonical
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: name_mask: virtual
Aug  5 12:07:49 sedentaire postfix/smtp[5938]: name_mask: dns
Aug  5 10:07:49 sedentaire postfix/smtp[5938]: host name lookup methods: dns
Aug  5 10:07:49 sedentaire postfix/smtp[5938]: connection established
Aug  5 10:07:49 sedentaire postfix/smtp[5938]: master_notify: status 0
Aug  5 10:07:49 sedentaire postfix/smtp[5938]: deliver_request_initial: send initial status
Aug  5 10:07:49 sedentaire postfix/smtp[5938]: send attr status = 0
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  mail
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  ipv4
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: name_mask: ipv4
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  Postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  postdrop
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $myhostname, localhost, nomade, salon, sedentaire, localhost.$mydomain, $mydomain, nomade.$mydomain, salon.$mydomain -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $myhostname -> sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  smtp.ISP.fr
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  /usr/lib/postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  /usr/sbin
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  /var/spool/postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  pid
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  all

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  double-bounce
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  nobody
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  hash:/etc/postfix/aliases
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  20050719
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  2.2.5
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  hash
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  deferred, defer

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $mydestination -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $relay_domains -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  TZ MAIL_CONFIG
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  subnet

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  +=
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  -=+
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  debug_peer_list,fast_flush_domains,mynetworks,permit_mx_backup_networks,qmqpd_authorized_clients,relay_domains,smtpd_access_maps

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  bounce
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  cleanup
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  defer
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  pickup
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  qmgr
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  rewrite
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  showq
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  error
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  flush
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  verify
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  trace


Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand ${config_directory}/prng_exch -> /etc/postfix/prng_exch

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $smtpd_tls_cert_file ->

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $smtpd_tls_dcert_file ->

Aug  5 12:07:49 sedentaire last message repeated 6 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $smtp_tls_cert_file ->

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $smtp_tls_dcert_file ->

Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  yes
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  100s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  1000s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  1000s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  3600s
Aug  5 12:07:49 sedentaire last message repeated 3 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  18000s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  18000s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  1s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  192.168.1.0/30, 127.0.0.0/8
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: inet_addr_local: configured 2 IPv4 addresses
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  30s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  30s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire last message repeated 7 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  120s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  120s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  180s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  180s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  600s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  600s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  20s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  20s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  500s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  10s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  2s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  2s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  300s
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  resource, software


Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  postmaster

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  noplaintext, noanonymous

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $var_smtp_sasl_opts ->

Aug  5 12:07:49 sedentaire last message repeated 2 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: expand $myhostname -> sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  dns

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  scache

Aug  5 12:07:49 sedentaire last message repeated 2 times
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: dict_eval: const  canonical, virtual

Aug  5 12:07:49 sedentaire postfix/smtp[5939]: process generation: 26 (26)
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: match_string: fast_flush_domains ~? debug_peer_list
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: match_string: fast_flush_domains ~? fast_flush_domains
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: name_mask: canonical
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: name_mask: virtual
Aug  5 12:07:49 sedentaire postfix/smtp[5939]: name_mask: dns
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: host name lookup methods: dns
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: connection established
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: master_notify: status 0
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: deliver_request_initial: send initial status
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: send attr status = 0
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  mail
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  ipv4
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: name_mask: ipv4
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: flags
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  Postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  postdrop
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: expand $myhostname, localhost, nomade, salon, sedentaire, localhost.$mydomain, $mydomain, nomade.$mydomain, salon.$mydomain -> sedentaire.local, localhost, nomade, salon, sedentaire, localhost.sedentaire.local, sedentaire.local, nomade.sedentaire.local, salon.sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: expand $myhostname -> sedentaire.local
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  smtp.ISP.fr
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  /usr/lib/postfix
Aug  5 12:07:49 sedentaire postfix/smtp[5940]: dict_eval: const  /usr/sbin

read next post...

theYinYeti 08-05-2006 05:40 AM

End of result:
Code:

Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: flags
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 3
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: queue_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: queue_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: active
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: queue_id
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: queue_id
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 17DF3FC3C
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 340
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: size
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: size
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 585
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: nexthop
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: nexthop
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: smtp.ISP.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: encoding
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: encoding
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: sender
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: sender
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: yves@ISP.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: errors-to
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: errors-to
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: yves@ISP.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: return-receipt
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: return-receipt
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: time
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: time
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 1154547791
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: client_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: client_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: client_address
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: client_address
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 127.0.0.1
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: protocol_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: protocol_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: ESMTP
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: helo_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: helo_name
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: [127.0.0.1]
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: sasl_method
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: sasl_method
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: sasl_username
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: sasl_username
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: sasl_sender
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: sasl_sender
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: (end)
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: rewrite_context
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: rewrite_context
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: local
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 276
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: original_recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: original_recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: me@somewhere-else.com
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: me@somewhere-else.com
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 319
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: original_recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: original_recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: me@at-work.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: recipient
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: me@at-work.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp socket: wanted attribute: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute name: offset
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: input attribute value: 0
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: deliver_request_get: file active/17DF3FC3C
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: deliver_message: from yves@ISP.fr
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: smtp_parse_destination: smtp.ISP.fr smtp
Aug  5 10:07:49 sedentaire postfix/smtp[5939]: fatal: unknown service: smtp/tcp
THEN IT SEEMS TO DO ALL THAT TWICE AGAIN, THEN:
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: private/smtp socket: malformed response
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  5 12:07:50 sedentaire postfix/master[4818]: warning: process /usr/lib/postfix/smtp pid 5939 exit status 1
Aug  5 12:07:50 sedentaire postfix/master[4818]: warning: /usr/lib/postfix/smtp: bad command startup -- throttling
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: private/smtp socket: malformed response
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  5 12:07:50 sedentaire postfix/master[4818]: warning: process /usr/lib/postfix/smtp pid 5940 exit status 1
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: private/smtp socket: malformed response
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  5 12:07:50 sedentaire postfix/master[4818]: warning: process /usr/lib/postfix/smtp pid 5941 exit status 1
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: premature end-of-input on private/smtp socket while reading input attribute name
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: private/smtp socket: malformed response
Aug  5 10:07:50 sedentaire postfix/qmgr[5879]: warning: transport smtp failure -- see a previous warning/fatal/panic logfile record for the problem description
Aug  5 12:07:50 sedentaire postfix/master[4818]: warning: process /usr/lib/postfix/smtp pid 5938 exit status 1

Empty lines are those ending with "dict_eval: const " (nothing after). I had trouble with the 25kB limit.

Here is the now-current master.cf, minus comments:
Code:

smtp    inet    n      -      y      -      -      smtpd
pickup  fifo    n      -      y      60      1      pickup
  -o content_filter=
  -o receive_override_options=
cleanup unix    n      -      y      -      0      cleanup
qmgr    fifo    n      -      y      300    1      qmgr
tlsmgr  unix    -      -      y      1000?  1      tlsmgr
rewrite unix    -      -      y      -      -      trivial-rewrite
bounce  unix    -      -      y      -      0      bounce
defer  unix    -      -      y      -      0      bounce
trace  unix    -      -      y      -      0      bounce
verify  unix    -      -      y      -      1      verify
flush  unix    n      -      y      1000?  0      flush
proxymap  unix  -      -      n      -      -      proxymap
smtp    unix    -      -      y      -      -      smtp -v
relay  unix    -      -      y      -      -      smtp
        -o fallback_relay=
showq  unix    n      -      y      -      -      showq
error  unix    -      -      y      -      -      error
discard unix    -      -      y      -      -      discard
local    unix  -      n      n      -      -      local
virtual  unix  -      n      n      -      -      virtual
lmtp    unix    -      -      y      -      -      lmtp
anvil  unix    -      -      y      -      1      anvil
scache  unix    -      -      y      -      1      scache
maildrop  unix  -      n      n      -      -      pipe
  flags=DRhu user=nobody argv=/usr/bin/maildrop -d ${recipient}
cyrus-deliver    unix  -      n      n      -      -      pipe
  user=cyrus argv=/usr/lib/cyrus-imapd/deliver -e -r ${sender} -m ${extension} ${user}
cyrus    unix  -      n      n      -      -      lmtp
  -o lmtp_cache_connection=yes
cyrus-chroot    unix  -      -      y      -      -      lmtp
  -o lmtp_cache_connection=yes
cyrus-inet      unix    -      -      y      -      -      lmtp
  -o lmtp_cache_connection=yes
  -o lmtp_sasl_auth_enable=yes
  -o lmtp_sasl_password_maps=hash:/etc/postfix/cyrus_lmtp_sasl_pass
  -o lmtp_sasl_security_options=noanonymous
uucp      unix  -      n      n      -      -      pipe
  flags=Fqhu user=uucp argv=uux -r -n -z -a$sender - $nexthop!rmail ($recipient)
127.0.0.1:10026 inet    n      -      y      -      -      smtpd
  -o content_filter=
  -o smtpd_restriction_classes=
  -o smtpd_client_restrictions=
  -o smtpd_helo_restrictions=
  -o smtpd_sender_restrictions=
  -o smtpd_recipient_restrictions=permit_mynetworks,reject
  -o mynetworks=127.0.0.0/8
  -o smtpd_authorized_xforward_hosts=127.0.0.0/8
  -o strict_rfc821_envelopes=yes
  -o receive_override_options=no_unknown_recipient_checks,no_header_body_checks
  -o smtpd_client_connection_limit_exceptions=127.0.0.0/8
lmtp-filter    unix    -      -      y      -      -      lmtp
  -o lmtp_data_done_timeout=1200
  -o lmtp_send_xforward_command=yes
smtp-filter    unix    -      -      y      -      -      smtp
  -o smtp_data_done_timeout=1200
  -o smtp_send_xforward_command=yes

Yves.

peter_robb 08-05-2006 05:43 PM

Check your /etc/services file.

It should have a line..
Code:

smtp        25/tcp      mail    #Simple Mail Transfer

theYinYeti 08-06-2006 04:59 AM

Yes, it does:
Code:

[yves@sedentaire ~]$ grep -i smtp /etc/services
smtp            25/tcp          mail
smtp            25/udp          mail
smtps          465/tcp                        # SMTP over SSL (TLS)

I had already checked actually, but nonetheless, thanks for the suggestion. In fact, the above result makes me think: I may try and use smtps... maybe my ISP switched to smtsp for some reason; do you think this is possible?

Yves.

billymayday 08-06-2006 05:25 AM

I don't know Mandriva, but I had the world's quickest look at their site. Looks like Mandriva may use SELinux. Do you have SELinux enabled?

If so, have a look at you system log (in FC5 /var/log/messages) and see if there are SELinux blocks. Try dissabling, and see if mail works.

theYinYeti 08-06-2006 05:31 AM

billymayday, I thank you for having a look :)
But no, Mandriva does not use SELinux, at least not in the 2006-Download-Edition that I use.

Yves.

theYinYeti 08-06-2006 05:50 AM

Reviewing all my config, I had an idea: try and see if local Postfix did work. And YES, it does!
Code:

[root@sedentaire ~]# mail yves
Subject: local test
local test 1
.
EOT
[root@sedentaire ~]# mail yves@ISP.fr
Subject: sort of local test
local test 2
.
EOT

I got both email! The second one is actually local due to the rule defined in "canonical-recipient":
Code:

yves@ISP.fr yves@localhost
Knowing that local mail works, do you think I can assume that the problem comes from the ISP side?

Yves.

billymayday 08-06-2006 07:11 AM

I don't run postfix chrooted, but have you tried running smtp un-chrooted (you should be able to just change the chroot setting int master.cf for the service)?

peter_robb 08-07-2006 07:03 AM

You need to add several files to the chroot directory..
/etc/services
/etc/resolv.conf
/etc/hosts
/etc/nsswitch

Please re-read the Postfix chroot setup docs!

theYinYeti 08-07-2006 01:27 PM

Thank you both! A quick google search for "(chroot OR chrooted) postfix" directed me at the "/usr/sbin/postfix-chroot.sh" script.

The issue is now solved!

My chrooted environment was somehow half-set only! But "/usr/sbin/postfix-chroot.sh check" did not detect it because it works the bad way: it checks that files being there are OK. My files being there in the chrooted environment were OK, but most were absent and "check" did not report anything about those. I discovered that by reading the script.

So I ran "/usr/sbin/postfix-chroot.sh enable" and all is fine again, or so it seems.

I wonder what happened... It worked before... And I remember some (long) time ago having updated the chrooted "hosts" file, as I had updated the normal file too. The chrooted /etc disappeared somehow, though. postfix-chroot.sh cured it all. My mailq is empty now, at last.

Yves.


All times are GMT -5. The time now is 09:02 AM.