Re: [exim] 2 hours delay (gnutls_handshake): timed out: deli…

Top Page
Delete this message
Reply to this message
Author: tt-admin
Date:  
To: 'Andrew C Aitchison'
CC: exim-users
Subject: Re: [exim] 2 hours delay (gnutls_handshake): timed out: delivering unencrypted to
>Does
>     exigrep 1nWC1t-0001kn-G2 /var/log/exim4/mainlog-202203*
>(or where ever your exim logs are) show anything for the "other process" ?


I'm afraid not, here you see two logs from yesterday (complete exigrep
output without sensitive information):

2022-03-29 10:02:40.626 [1758] cwd=/var/spool/exim4 3 args: /usr/sbin/exim4
-Mc 1nZ6oK-0000S3-GM
2022-03-29 10:02:40.614 [1739] 1nZ6oK-0000S3-GM <= ... H=(...) [...]:56786
I=[...]:25 P=esmtps X=TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256 CV=no
S=3421694 M8S=0 id=02276F4E_71BD948B_foo@bar from <..> for ...
2022-03-29 10:05:58.606 [4928] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:10:58.591 [5636] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:15:58.606 [6321] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:20:58.594 [7183] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:25:58.602 [7933] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:30:58.600 [8457] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:35:58.738 [9096] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:40:58.613 [9701] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:45:58.603 [10239] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:50:58.728 [11294] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 10:56:01.073 [12271] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:00:58.601 [12901] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:05:58.601 [14012] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:10:58.593 [14823] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:15:58.597 [15630] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:20:58.604 [16520] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:25:58.787 [17004] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:30:58.610 [18508] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:35:58.610 [20784] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:40:58.616 [22363] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:45:58.665 [23905] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:50:58.728 [24668] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 11:55:58.595 [25150] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 12:00:58.617 [25571] 1nZ6oK-0000S3-GM Spool file is locked
(another process is handling this message)
2022-03-29 12:04:06.427 [1762] 1nZ6oK-0000S3-GM TLS session:
(gnutls_handshake): timed out: delivering unencrypted to H=... [...] (not in
hosts_require_tls)
2022-03-29 12:04:08.110 [1758] 1nZ6oK-0000S3-GM => ... F=<...> P=<...>
R=dnslookup T=remote_smtp S=3466181 H=... [...]:25 PRX=[]:0 I=[...]:59614
C="250 2.0.0 OK 59/09-19783-CC9D2426" QT=2h1m27s DT=1.678s
2022-03-29 12:04:08.110 [1758] 1nZ6oK-0000S3-GM Completed QT=2h1m27s


Same sender, same recipient, same recipient host:

2022-03-29 12:33:19.531 [29600] cwd=/var/spool/exim4 3 args: /usr/sbin/exim4
-Mc 1nZ9A7-0007hO-Fh

2022-03-29 12:33:19.506 [29598] 1nZ9A7-0007hO-Fh <= ... H=(...) [...]:40002
I=[...]:25 P=esmtps X=TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256 CV=no S=610337
M8S=0 id=02B18AED_0F70847B_foo@bar from <...> for ...
2022-03-29 12:33:20.105 [29600] 1nZ9A7-0007hO-Fh => ... F=<...> P=<...>
R=dnslookup T=remote_smtp S=618306 H=... [...]:25 PRX=[]:0 I=[...]:60340
X=TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256 CV=yes DN="CN=*.foo.bar" C="250
2.0.0 OK DE/29-19783-5A0E2426" QT=0.618s DT=0.546s
2022-03-29 12:33:20.106 [29600] 1nZ9A7-0007hO-Fh Completed QT=0.619s

Restarting the daemon did not help (was not expecting, but tried anyway).


Cheers
--
Marc



-- 
Andrew C. Aitchison                    Kendal, UK
             andrew@???