develooper Front page | perl.qpsmtpd | Postings from February 2016

Logging: Missing lines, truncated data

From:
Christoph Lukas
Date:
February 22, 2016 11:15
Subject:
Logging: Missing lines, truncated data
Message ID:
56CAEDC2.9020304@firc.de
Hello list,

I'm hoping you can help me with a spot of bother I ran into:

The logs written to syslog and file are missing lines, while certain 
lines are truncated.

For example, this is the incomming session that sent the "WELCOME to 
qpsmtpd@perl.org" mail:
(timestamps after the first line are removed for readability)

   Mon Feb 22 10:55:34 2016 mail[17615]: US
   mail[17615]: Linux 2.6.x
   mail[17615]: pass, not spontaneous
   mail[17615]: skip, no match
   mail[17615]: karma 1 (1)
   mail[17615]: pass
   mail[17615]: pass
   mail[17615]: 220 mailin.firc.de ESMTP qpsmtpd 0.96/v0.96 ready; send 
us your mail, but not your spam.
   mail[17615]: dispatching EHLO x6.develooper.com
   mail[17615]: karma 1 (2)
   mail[17615]: pass
   mail[17615]: 250-mailin.firc.de Hi x6.develooper.com [207.171.7.86]
   mail[17615]: 250-PIPELINING
   mail[17615]: 250-8BITMIME
   mail[17615]: 250 STARTTLS
   mail[17615]: dispatching MAIL FROM:<qpsmtpd-return-@perl.org>
   mail[17615]: pass, perl.org has MX at mx.develooper.com
   mail[17615]: pass, no zones
   mail[17615]: pass
   mail[17615]: karma 1 (3)
   mail[17615]: pass, perl.org: 207.171.7.86 is authorized to use 
'qpsmtpd-return-@perl.org' in 'mfrom' identity (mechanism 
'ip4:207.171.7.0/24' matched)
   mail[17615]: 250 <qpsmtpd-return-@perl.org>, sender OK - how exciting 
to get mail from you!
   mail[17615]: dispatching RCPT TO:<clukas@firc.de>
   mail[17615]: pass
   mail[17615]: pass: firc.de in rcpthosts
   mail[17615]: 250 <clukas@firc.de>, recipient ok
   mail[17615]: dispatching DATA
   mail[17615]: pass
   mail[17615]: 354 go ahead
   mail[17615]: skip, unsigned
   mail[17615]: pass, no signature, neutral policy
   mail[17615]: replace_rcpt: recipient: clukas@firc.de -> clukas@firc.de
   mail[17615]: pass, not a null sender
   mail[17615]: karma 1 (4)
   mail[17615]: error, reject disabled (Ham, -4.5, learn=ham)
   mail[17615]: pass
   mail[17615]: 250 Queued! <1456138532.11874.ezmlm@perl.org> (Queue-Id: 
F0D0A10A7)
   mail[17615]: dispatching QUIT
   mail[17615]: 221 mailin.firc.de closing connection. Have a wonderful day.
   mail[17615]: click, disconnecting
   mail[17615]: 3.182 s.


This differs greatly from the samples at [1]: crucial information is 
missing, lines containing a colon seem to be missing text before the :.

Logging is configured as such:
   ## Logging
   # File:
   logging/file loglevel LOGINFO /srv/smtp/qpsmtpd/log/qpsmtpd.log
   # Syslog:
   logging/syslog loglevel LOGINFO priority LOG_NOTICE facilty mail

The logs in both syslog and file are mutilated as shown above.

OS is FreeBSD 10.3-BETA2 amd64, perl is at v5.20.3, qpsmtpd is at git 
HEAD (43020a8).

What can be done to restore proper logging?

Thank you very much :-)

Best regards,
Christoph

1: https://github.com/smtpd/qpsmtpd/wiki/Sample-Logs



nntp.perl.org: Perl Programming lists via nntp and http.
Comments to Ask Bjørn Hansen at ask@perl.org | Group listing | About