[exim] Queue time logging incorrect in 4.94?

Top Page
Delete this message
Reply to this message
Author: Matt Rubright
Date:  
To: exim-users
Subject: [exim] Queue time logging incorrect in 4.94?
Hi All,

While troubleshooting some user complaints about email queue times, I
discovered some cases where emails are logged as having longer queue times
than they actually do. Here's an example from yesterday (sender and
recipient email addresses redacted):

2020-10-26 15:29:00 1kX89c-0003J8-W6 <= sender@???
H=(OCS-LOANER-03) [10.38.130.32] P=smtp S=5024045
2020-10-26 15:29:05 1kX89c-0003J8-W6 => recipA@???
R=noauthsmarthost T=remote_smtp H=smtp-relay.gmail.com [108.177.122.28]
X=TLS1.2:ECDHE-ECDSA-AES128-GCM-SHA256:128 CV=yes K C="250 2.0.0 OK
190sm1514290ooj.5 - gsmtp"
2020-10-26 15:29:05 1kX89c-0003J8-W6 -> recipB@???
R=noauthsmarthost T=remote_smtp H=smtp-relay.gmail.com [108.177.122.28]
X=TLS1.2:ECDHE-ECDSA-AES128-GCM-SHA256:128 CV=yes K C="250 2.0.0 OK
190sm1514290ooj.5 - gsmtp"
2020-10-26 15:29:05 1kX89c-0003J8-W6 Completed QT=1m24s

The QT log entry shows the total queue time to be 1m24s, but the timestamps
show 5 seconds start to finish. Am I misreading this somehow? The message
ID doesn't show up anywhere else in the log,

My log_selector configuration looks like this:

+queue_time_overall +smtp_incomplete_transaction

Output from 'exim -bV' for version information:

Exim version 4.94 #2 built 01-Jun-2020 19:51:21
Copyright (c) University of Cambridge, 1995 - 2018
(c) The Exim Maintainers and contributors in ACKNOWLEDGMENTS file, 2007 -
2018
Berkeley DB: Berkeley DB 5.3.21: (May 11, 2012)
Support for: crypteq iconv() IPv6 PAM Perl Expand_dlfunc OpenSSL
Content_Scanning DANE DKIM DNSSEC Event OCSP PIPE_CONNECT PRDR PROXY SOCKS
SPF DMARC TCP_Fast_Open
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm
dbmjz dbmnz dnsdb dsearch ldap ldapdn ldapm nis nis0 nisplus passwd sqlite
Authenticators: cram_md5 cyrus_sasl dovecot gsasl plaintext spa tls
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Malware: f-protd f-prot6d drweb fsecure sophie clamd avast sock cmdline
Fixed never_users: 0
Configure owner: 0:0
Size of off_t: 8
Configuration file is /etc/exim/exim.conf

Thanks for your help,
Matt Rubright
UNC-Charlotte