AboutPricingCommunityTeamsStart Free TrialLog in
Avatar of INBO
INBO
 asked on

Postfix timed out with word and excel attachments

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.
LinuxEmail Servers
Avatar of undefined
Last Comment
INBO
8/22/2022 - Mon
arnold
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.
INBO
ASKER
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.
INBO
ASKER
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"'
Your help has saved me hundreds of hours of internet surfing.
fblack61
arnold
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.
INBO
ASKER
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
arnold
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?
âš¡ FREE TRIAL OFFER
Try out a week of full access for free.
Find out why thousands trust the EE community with their toughest problems.
INBO
ASKER
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.
arnold
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..
INBO
ASKER
Do you mean the steps before the ones I placed above?
Experts Exchange is like having an extremely knowledgeable team sitting and waiting for your call. Couldn't do my job half as well as I do without it!
James Murphy
arnold
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.
INBO
ASKER
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]
arnold
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?
âš¡ FREE TRIAL OFFER
Try out a week of full access for free.
Find out why thousands trust the EE community with their toughest problems.
INBO
ASKER
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]
INBO
ASKER
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]
arnold
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.
This is the best money I have ever spent. I cannot not tell you how many times these folks have saved my bacon. I learn so much from the contributors.
rwheeler23
INBO
ASKER
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

arnold
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.

INBO
ASKER
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?
âš¡ FREE TRIAL OFFER
Try out a week of full access for free.
Find out why thousands trust the EE community with their toughest problems.
arnold
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.



 
INBO
ASKER
Except from the log-files, is there nowhere in the settingfiles of postfix or whatever a clue to search for that anyone knows of.
ASKER CERTIFIED SOLUTION
INBO
THIS SOLUTION ONLY AVAILABLE TO MEMBERS.
View this solution by signing up for a free trial.
Members can start a 7-Day free trial and enjoy unlimited access to the platform.
See Pricing Options
Start Free Trial
GET A PERSONALIZED SOLUTION
Ask your own question & get feedback from real experts
Find out why thousands trust the EE community with their toughest problems.
INBO
ASKER
I found out myself.
Experts Exchange has (a) saved my job multiple times, (b) saved me hours, days, and even weeks of work, and often (c) makes me look like a superhero! This place is MAGIC!
Walt Forbes
Plans and Pricing
Resources
Product
Podcasts
Careers
Contact Us
Teams
Certified Expert Program
Credly Partnership
Udemy Partnership
Privacy Policy
Terms of Use

© 1996-2023 Experts Exchange, LLC. All rights reserved. Covered by US Patent