Solved

Postfix timed out with word and excel attachments

Posted on 2010-11-15
22
657 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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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
Comment Utility
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
 
LVL 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
Do you mean the steps before the ones I placed above?
0
 
LVL 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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
Why You Should Analyze Threat Actor TTPs

After years of analyzing threat actor behavior, it’s become clear that at any given time there are specific tactics, techniques, and procedures (TTPs) that are particularly prevalent. By analyzing and understanding these TTPs, you can dramatically enhance your security program.

 
LVL 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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
Comment Utility
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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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 76

Expert Comment

by:arnold
Comment Utility
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
Comment Utility
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
Comment Utility
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
Comment Utility
I found out myself.
0

Featured Post

How your wiki can always stay up-to-date

Quip doubles as a “living” wiki and a project management tool that evolves with your organization. As you finish projects in Quip, the work remains, easily accessible to all team members, new and old.
- Increase transparency
- Onboard new hires faster
- Access from mobile/offline

Join & Write a Comment

Email signatures have numerous marketing benefits. Here are 8 top reasons to turn your email signature into a marketing channel.
Local Continuous Replication is a cost effective and quick way of backing up Exchange server data. The following article describes the steps required to configure Local Continuous Replication. Also, the article tells you how to restore from a backup…
In this video we show how to create a Resource Mailbox in Exchange 2013. We show this process by using the Exchange Admin Center. Log into Exchange Admin Center.: Navigate to the Recipients >> Resources tab.: "Recipients" is our default selection …
Get a first impression of how PRTG looks and learn how it works.   This video is a short introduction to PRTG, as an initial overview or as a quick start for new PRTG users.

772 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

Need Help in Real-Time?

Connect with top rated Experts

10 Experts available now in Live!

Get 1:1 Help Now