Go Premium for a chance to win a PS4. Enter to Win

x
?
Solved

Postfix timed out with word and excel attachments

Posted on 2010-11-15
22
Medium Priority
?
706 Views
Last Modified: 2013-12-16
I have a linux server (suse 11.2) with postfix on it. I receive all mail, but when a mail has an attachment .doc or .xls I don't receive the mail and after a while the sender gets a message the server has timed out. When I change the extension it does the same, when I send a docx it goes allright, when I zip the doc it also goes allright. In the log-file it says nothing about the timeout.
0
Comment
Question by:INBO
  • 13
  • 9
22 Comments
 
LVL 80

Expert Comment

by:arnold
ID: 34137365
You need to check the configuration of your postfix dealing with whether you have scanners in place that might be affected by the control characters within.  Check the log of your mail server when you directly send to it from the outside such a document to see what it records.

The timeout is
sending server connects to your postfix server:
exchanges parameters
sends data
your server at this point is checking the attachment, anti-virus,anti-spam, etc. but this process takes longer that the sending server is willing to wait.
The connection is severed/terminated due to a connection timeout.
0
 

Author Comment

by:INBO
ID: 34138548
When I send a mail I see two lines added to the mail log:

Nov 15 19:26:06 S-001 postfix/smtpd[9283]: 75443291BB: client=smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 19:26:06 S-001 postfix/cleanup[9288]: 75443291BB: message-id=<46383.92.254.96.114.1289845395.squirrel@webmail.xtramediaservices.nl>

Then everything stops.

I ensured all virusscanners are off.
0
 

Author Comment

by:INBO
ID: 34138912
When I put -v after cleanup in the master.cf I get this:

....
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from smtp20.mail.sp.isp-net.nl (smtp20.mail.sp.isp-net.nl [217.149.192.116])??by S-001.v-tol.nl (Postfix) with ESMTP id 251AA291BB??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 19:58:36 +0100 (CE'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from webmail.xtramediaservices.nl (webmail10.mail.sp.isp-net.nl [217.149.192.62])??by smtp20.mail.sp.isp-net.nl (Postfix) with ESMTP id 14A1FFF52??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 19:'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from 92.254.96.114?        (SquirrelMail authenticated user jfkvantol)?        by webmail.xtramediaservices.nl with HTTP;?        Mon, 15 Nov 2010 19:55:45 +0100 (MET)'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Message-ID: <53031.92.254.96.114.1289847345.squirrel@webmail.xtramediaservices.nl>'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: 251AA291BB: message-id=<53031.92.254.96.114.1289847345.squirrel@webmail.xtramediaservices.nl>
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Date: Mon, 15 Nov 2010 19:55:45 +0100 (MET)'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Subject: worddoc'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'From: jeroenvantol@xmsnet.nl'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'To: jeroen@v-tol.nl'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'User-Agent: SquirrelMail/1.5.1 [CVS]'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'MIME-Version: 1.0'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: multipart / mixed
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: boundary = ----=_20101115195545_45334
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: PUSH boundary ----=_20101115195545_45334
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: multipart/mixed;boundary="----=_20101115195545_45334"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: text / plain
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: text/plain; charset="iso-8859-1"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: 8bit  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: 8bit'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: application / msword
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: application/msword;?      name="Imkervereniging Nijmegen 100 jaar.doc"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: base64  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: base64'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Disposition: attachment;?      filename="Imkervereniging Nijmegen 100 jaar.doc"'
0
Technology Partners: We Want Your Opinion!

We value your feedback.

Take our survey and automatically be enter to win anyone of the following:
Yeti Cooler, Amazon eGift Card, and Movie eGift Card!

 
LVL 80

Expert Comment

by:arnold
ID: 34138972
I do  not see anything here that would prevent the processing of the message.

Compare the same process when a different type of attachment is included.
0
 

Author Comment

by:INBO
ID: 34138996
Here with a png:

.....
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Received: from smtp20.mail.sp.isp-net.nl (smtp20.mail.sp.isp-net.nl [217.149.192.116])??by S-001.v-tol.nl (Postfix) with ESMTP id CB0C129444??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 20:10:41 +0100 (CE'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Received: from webmail.xtramediaservices.nl (webmail10.mail.sp.isp-net.nl [217.149.192.62])??by smtp20.mail.sp.isp-net.nl (Postfix) with ESMTP id D1E42FF4B??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 20:'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Received: from 92.254.96.114?        (SquirrelMail authenticated user jfkvantol)?        by webmail.xtramediaservices.nl with HTTP;?        Mon, 15 Nov 2010 20:07:50 +0100 (MET)'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Message-ID: <43384.92.254.96.114.1289848070.squirrel@webmail.xtramediaservices.nl>'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: CB0C129444: message-id=<43384.92.254.96.114.1289848070.squirrel@webmail.xtramediaservices.nl>
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Date: Mon, 15 Nov 2010 20:07:50 +0100 (MET)'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Subject: worddoc'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'From: jeroenvantol@xmsnet.nl'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'To: jeroen@v-tol.nl'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'User-Agent: SquirrelMail/1.5.1 [CVS]'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'MIME-Version: 1.0'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: multipart / mixed
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: boundary = ----=_20101115200750_81733
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: PUSH boundary ----=_20101115200750_81733
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Type: multipart/mixed;boundary="----=_20101115200750_81733"'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: text / plain
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Type: text/plain; charset="iso-8859-1"'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: 8bit  
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Transfer-Encoding: 8bit'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: image / png
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Type: image/png; name="Screenshot-1.png"'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: header_token: base64  
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Transfer-Encoding: base64'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: cleanup_header_callback: 'Content-Disposition: attachment; filename="Screenshot-1.png"'
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: POP boundary ----=_20101115200750_81733
Nov 15 20:10:41 S-001 postfix/cleanup[10383]: extracted envelope E
Nov 15 20:10:42 S-001 postfix/cleanup[10383]: cleanup_flush: status 0
Nov 15 20:10:42 S-001 postfix/cleanup[10383]: send attr status = 0
Nov 15 20:10:42 S-001 postfix/cleanup[10383]: send attr reason =
Nov 15 20:10:42 S-001 postfix/qmgr[10242]: CB0C129444: from=<jeroenvantol@xmsnet.nl>, size=189799, nrcpt=1 (queue active)
Nov 15 20:10:42 S-001 postfix/cleanup[10383]: master_notify: status 1
Nov 15 20:10:42 S-001 postfix/cleanup[10383]: connection closed
Nov 15 20:10:42 S-001 postfix/smtpd[10382]: disconnect from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:10:42 S-001 postfix/lmtp[10452]: CB0C129444: to=<jeroen@v-tol.nl>, relay=S-001.v-tol.nl[/var/lib/imap/socket/lmtp], delay=0.38, delays=0.21/0.01/0.02/0.15, dsn=2.1.5, status=sent (250 2.1.5 Ok)
Nov 15 20:10:42 S-001 postfix/qmgr[10242]: CB0C129444: removed
Nov 15 20:11:08 S-001 postfix/cleanup[10381]: idle timeout -- exiting
Nov 15 20:11:08 S-001 postfix/cleanup[10383]: rewrite stream disconnect
Nov 15 20:12:22 S-001 postfix/cleanup[10383]: idle timeout -- exiting
0
 
LVL 80

Expert Comment

by:arnold
ID: 34139134
Which side am I looking at is this the processing on your server or from the sending server?
Are you using IMAP to send the message out? i.e. an Imap Outbox?
0
 

Author Comment

by:INBO
ID: 34139368
It is the processing of my server (destination server), I think the other site (sending server) is also imap, it is the server of my ISP who offers squirrelmail.
0
 
LVL 80

Expert Comment

by:arnold
ID: 34142149
IMAP is not a sending mail protocol, it is a retrieving/access to mailbox type of protocol.  It does not really matter what they use to send the issue deals with what happens to the message once it hits your server.  The cleanup process is the last thing. There is the smtpd that handles the incoming, then there is the queue that processes the message. The cleanup does its thing and bails when not needed which the idle timeout indicates.

nothing in the long has the data from the time the sending server connected to your system through the end.  There are different parameters that tie the log entries to a step by step even though they have multiple separate process that handle each step..
0
 

Author Comment

by:INBO
ID: 34142550
Do you mean the steps before the ones I placed above?
0
 
LVL 80

Expert Comment

by:arnold
ID: 34145556
Yes, you need to see the complete flow from the connection being made from the remote server. The message either being accepted on your end or it is being passed to an internal process at some point your side may reflect that the remote side has closed the connection which then leads to the discarding of the message on your side.
i.e.
Connection from x.x.x.x
message from user@domain to your emailaddress
processing message for anti-virus/anti-spam, anti-macro because the message has the .doc etc.
remote server disconnect, calling cleanup process.
0
 

Author Comment

by:INBO
ID: 34150135
Oke, here it comes, it is the output of the same mail as above which went wrong:

Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  mail
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  all
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: all
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  S-001.v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  Postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand ${multi_instance_name:postfix}${multi_instance_name?$multi_instance_name} -> postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  maildrop
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  v-tol.nl, bijentuin.nl, inye.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $myhostname -> S-001.v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  /usr/lib/postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  /var/lib/postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  /usr/sbin
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  /var/spool/postfix
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  pid
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  all
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  double-bounce
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  nobody
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  hash:/etc/aliases
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  20090519
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  2.6.1
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  hash
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  deferred, defer
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $mydestination, hash:/etc/postfix/relay -> v-tol.nl, bijentuin.nl, inye.nl, hash:/etc/postfix/relay
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $relay_domains -> v-tol.nl, bijentuin.nl, inye.nl, hash:/etc/postfix/relay
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  TZ MAIL_CONFIG LANG
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY LANG=C
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  subnet
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  +=
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  -=+
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  debug_peer_list,fast_flush_domains,mynetworks,permit_mx_backup_networks,qmqpd_authorized_clients,relay_domains,smtpd_access_maps
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  bounce
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  cleanup
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  defer
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  pickup
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  qmgr
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  rewrite
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  showq
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  error
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  flush
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  verify
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  trace
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  proxymap
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  proxywrite
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  2
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  no
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  no
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  no
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  100s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  100s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  100s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  100s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  3600s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  3600s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  5s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  5s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1000s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1000s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  10s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  10s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  500s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  500s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  18000s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  18000s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: subnet
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: inet_addr_local: configured 4 IPv4 addresses
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: inet_addr_local: configured 3 IPv6 addresses
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: 127.0.0.0/8: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: 127.0.0.0/8: 1
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: 192.168.0.0/24: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: 192.168.32.0/24: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: [::1]/128: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: [fe80::%eth0]/64: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: [fe80::%eth1]/64: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mynetworks: 127.0.0.0/8 192.168.0.0/24 192.168.32.0/24 [::1]/128 [fe80::%eth0]/64 [fe80::%eth1]/64
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  127.0.0.0/8 192.168.0.0/24 192.168.32.0/24 [::1]/128 [fe80::%eth0]/64 [fe80::%eth1]/64
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  hash:/etc/postfix/canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  hash:/etc/postfix/sender_canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  envelope_sender, envelope_recipient, header_sender, header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  envelope_sender, header_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  envelope_recipient, header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  hash:/etc/postfix/virtual
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  MAILER-DAEMON
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  root
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $header_checks ->
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $header_checks ->
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  canonical, virtual
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  To: undisclosed-recipients:;
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  envelope_sender, header_sender, header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  \0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  6
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  tempfail
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $myhostname -> S-001.v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: expand $mail_name $mail_version -> Postfix 2.6.1
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  j {daemon_name} v
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  {tls_version} {cipher} {cipher_bits} {cert_subject} {cert_issuer}
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  i {auth_type} {auth_authen} {auth_author} {mail_addr} {mail_host} {mail_mailer}
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  i {rcpt_addr} {rcpt_host} {rcpt_mailer}
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  i
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  i
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  i
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  0h
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1h
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  1h
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  30s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  30s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  30s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  30s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  300s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_eval: const  300s
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: process generation: 30 (30)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Compiled against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Run-time linked against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_open: hash:/etc/postfix/canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Compiled against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Run-time linked against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: warning: database /etc/postfix/sender_canonical.db is older than source file /etc/postfix/sender_canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_open: hash:/etc/postfix/sender_canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Compiled against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Run-time linked against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_open: hash:/etc/postfix/virtual
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: envelope_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: envelope_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: envelope_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: envelope_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: envelope_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_sender
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: header_recipient
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: fast_flush_domains ~? debug_peer_list
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: fast_flush_domains ~? fast_flush_domains
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Compiled against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: Run-time linked against Berkeley DB: 4.5.20?
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: dict_open: hash:/etc/postfix/relay
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: canonical
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: name_mask: virtual
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: connection established
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: master_notify: status 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_flow_get: 1 1
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: open incoming/251AA291BB
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_open: open incoming/251AA291BB
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr queue_id = 251AA291BB
Nov 15 19:58:36 S-001 postfix/smtpd[10268]: 251AA291BB: client=smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup socket: wanted attribute: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute value: 178
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup socket: wanted attribute: (list terminator)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_smtp_reply
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope T 1289847516 113252
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A rewrite_context=remote
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope S jeroenvantol@xmsnet.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: connect to subsystem private/rewrite
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr request = rewrite
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr rule = local
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr address = jeroenvantol@xmsnet.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute value: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: address
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: address
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute value: jeroenvantol@xmsnet.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: (list terminator)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: rewrite_clnt: local: jeroenvantol@xmsnet.nl -> jeroenvantol@xmsnet.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: sender_canonical_maps: jeroenvantol@xmsnet.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? bijentuin.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? inye.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_list_match: xmsnet.nl: no match
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: sender_canonical_maps: @xmsnet.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_find: jeroenvantol@xmsnet.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_map: jeroenvantol@xmsnet.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: jeroenvantol@xmsnet.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? bijentuin.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: xmsnet.nl ~? inye.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_list_match: xmsnet.nl: no match
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: @xmsnet.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_find: jeroenvantol@xmsnet.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_map: jeroenvantol@xmsnet.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A encoding=8bit
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_client_name=smtp20.mail.sp.isp-net.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_client_address=217.149.192.116
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_client_port=50459
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_message_origin=smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_helo_name=smtp20.mail.sp.isp-net.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A log_protocol_name=ESMTP
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A client_name=smtp20.mail.sp.isp-net.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A reverse_client_name=smtp20.mail.sp.isp-net.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A client_address=217.149.192.116
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A client_port=50459
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A helo_name=smtp20.mail.sp.isp-net.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A protocol_name=ESMTP
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A client_address_type=2
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope A dsn_orig_rcpt=rfc822;jeroen@v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope R jeroen@v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr request = rewrite
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr rule = local
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: send attr address = jeroen@v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute value: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: address
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: address
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute value: jeroen@v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: (list terminator)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: rewrite_clnt: local: jeroen@v-tol.nl -> jeroen@v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: jeroen@v-tol.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: v-tol.nl ~? v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: jeroen: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: @v-tol.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_find: jeroen@v-tol.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_map: jeroen@v-tol.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here_check: jeroen@v-tol.nl: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: jeroen@v-tol.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: match_string: v-tol.nl ~? v-tol.nl
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: jeroen: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: @v-tol.nl: not found
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_find: jeroen@v-tol.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: mail_addr_map: jeroen@v-tol.nl -> (not found)
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: been_here: rfc822;jeroen@v-tol.nl?0?jeroen@v-tol.nl?jeroen@v-tol.nl: 0
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: initial envelope M
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from smtp20.mail.sp.isp-net.nl (smtp20.mail.sp.isp-net.nl [217.149.192.116])??by S-001.v-tol.nl (Postfix) with ESMTP id 251AA291BB??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 19:58:36 +0100 (CE'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from webmail.xtramediaservices.nl (webmail10.mail.sp.isp-net.nl [217.149.192.62])??by smtp20.mail.sp.isp-net.nl (Postfix) with ESMTP id 14A1FFF52??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 19:'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from 92.254.96.114?        (SquirrelMail authenticated user jfkvantol)?        by webmail.xtramediaservices.nl with HTTP;?        Mon, 15 Nov 2010 19:55:45 +0100 (MET)'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Message-ID: <53031.92.254.96.114.1289847345.squirrel@webmail.xtramediaservices.nl>'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: 251AA291BB: message-id=<53031.92.254.96.114.1289847345.squirrel@webmail.xtramediaservices.nl>
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Date: Mon, 15 Nov 2010 19:55:45 +0100 (MET)'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Subject: worddoc'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'From: jeroenvantol@xmsnet.nl'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'To: jeroen@v-tol.nl'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'User-Agent: SquirrelMail/1.5.1 [CVS]'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'MIME-Version: 1.0'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: multipart / mixed
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: boundary = ----=_20101115195545_45334
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: PUSH boundary ----=_20101115195545_45334
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: multipart/mixed;boundary="----=_20101115195545_45334"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: text / plain
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: text/plain; charset="iso-8859-1"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: 8bit  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: 8bit'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: application / msword
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: application/msword;?      name="Imkervereniging Nijmegen 100 jaar.doc"'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: header_token: base64  
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: base64'
Nov 15 19:58:36 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Disposition: attachment;?      filename="Imkervereniging Nijmegen 100 jaar.doc"'
Nov 15 20:03:36 S-001 postfix/smtpd[10268]: timeout after DATA (104372 bytes) from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:03:36 S-001 postfix/smtpd[10268]: disconnect from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: cleanup_flush: status 1
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: send attr status = 1
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: send attr reason =
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: POP boundary ----=_20101115195545_45334
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: master_notify: status 1
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: connection closed
Nov 15 20:03:36 S-001 postfix/cleanup[10273]: rewrite stream disconnect
Nov 15 20:03:57 S-001 postfix/smtpd[10268]: connect from fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: connection established
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: master_notify: status 0
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_flow_get: 1 1
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: open incoming/46D0F291BB
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_open: open incoming/46D0F291BB
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr queue_id = 46D0F291BB
Nov 15 20:03:57 S-001 postfix/smtpd[10268]: 46D0F291BB: client=fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup socket: wanted attribute: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute value: 178
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup socket: wanted attribute: (list terminator)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_smtp_reply
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope T 1289847837 277960
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A rewrite_context=remote
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope S reuniewumn@gmail.com
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: connect to subsystem private/rewrite
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr request = rewrite
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr rule = local
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr address = reuniewumn@gmail.com
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute value: 0
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: address
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: address
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute value: reuniewumn@gmail.com
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: (list terminator)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: rewrite_clnt: local: reuniewumn@gmail.com -> reuniewumn@gmail.com
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: sender_canonical_maps: reuniewumn@gmail.com: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? bijentuin.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? inye.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_list_match: gmail.com: no match
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: sender_canonical_maps: @gmail.com: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_find: reuniewumn@gmail.com -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_map: reuniewumn@gmail.com -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: reuniewumn@gmail.com: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? bijentuin.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: gmail.com ~? inye.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_list_match: gmail.com: no match
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: @gmail.com: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_find: reuniewumn@gmail.com -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_map: reuniewumn@gmail.com -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_client_name=fallback1.mx.nxs.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_client_address=193.189.134.4
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_client_port=33880
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_message_origin=fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_helo_name=fallback1.mx.nxs.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A log_protocol_name=ESMTP
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A client_name=fallback1.mx.nxs.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A reverse_client_name=fallback1.mx.nxs.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A client_address=193.189.134.4
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A client_port=33880
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A helo_name=fallback1.mx.nxs.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A protocol_name=ESMTP
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A client_address_type=2
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope A dsn_orig_rcpt=rfc822;jeroen@v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope R jeroen@v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr request = rewrite
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr rule = local
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: send attr address = jeroen@v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: flags
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute value: 0
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: address
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: address
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute value: jeroen@v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: private/rewrite socket: wanted attribute: (list terminator)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: input attribute name: (end)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: rewrite_clnt: local: jeroen@v-tol.nl -> jeroen@v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: jeroen@v-tol.nl: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: v-tol.nl ~? v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: jeroen: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: canonical_maps: @v-tol.nl: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_find: jeroen@v-tol.nl -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_map: jeroen@v-tol.nl -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: been_here_check: jeroen@v-tol.nl: 0
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: jeroen@v-tol.nl: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: match_string: v-tol.nl ~? v-tol.nl
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: jeroen: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: maps_find: virtual_alias_maps: @v-tol.nl: not found
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_find: jeroen@v-tol.nl -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: mail_addr_map: jeroen@v-tol.nl -> (not found)
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: been_here: rfc822;jeroen@v-tol.nl?0?jeroen@v-tol.nl?jeroen@v-tol.nl: 0
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: initial envelope M
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from fallback1.mx.nxs.nl (fallback1.mx.nxs.nl [193.189.134.4])??by S-001.v-tol.nl (Postfix) with ESMTP id 46D0F291BB??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 20:03:57 +0100 (CET)'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: from mail-bw0-f54.google.com (mail-bw0-f54.google.com [209.85.214.54])??by fallback1.mx.nxs.nl (Postfix) with ESMTP id 14022411E55??for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 18:44:34 +0100 (CE'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: by bwz2 with SMTP id 2so5475171bwz.27?        for <jeroen@v-tol.nl>; Mon, 15 Nov 2010 09:44:30 -0800 (PST)'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed;?        d=gmail.com; s=gamma;?        h=domainkey-signature:mime-version:received:received:date:message-id?         :subject:from:content-type;?  '
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'DomainKey-Signature: a=rsa-sha1; c=nofws;?        d=gmail.com; s=gamma;?        h=mime-version:date:message-id:subject:from:content-type;?        b=sE8/ZJZ/M8FETkB2YVEtL5QBuZA8hkfLhWSnEFJQfrbySliAvKCC'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'MIME-Version: 1.0'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: by 10.204.73.75 with SMTP id p11mt8282160bkj.46.1289818357588; Mon,? 15 Nov 2010 02:52:37 -0800 (PST)'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Received: by 10.204.70.14 with HTTP; Mon, 15 Nov 2010 02:52:37 -0800 (PST)'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Date: Mon, 15 Nov 2010 11:52:37 +0100'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Message-ID: <AANLkTi=GAMigqvN1CWOvsSCt7-mJxZqRDTNMKVatqPBi@mail.gmail.com>'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: 46D0F291BB: message-id=<AANLkTi=GAMigqvN1CWOvsSCt7-mJxZqRDTNMKVatqPBi@mail.gmail.com>
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Subject: de WUMN-reunie is voorbij!'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'From: reunie <reuniewumn@gmail.com>'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: multipart / mixed
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: boundary = 001636c5995a0b8dbd04951afe91
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: PUSH boundary 001636c5995a0b8dbd04951afe91
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: multipart/mixed; boundary=001636c5995a0b8dbd04951afe91'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'To: undisclosed-recipients:;'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: text / plain
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: text/plain; charset=ISO-8859-1'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: quoted-printable  
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: quoted-printable'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: application / msword
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Type: application/msword; name="Allerlaatste bericht.doc"'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Disposition: attachment; filename="Allerlaatste bericht.doc"'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: header_token: base64  
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'Content-Transfer-Encoding: base64'
Nov 15 20:03:57 S-001 postfix/cleanup[10273]: cleanup_header_callback: 'X-Attachment-Id: f_ggj8g4cg0'
Nov 15 20:03:58 S-001 postfix/smtpd[10380]: connect from fallback1.mx.nxs.nl[193.189.134.4]
0
 
LVL 80

Expert Comment

by:arnold
ID: 34150216
Nov 15 20:03:36 S-001 postfix/smtpd[10268]: timeout after DATA (104372 bytes) from smtp20.mail.sp.isp-net.nl[217.149.192.116]

You need to track back and make sure you have all the related processies.  You start off with the cleanup events.
grep '\[10268\]' from the maillog.  Then start at the first one and see what process it starts this will complete the picture as far as when the process starts to when it ends.  Does your firewall also have email scanning capabilities?
0
 

Author Comment

by:INBO
ID: 34159385
This what I get:

Nov 17 03:03:35 S-001 postfix/smtpd[10268]: warning: database /etc/aliases.db is older than source file /etc/aliases
Nov 17 03:03:35 S-001 postfix/smtpd[10268]: warning: database /etc/postfix/virtual.db is older than source file /etc/postfix/virtual
Nov 17 03:03:35 S-001 postfix/smtpd[10268]: connect from fallback1.mx.nxs.nl[193.189.134.4]
Nov 17 03:03:35 S-001 postfix/smtpd[10268]: 98ABB29444: client=fallback1.mx.nxs.nl[193.189.134.4]
Nov 17 03:08:35 S-001 postfix/smtpd[10268]: timeout after DATA (105858 bytes) from fallback1.mx.nxs.nl[193.189.134.4]
Nov 17 03:08:35 S-001 postfix/smtpd[10268]: disconnect from fallback1.mx.nxs.nl[193.189.134.4]
0
 

Author Comment

by:INBO
ID: 34159466
Oh, and before that:

Nov 15 19:58:36 S-001 postfix/smtpd[10268]: connect from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 19:58:36 S-001 postfix/smtpd[10268]: 251AA291BB: client=smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:03:36 S-001 postfix/smtpd[10268]: timeout after DATA (104372 bytes) from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:03:36 S-001 postfix/smtpd[10268]: disconnect from smtp20.mail.sp.isp-net.nl[217.149.192.116]
Nov 15 20:03:57 S-001 postfix/smtpd[10268]: connect from fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:03:57 S-001 postfix/smtpd[10268]: 46D0F291BB: client=fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:08:57 S-001 postfix/smtpd[10268]: timeout after DATA (4185 bytes) from fallback1.mx.nxs.nl[193.189.134.4]
Nov 15 20:08:57 S-001 postfix/smtpd[10268]: disconnect from fallback1.mx.nxs.nl[193.189.134.4]
0
 
LVL 80

Expert Comment

by:arnold
ID: 34160353
run newaliases to get the /etc/aliases.db file updated based on the /etc/aliases file.

You also need to run the postmap/postconf for the virtual list.
"postmap /etc/postfix/virtual"
http://www.postfix.org/virtual.5.html

These two errors deal with the text files being updated while the DB was not rebuilt with the new/updated information.
0
 

Author Comment

by:INBO
ID: 34163504
Some more info, this mail returns after a while when I sended a ms word-attachment from another place (exchange server at my work) to my server:

jeroen@v-tol.nl
[92.254.96.114] #<[92.254.96.114] #5.0.0 smtp; 5.4.7 - Delivery expired (message too old) [Default] '[Errno 60] Operation timed out' (delivery attempts: 72)> #SMTP#

Oorspronkelijke berichtkoppen:

X-IronPort-AV: E=Sophos;i="4.59,200,1288566000";
   d="doc'32?scan'32,208,217,32";a="430324"
Received: from unknown (HELO mail.inbo.com) ([10.10.10.22])  by mail.serveratwork
 with ESMTP; 15 Nov 2010 13:46:20 +0100
Received: from 02milano.serveratwork ([10.10.10.22]) by 02milano.serveratwork
 ([10.10.10.22]) with mapi; Mon, 15 Nov 2010 13:46:20 +0100
From: Tol Jeroen van <Jeroen.vanTol@mailatwork>
To: "'jeroen@v-tol.nl'" <jeroen@v-tol.nl>
Date: Mon, 15 Nov 2010 13:46:19 +0100
Subject: word
Thread-Topic: word
Thread-Index: AcuEwxjbHn2mcI3MR/y8VR/a8vpNQg==
Message-ID: <7055008DA155B1489D405DFCA4BE616442FDA50905@02milano.serveratwork>
Accept-Language: nl-NL, en-US
Content-Language: nl-NL
X-MS-Has-Attach: yes
X-MS-TNEF-Correlator:
acceptlanguage: nl-NL, en-US
Content-Type: text/plain
MIME-Version: 1.0

0
 
LVL 80

Expert Comment

by:arnold
ID: 34163653
The error you receive deals with the message on the sending server exceeding the queue lifetime.

I.e. the message delivery attempts will only last so long.  Nothing you are providing here provides any information as to what is going on.

Can you send a word document from this place to any other email account without a problem?

There is something on your server or the router/firewall in front of your server that handles message checking and for one reason or another the connection times out or exceeds the connection time limit of the sending server which then disconnects.
You have to look at your complete environment from the internet connection to the router all the way through to your mail server dealing with what devices/services there are there.

0
 

Author Comment

by:INBO
ID: 34163779
That is because I don't know where to look anymore. From the send-address above I can send word-attachments to any place. It has to do with my server, it does'nt mather from where I send it, it is the same result, all attachments go fine, only ms word and ms excel attachments go wrong. I gave all info from my maillog (/var/log/mail) I could find. The intenetconnection goes through glass-fibermodem to a router with NAT, port 80, 143 and  25 are open also on the firewall. I don't think any filtering or what so ever on the router. I checked what I could think of in the postfix settings (master and main), I even did a fresh installation a while ago. The Grep you asked for is really all that I have. Should I tag more lines in master.cf with a -v ?

The only thing I see is that it stops during the cleanup-proces. Is there a way to stop the cleanup and test?
0
 
LVL 80

Expert Comment

by:arnold
ID: 34164215
Cleanup is the disposition of data stage.
http://www.postfix.org/cleanup.8.html

Do you have any postfix/qmgr events?

/var/log/maillog is the place to look.  You need to match the message processing flow.
postfix/smtpd deals with the inbound connection.  
postfix/smtp deals with an outgoing connection

Try the following.  you would be using your email client outside your servers LAN configured to send messages directly through it.
i.e. the outgoing server in the client configuration will be your mail server.

You then need to determine what your external IP is.

on your server you would run:
/usr/sbin/tcpdump ip host <your_public_ip_from the prior step> and port 25 >/tmp/datafile

You may want to send each message separately while noting the time start of sending and when the process ends with or without an error message(make sure the time you have is the same as the time on the server.)
use the send immediately versus queue (outbox)
Once that is in place, trigger the sending of a message with no attachments.
Then send a message with an attachment.
Then send a message with a word/excel file as an attachment.
use ctrl-c on the server to terminate the tcpdump capture.

The /tmp/datafile will contain the interaction information only i.e. you will see src > destination type of notices with times.

you would then use /var/log/maillog to go through the log from the time you started the send until the time you recorded for when the message was processed.

You may/would likely have entries from other inbound connections that your server handled.



 
0
 

Author Comment

by:INBO
ID: 34398889
Except from the log-files, is there nowhere in the settingfiles of postfix or whatever a clue to search for that anyone knows of.
0
 

Accepted Solution

by:
INBO earned 0 total points
ID: 36171901
I have to apologize, after searching for a year or so and using a different mail-address for word and excel-attachments I found out it was a buggy Genexis modem, not postfix or Cyrus or the whole Linux-server at all. It seems there is a range of modems with this bug where an attachment with a string with a lot of A's will stop the mail. It seems that this kind of string is typical for Office attachments. Googling around I only found only a very few people with the similar problem.
0
 

Author Closing Comment

by:INBO
ID: 36202576
I found out myself.
0

Featured Post

Free Tool: Site Down Detector

Helpful to verify reports of your own downtime, or to double check a downed website you are trying to access.

One of a set of tools we are providing to everyone as a way of saying thank you for being a part of the community.

Question has a verified solution.

If you are experiencing a similar issue, please ask a related question

Sometimes clients can lose connectivity with the Lotus Notes Domino Server, but there's not always an obvious answer as to why it happens.   Read this article to follow one of the first experiences I had with Lotus Notes on a client's machine, my…
This article outlines some of the reasons why an email message gets flagged as spam on a recipient's end.
The basic steps you have just learned will be implemented in this video. The basic steps are shown to configure an Exchange DAG in a live working Exchange Server Environment and manage the same (Exchange Server 2010 Software is used in a Windows Ser…
This demo shows you how to set up the containerized NetScaler CPX with NetScaler Management and Analytics System in a non-routable Mesos/Marathon environment for use with Micro-Services applications.
Suggested Courses

963 members asked questions and received personalized solutions in the past 7 days.

Join the community of 500,000 technology professionals and ask your questions.

Join & Ask a Question