haraka-smtp diyng periodically and not accepting connections on port 25/tcp

Issue #989 resolved
Jan Panoch created an issue

[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] running send_email hooks
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] Sending mail: 1682422229356_1682488305257_10_1453_k2D1gN_8321_ms.dacc.cz
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] running get_mx hooks
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] hook=get_mx plugin=relay function=force_routing params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] registered relays: {}
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] running send_email hooks
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] Sending mail: 1682422607731_1682488683725_10_1453_PKo9JM_8328_ms.dacc.cz
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] running get_mx hooks
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] hook=get_mx plugin=relay function=force_routing params=centrum-zakaznickych-karet.cz retval=CONT msg=""
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] registered relays: {}
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] running send_email hooks
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] Sending mail: 1682423804752_1682489880790_10_1453_1RBxEK_8346_ms.dacc.cz
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] running get_mx hooks
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] hook=get_mx plugin=relay function=force_routing params=centrum-zakaznickych-karet.cz retval=CONT msg=""
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] registered relays: {}
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] running send_email hooks
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] Sending mail: 1682426758603_1682492834498_10_1453_7EBWiC_8370_ms.dacc.cz
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] running get_mx hooks
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] hook=get_mx plugin=relay function=force_routing params=elisczech.com retval=CONT msg=""
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] registered relays: {}
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] running send_email hooks
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] Sending mail: 1682426945565_1682493021598_10_1453_yR7GgZ_8372_ms.dacc.cz
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] running get_mx hooks
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] hook=get_mx plugin=relay function=force_routing params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] registered relays: {}
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] running send_email hooks
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] Sending mail: 1682427669587_1682493746136_10_1453_TovTx3_8381_ms.dacc.cz
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] running get_mx hooks
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] hook=get_mx plugin=relay function=force_routing params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] registered relays: {}
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] running send_email hooks
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] Sending mail: 1682483112939_1682483112939_0_1453_ScBdvi_8606_ms.dacc.cz
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] running get_mx hooks
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] running get_mx hook in relay plugin
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] hook=get_mx plugin=relay function=force_routing params=dacc.cz retval=CONT msg=""
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] running get_mx hook in rcpt_database plugin
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] registered relays: {}
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] {"skip_plugins":[]}
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] remote smtp delivery
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] {"skip_plugins":[]}
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] remote smtp delivery
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] {"skip_plugins":[]}
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] remote smtp delivery
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=centrum-zakaznickych-karet.cz retval=CONT msg=""
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] {"skip_plugins":[]}
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] remote smtp delivery
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=bova-polygon.cz retval=CONT msg=""
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] {"skip_plugins":[]}
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] remote smtp delivery
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=centrum-zakaznickych-karet.cz retval=CONT msg=""
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] {"skip_plugins":[]}
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] remote smtp delivery
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=elisczech.com retval=CONT msg=""
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] {"skip_plugins":[],"remote_source":true,"original_rcpt_to":["admin@dacc.cz"],"new_rcpt_to":[{"original":"admin@dacc.cz","original_host":"dacc.cz","host":"dacc.cz","user":"admin"}],"attachment_count":0,"attachments":[],"attachment_ctypes":["text/html"],"attachment_files":[],"attachment_archive_files":[],"authentication_results":[]}
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] forcing route: {"priority":0,"exchange":"ms1.panoch.net","port":25}
[INFO] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=dacc.cz retval=OK msg="[object Object]"
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] Got a MX from Plugin: dacc.cz => 0 {"priority":0,"exchange":"ms1.panoch.net","port":25}
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] DNS (A) for ms1.panoch.net -> 89.22.65.5
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] delivering from: ms.dacc.cz to: 89.22.65.5:25 (0) (4)
[DEBUG] [39485294-41EE-4237-AAE2-EBA2485B7193] [core] [outbound] created. host: 89.22.65.5 port: 25
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] DNS (A) for hze1-ms1.centrum-zakaznickych-karet.cz -> 193.150.24.105
[DEBUG] [C9AABF65-2F34-4F71-925B-DC0CD1515384.1] [outbound] delivering from: ms.dacc.cz to: 193.150.24.105:25 (0) (4)
[DEBUG] [2F819CD4-7601-4DEB-9148-EC8B559911BE] [core] [outbound] created. host: 193.150.24.105 port: 25
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] DNS (A) for bova-polygon.cz -> 81.95.96.160
[DEBUG] [9CCE88C3-2A90-4982-BD44-5B35216ED2F7.1] [outbound] delivering from: ms.dacc.cz to: 81.95.96.160:25 (0) (4)
[DEBUG] [8EAC2172-8217-4247-BB46-C15D3D553AF9] [core] [outbound] created. host: 81.95.96.160 port: 25
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] DNS (A) for bova-polygon.cz -> 81.95.96.160
[DEBUG] [56C89BAC-84CF-45C4-8490-914257C42A1F.1] [outbound] delivering from: ms.dacc.cz to: 81.95.96.160:25 (0) (4)
[DEBUG] [7FFB3489-C490-47AE-BA92-4B688AF35D57] [core] [outbound] created. host: 81.95.96.160 port: 25
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] DNS (A) for hze1-ms1.centrum-zakaznickych-karet.cz -> 193.150.24.105
[DEBUG] [CB8AD6F8-7A12-4ED4-AC56-F04108072604.1] [outbound] delivering from: ms.dacc.cz to: 193.150.24.105:25 (0) (4)
[DEBUG] [E41E451A-33D9-43B3-B619-21D50C7CAA19] [core] [outbound] created. host: 193.150.24.105 port: 25
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] DNS (A) for bova-polygon.cz -> 81.95.96.160
[DEBUG] [61FF4D80-901B-4801-B57F-DD2B5B74FBED.1] [outbound] delivering from: ms.dacc.cz to: 81.95.96.160:25 (0) (4)
[DEBUG] [115C544C-031A-416A-A164-6C8E91881631] [core] [outbound] created. host: 81.95.96.160 port: 25
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] DNS (A) for elisczech.com -> 46.28.105.2
[DEBUG] [B17B124A-1FF5-4350-AFB9-3BAA688D0591.1] [outbound] delivering from: ms.dacc.cz to: 46.28.105.2:25 (0) (4)
[DEBUG] [1CB6A954-FB80-42B0-8794-D7A0C9A706EE] [core] [outbound] created. host: 46.28.105.2 port: 25
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 220 ms1.panoch.net ESMTP\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] C: EHLO ms.dacc.cz
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-ms1.panoch.net\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-PIPELINING\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-SIZE 302400000\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-VRFY\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-ETRN\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-STARTTLS\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250-8BITMIME\r\n
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 250 DSN\r\n
[DEBUG] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] Trying TLS for domain: dacc.cz, host: 89.22.65.5
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] C: STARTTLS
[PROTOCOL] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] S: 220 2.0.0 Ready to start TLS\r\n
[DEBUG] [-] [core] client TLS upgrade in progress, awaiting secured.
[ERROR] [-] [core] client TLS error: Error: 0058696FCB7F0000:error:0A000152:SSL routines:final_renegotiate:unsafe legacy renegotiation disabled:../deps/openssl/openssl/ssl/statem/extensions.c:922:
[ERROR] [6977169D-F902-4148-A1C9-DC946CA413FB.1.1] [outbound] Ongoing connection failed to 89.22.65.5:25 : Error: 0058696FCB7F0000:error:0A000152:SSL routines:final_renegotiate:unsafe legacy renegotiation disabled:../deps/openssl/openssl/ssl/statem/extensions.c:922:
[DEBUG] [-] [core] [outbound] release_client: 39485294-41EE-4237-AAE2-EBA2485B7193 89.22.65.5:25 to undefined
[NOTICE] [-] [OutboundTLS] TLS connection failed. Marking 89.22.65.5 as non-TLS for 604800 seconds
[CRIT] [-] [core] TypeError: this.db.setex is not a function
[CRIT] [-] [core] at OutboundTLS.mark_tls_nogo (/usr/lib/node_modules/Haraka/outbound/tls.js:98:17)
[CRIT] [-] [core] at pluggableStream.<anonymous> (/usr/lib/node_modules/Haraka/outbound/hmail.js:404:30)
[CRIT] [-] [core] at Object.onceWrapper (node:events:627:26)
[CRIT] [-] [core] at pluggableStream.emit (node:events:512:28)
[CRIT] [-] [core] at TLSSocket.<anonymous> (/usr/lib/node_modules/Haraka/tls_socket.js:90:18)
[CRIT] [-] [core] at Object.onceWrapper (node:events:627:26)
[CRIT] [-] [core] at TLSSocket.emit (node:events:524:35)
[CRIT] [-] [core] at emitErrorNT (node:internal/streams/destroy:151:8)
[CRIT] [-] [core] at emitErrorCloseNT (node:internal/streams/destroy:116:3)
[CRIT] [-] [core] at process.processTicksAndRejections (node:internal/process/task_queues:82:21)
[NOTICE] [-] [core] Shutting down

Comments (7)

  1. Jan Panoch reporter

    i think yesterday it was some isolated incident with my old mailserver running outdated TLS, but today the incident repeated with some other mailserver on internet and i must reconfigure poste.io to use my other mailserver as outgoing mail gateway to mitigate this problem temporary..

  2. ChrisT

    I wanted to chime in and let you know that we've been experiencing the same issue for the past 5 days on our production system. The server crashes and attempts to restart every few seconds. Here are some relevant logs that we have gathered (IPs, IDs and DNS names obfuscated):

    ...
    2023-05-02 17:20:28.181290500  [DEBUG] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] delivering from: MYSMTP.com to: 81.91.xx.xx:25 (0) (0)
    2023-05-02 17:20:28.182900500  [DEBUG] [F7DEE150-F658-4853-8582-FFFFFFFFFFFD] [core] [outbound] created. host: 81.91.xx.xx port: 25
    2023-05-02 17:20:28.201068500  [NOTICE] [-] [core] [outbound] Failed to get socket: Outbound connection error: Error: connect ECONNREFUSED 81.91.xx.xx:25
    2023-05-02 17:20:28.201790500  [DEBUG] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] DNS (A) for fred.REMOTESMTP.cz -> 81.91.xx.xy
    2023-05-02 17:20:28.201936500  [DEBUG] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] delivering from: MYSMTP.com to: 81.91.xx.xy:25 (0) (0)
    2023-05-02 17:20:28.202516500  [DEBUG] [4CD4427E-D513-4D3C-81F3-FFFFFFFFFFFE] [core] [outbound] created. host: 81.91.xx.xy port: 25
    2023-05-02 17:20:28.236328500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 220 mail.REMOTESMTP.cz ESMTP Postfix (Debian/GNU)\r\n
    2023-05-02 17:20:28.236689500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] C: EHLO MYSMTP.com
    2023-05-02 17:20:28.252471500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-mail.REMOTESMTP.cz\r\n
    2023-05-02 17:20:28.252712500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-PIPELINING\r\n
    2023-05-02 17:20:28.252751500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-SIZE 20480000\r\n
    2023-05-02 17:20:28.252800500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-ETRN\r\n
    2023-05-02 17:20:28.252834500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-STARTTLS\r\n
    2023-05-02 17:20:28.252881500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-AUTH PLAIN\r\n
    2023-05-02 17:20:28.252923500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n
    2023-05-02 17:20:28.252969500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250-8BITMIME\r\n
    2023-05-02 17:20:28.252975500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 250 DSN\r\n
    2023-05-02 17:20:28.254846500  [DEBUG] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] Trying TLS for domain: REMOTEDOMAIN.cz, host: 81.91.xx.xy
    2023-05-02 17:20:28.254902500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] C: STARTTLS
    2023-05-02 17:20:28.270913500  [PROTOCOL] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] S: 220 2.0.0 Ready to start TLS\r\n
    2023-05-02 17:20:28.275297500  [DEBUG] [-] [core] client TLS upgrade in progress, awaiting secured.
    2023-05-02 17:20:28.296343500  [ERROR] [-] [core] client TLS error: Error: 00983EB3177F0000:error:0A0C0103:SSL routines:tls_process_key_exchange:internal error:../deps/openssl/openssl/ssl/statem/statem_clnt.c:2262:
    2023-05-02 17:20:28.296610500  [ERROR] [9CBB6C4A-893F-4C52-AF10-FFFFFFFFFFFF.1.1] [outbound] Ongoing connection failed to 81.91.xx.xy:25 : Error: 00983EB3177F0000:error:0A0C0103:SSL routines:tls_process_key_exchange:internal error:../deps/openssl/openssl/ssl/statem/statem_clnt.c:2262:
    2023-05-02 17:20:28.296747500  [DEBUG] [-] [core] [outbound] release_client: 4CD4427E-D513-4D3C-81F3-FFFFFFFFFFFE 81.91.xx.xy:25 to undefined
    2023-05-02 17:20:28.296971500  [NOTICE] [-] [OutboundTLS] TLS connection failed. Marking 81.91.xx.xy as non-TLS for 604800 seconds
    2023-05-02 17:20:28.298187500  [CRIT] [-] [core] TypeError: this.db.setex is not a function
    2023-05-02 17:20:28.298269500  [CRIT] [-] [core]     at OutboundTLS.mark_tls_nogo (/usr/lib/node_modules/Haraka/outbound/tls.js:98:17)
    2023-05-02 17:20:28.298320500  [CRIT] [-] [core]     at pluggableStream.<anonymous> (/usr/lib/node_modules/Haraka/outbound/hmail.js:404:30)
    2023-05-02 17:20:28.298362500  [CRIT] [-] [core]     at Object.onceWrapper (node:events:627:26)
    2023-05-02 17:20:28.298393500  [CRIT] [-] [core]     at pluggableStream.emit (node:events:512:28)
    2023-05-02 17:20:28.298457500  [CRIT] [-] [core]     at TLSSocket.<anonymous> (/usr/lib/node_modules/Haraka/tls_socket.js:90:18)
    2023-05-02 17:20:28.298464500  [CRIT] [-] [core]     at Object.onceWrapper (node:events:627:26)
    2023-05-02 17:20:28.298492500  [CRIT] [-] [core]     at TLSSocket.emit (node:events:524:35)
    2023-05-02 17:20:28.298519500  [CRIT] [-] [core]     at emitErrorNT (node:internal/streams/destroy:151:8)
    2023-05-02 17:20:28.298565500  [CRIT] [-] [core]     at emitErrorCloseNT (node:internal/streams/destroy:116:3)
    2023-05-02 17:20:28.298601500  [CRIT] [-] [core]     at process.processTicksAndRejections (node:internal/process/task_queues:82:21)
    2023-05-02 17:20:28.299594500  [NOTICE] [-] [core] Shutting down
    ...
    

    Interestingly, our crashes were also related to a .cz TLD.

    As a temporary workaround, we removed a file from the queue located at /data/queue/smtp, and the server is now finally functioning correctly. However, this is a production system, and we need a permanent fix to ensure this problem does not reoccur.

  3. Log in to comment