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

Top Page
Delete this message
Reply to this message
Author: Andrew C Aitchison
Date:  
To: tt-admin
CC: exim-users
Subject: Re: [exim] 2 hours delay (gnutls_handshake): timed out: delivering unencrypted to
On Wed, 30 Mar 2022, tt-admin via Exim-users wrote:

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


I wonder what 1758 and 1762 were doing all that time -
I presume there is no other mention of 1762 in the log ?

If you see any more of these stuck in the queue, can you get an lsof
and a truss/strace/ltrace of the processes ?

> 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@???

>
>
> --
> ## List details at https://lists.exim.org/mailman/listinfo/exim-users
> ## Exim details at http://www.exim.org/
> ## Please use the Wiki with this list - http://wiki.exim.org/
>


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