Queue write error

classic Classic list List threaded Threaded
5 messages Options
Reply | Threaded
Open this post in threaded view
|

Queue write error

Stephen Atkins-2
This one has been baffelling me for a few days now.  We had to move our
mail server to a new machine (bigger, faster, stronger) as the old one
ran out of disk space.  Any way here is a transcript of what the server
is sending me.  It seems to be after postfix has initially accepted the
mail and its on its way back in from the amavisd-new.  If you need more
info let me know.

Transcript of session follows.

  Out: 220 mta5.rcr.west ESMTP Postfix.  We block/report all spam/spammers.
  In:  EHLO localhost
  Out: 250-mta5.rcr.west
  Out: 250-PIPELINING
  Out: 250-SIZE 26214400
  Out: 250-ETRN
  Out: 250-STARTTLS
  Out: 250-AUTH PLAIN LOGIN
  Out: 250-AUTH=PLAIN LOGIN
  Out: 250-ENHANCEDSTATUSCODES
  Out: 250-8BITMIME
  Out: 250 DSN
  In:  MAIL FROM:<emailblocked> BODY=7BIT
  Out: 250 2.1.0 Ok
  In:  RCPT TO:<emailblocked> ORCPT=rfc822;emailblocked
  Out: 250 2.1.5 Ok
  In:  DATA
  Out: 354 End data with <CR><LF>.<CR><LF>
  Out: 451 4.3.0 Error: queue file write error
  In:  QUIT
  Out: 221 2.0.0 Bye

--
Stephen Atkins
Information Systems
Resorts of the Canadian Rockies INC.
http://www.skircr.com
[hidden email]
Voice: (403) 209-3367
Cell: (403) 510-8333
Fax: (403) 244-3774
Reply | Threaded
Open this post in threaded view
|

Re: Queue write error

Ralf Hildebrandt
* Stephen Atkins <[hidden email]>:
> This one has been baffelling me for a few days now.  We had to move our  
> mail server to a new machine (bigger, faster, stronger) as the old one  
> ran out of disk space.  Any way here is a transcript of what the server  
> is sending me.  It seems to be after postfix has initially accepted the  
> mail and its on its way back in from the amavisd-new.  If you need more  
> info let me know.
>
> Transcript of session follows.

What's in your logs?

--
Ralf Hildebrandt ([hidden email])          [hidden email]
Postfix - Einrichtung, Betrieb und Wartung       Tel. +49 (0)30-450 570-155
http://www.arschkrebs.de
You step in the stream,
But the water has moved on.
This page is not here.
Reply | Threaded
Open this post in threaded view
|

Re: Queue write error

Stephen Atkins-2
Ralf Hildebrandt wrote:

> * Stephen Atkins <[hidden email]>:
>> This one has been baffelling me for a few days now.  We had to move our  
>> mail server to a new machine (bigger, faster, stronger) as the old one  
>> ran out of disk space.  Any way here is a transcript of what the server  
>> is sending me.  It seems to be after postfix has initially accepted the  
>> mail and its on its way back in from the amavisd-new.  If you need more  
>> info let me know.
>>
>> Transcript of session follows.
>
> What's in your logs?
>


Here is the entire log for one of the emails.  It seems that on line
three amavis is getting an error of "Negative SMTP resp. to data-dot".
I think its at this point that its failing.  As you can see the message
does eventually get through.  I've changed the email addresses and
external IPs.

Jul 16 08:54:33 mta5 amavis[3541]: (03541-17) ESMTP::10024
/var/lib/amavis/tmp/amavis-20080716T085430-03541: <[hidden email]>
-> <[hidden email]> SIZE=88585 Received: from company.com
([127.0.0.1]) by localhost (mta5.rcr.west [127.0.0.1]) (amavisd-new,
port 10024) with ESMTP for <[hidden email]>; Wed, 16 Jul 2008
08:54:33 -0600 (MDT)
Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Checking: FpDqL9d3ddPU
<[hidden email]> -> <[hidden email]>
Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Negative SMTP resp. to
data-dot (<[hidden email]>): 451 4.3.0 Error: queue file
write error
Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) (!)FWD via SMTP:
<[hidden email]> -> <[hidden email]>,BODY=7BIT 451 4.6.0
Failed, id=03541-17, from MTA([127.0.0.1]:10025): 451 4.3.0 Error: queue
file write error
Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Blocked MTA-BLOCKED,
[192.168.1.30] <[hidden email]> -> <[hidden email]>,
Message-ID: <009001c8e753$bf7ea8f0$[hidden email]>, mail_id:
FpDqL9d3ddPU, Hits: -3.268, size: 88585, 207 ms
Jul 16 08:54:34 mta5 postfix/smtp[2637]: 862FA96308:
to=<[hidden email]>, relay=127.0.0.1[127.0.0.1]:10024,
delay=9.6, delays=0.19/8.9/0.34/0.21, dsn=4.3.0, status=deferred (host
127.0.0.1[127.0.0.1] said: 451 4.3.0 Error: queue file write error (in
reply to end of DATA command))
Jul 16 09:03:21 mta5 amavis[4192]: (04192-08) ESMTP::10024
/var/lib/amavis/tmp/amavis-20080716T090224-04192: <[hidden email]>
-> <[hidden email]> SIZE=88585 Received: from company.com
([127.0.0.1]) by localhost (mta5.rcr.west [127.0.0.1]) (amavisd-new,
port 10024) with ESMTP for <[hidden email]>; Wed, 16 Jul 2008
09:03:21 -0600 (MDT)
Jul 16 09:03:21 mta5 amavis[4192]: (04192-08) Checking: uM4zAkFRbMoi
<[hidden email]> -> <[hidden email]>
Jul 16 09:03:21 mta5 amavis[4192]: (04192-08) FWD via SMTP:
<[hidden email]> -> <[hidden email]>,BODY=7BIT 250 2.6.0
Ok, id=04192-08, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as
BC22A9627A
Jul 16 09:03:21 mta5 amavis[4192]: (04192-08) Passed CLEAN,
[192.168.1.30] <[hidden email]> -> <[hidden email]>,
Message-ID: <009001c8e753$bf7ea8f0$[hidden email]>, mail_id:
uM4zAkFRbMoi, Hits: -3.268, size: 88585, queued_as: BC22A9627A, 221 ms
Jul 16 09:03:21 mta5 postfix/smtp[4247]: 862FA96308:
to=<[hidden email]>, relay=127.0.0.1[127.0.0.1]:10024,
delay=537, delays=536/0.95/0.39/0.22, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as BC22A9627A)
Jul 16 09:03:23 mta5 postfix/smtp[4282]: BC22A9627A:
to=<[hidden email]>, relay=somewhere.com[123.123.123.123]:25,
delay=1.3, delays=0.04/0.02/0.19/1.1, dsn=2.0.0, status=sent (250 ok:
Message 32920653 accepted)


--
Stephen Atkins
Information Systems
Resorts of the Canadian Rockies INC.
http://www.company.com
[hidden email]
Voice: (403) 209-3367
Cell: (403) 510-8333
Fax: (403) 244-3774
Reply | Threaded
Open this post in threaded view
|

Re: Queue write error

Wietse Venema
Stephen Atkins:
[ Charset UTF-8 unsupported, converting... ]

> Ralf Hildebrandt wrote:
> > * Stephen Atkins <[hidden email]>:
> >> This one has been baffelling me for a few days now.  We had to move our  
> >> mail server to a new machine (bigger, faster, stronger) as the old one  
> >> ran out of disk space.  Any way here is a transcript of what the server  
> >> is sending me.  It seems to be after postfix has initially accepted the  
> >> mail and its on its way back in from the amavisd-new.  If you need more  
> >> info let me know.
> >>
> >> Transcript of session follows.
> >
> > What's in your logs?
> >
>
>
> Here is the entire log for one of the emails.  It seems that on line
> three amavis is getting an error of "Negative SMTP resp. to data-dot".
> I think its at this point that its failing.  As you can see the message
> does eventually get through.  I've changed the email addresses and
> external IPs.
>
> Jul 16 08:54:33 mta5 amavis[3541]: (03541-17) ESMTP::10024
> /var/lib/amavis/tmp/amavis-20080716T085430-03541: <[hidden email]>
> -> <[hidden email]> SIZE=88585 Received: from company.com
> ([127.0.0.1]) by localhost (mta5.rcr.west [127.0.0.1]) (amavisd-new,
> port 10024) with ESMTP for <[hidden email]>; Wed, 16 Jul 2008
> 08:54:33 -0600 (MDT)
> Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Checking: FpDqL9d3ddPU
> <[hidden email]> -> <[hidden email]>
> Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Negative SMTP resp. to
> data-dot (<[hidden email]>): 451 4.3.0 Error: queue file
> write error
> Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) (!)FWD via SMTP:
> <[hidden email]> -> <[hidden email]>,BODY=7BIT 451 4.6.0
> Failed, id=03541-17, from MTA([127.0.0.1]:10025): 451 4.3.0 Error: queue
> file write error
> Jul 16 08:54:34 mta5 amavis[3541]: (03541-17) Blocked MTA-BLOCKED,
> [192.168.1.30] <[hidden email]> -> <[hidden email]>,
> Message-ID: <009001c8e753$bf7ea8f0$[hidden email]>, mail_id:
> FpDqL9d3ddPU, Hits: -3.268, size: 88585, 207 ms
> Jul 16 08:54:34 mta5 postfix/smtp[2637]: 862FA96308:
> to=<[hidden email]>, relay=127.0.0.1[127.0.0.1]:10024,
> delay=9.6, delays=0.19/8.9/0.34/0.21, dsn=4.3.0, status=deferred (host
> 127.0.0.1[127.0.0.1] said: 451 4.3.0 Error: queue file write error (in
> reply to end of DATA command))

I'm sorry, but there is a lot more Postfix logging per mail delivery.
For example, there is logging by the postfix/smtpd process on port 10025
that receives mail from amavis, and from a postfix/cleanup process that
receives the mail from the postfix/smtpd process.

For a quick report of all problems, follow instructions at
http://www.postfix.org/DEBUG_README.html#logging

        Wietse
Reply | Threaded
Open this post in threaded view
|

Re: Queue write error

Wietse Venema
In reply to this post by Stephen Atkins-2
Stephen Atkins:
> Jul 17 08:37:42 mta5 postfix/cleanup[3816]: warning: B950896266: trace service failure
> Jul 17 08:37:42 mta5 postfix/cleanup[3816]: warning: B950896266: trace logfile update error

You have screwed up your trace service. This is a required part of
Postfix for delivery status notifications.

Follow-ups to postfix-users.

        Wietse