Observing a 33 second delay before response MDN is sent

By m.ewald on Tue, 03/05/2024 - 10:24

Hi,

We're trying to set up the Open Source version of the Mendelson AS2 server. Things seem to - mostly - work fine, but between receiving an MDN from our partner and sending the acknowledgement, there is a ~33 second pause where nothing seems to be happening.

The logs do not indicate anything going on:


[09:10:07] [12345678-1234-1234-1234-1234567890ab@PIP] Erstelle ausgehende MDN, setze Nachrichten Id auf "mendelson_opensource_AS2-COMMUN-1709626207247-60@XYZ-PROD_PARTNER".
[09:10:43] [mendelson_opensource_AS2-COMMUN-1709626207247-60@XYZ-PROD_PARTNER] Ausgehende MDN wurde mit dem Algorithmus "SHA-512" signiert.
[09:10:43] [mendelson_opensource_AS2-COMMUN-1709626207247-60@XYZ-PROD_PARTNER] Ausgehende MDN erstellt für die AS2 Nachricht "12345678-1234-1234-1234-1234567890ab@PIP", Status auf [processed] gesetzt.
[09:10:43] [mendelson_opensource_AS2-COMMUN-1709626207247-60@XYZ-PROD_PARTNER] Synchrone MDN als Antwort auf 12345678-1234-1234-1234-1234567890ab@PIP versandt.
[09:10:43] [12345678-1234-1234-1234-1234567890ab@PIP] Die AS2 Kommunikation war erfolgreich, die Nutzdaten 1 wurden nach "C:\mendelson-as2\messages\ThisCompany\inbox\9f9092f4_2857_1eee_b6d8_f8b27391f516@PIP" verschoben. (11,17 KB)
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Eingehende Übertragung ist eine AS2 Nachricht [PARTNER This Company Name], Rohdatengrösse: 12,20 KB
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Eingegangene AS2 Nachricht ist verschlüsselt.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Die Daten der eingegangenen AS2 Nachricht wurden mit Hilfe des Schlüssels "THISCOMPANY" entschlüsselt, der Verschlüsselungsalgorithmus war "AES-256", der Schlüsselverschlüsselungsalgorithmus war "RSA".
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Eingegangene AS2 Nachricht ist digital signiert.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Für die digitale Signatur wurde vom Sender der Algorithmus "SHA-512" verwendet.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Benutze das Zertifikat "PARTNER" zum Überprüfen der digitalen Signatur der eingegangenen AS2 Nachricht.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Digitale Signatur der eingegangenen AS2 Nachricht wurde erfolgreich überprüft.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Die komprimierten Nutzdaten der eingegangenen AS2 Nachricht wurden von 8,28 KB auf 12,50 KB expandiert.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Der originale Dateiname wurde vom Sender als "EXAMPLE-X-FILENAME.PDF" übertragen.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Es wurden 1 Anhänge mit Nutzdaten in der AS2 Nachricht gefunden.
[09:10:43] [98765432-9876-9876-9876-9876543210FE@PIP] Erstelle ausgehende MDN, setze Nachrichten Id auf "mendelson_opensource_AS2-COMMUN-1709626243956-61@XYZ-PROD_PARTNER".
[09:11:20] [mendelson_opensource_AS2-COMMUN-1709626243956-61@XYZ-PROD_PARTNER] Ausgehende MDN wurde mit dem Algorithmus "SHA-512" signiert.
[09:11:20] [mendelson_opensource_AS2-COMMUN-1709626243956-61@XYZ-PROD_PARTNER] Ausgehende MDN erstellt für die AS2 Nachricht "98765432-9876-9876-9876-9876543210FE@PIP", Status auf [processed] gesetzt.
[09:11:20] [mendelson_opensource_AS2-COMMUN-1709626243956-61@XYZ-PROD_PARTNER] Synchrone MDN als Antwort auf 98765432-9876-9876-9876-9876543210FE@PIP versandt.
[09:11:20] [98765432-9876-9876-9876-9876543210FE@PIP] Die AS2 Kommunikation war erfolgreich, die Nutzdaten 1 wurden nach "C:\mendelson-as2\messages\ThisCompany\inbox\aaaaaaaa_aaaa_aaaa_aaaa_aaaaaaaaaaaa@PIP" verschoben. (12,26 KB)

- This is with the OpenJDK Java VM 11.0.21 (2023-10-17 LTS)
- Java memory has been increased to 4 GiB.
- The Mendelson AS2 UI shows "No configuration problems."
- Using the built-in HSQL database for now.

I can't find an explanation for the 33 second delay. It is pretty close to 30 seconds, so I've been thinking maybe some TCP connection is made and runs into a timeout (i.e. an e-mail notification attempt or something), but all e-mail notifications are off and it would be strange for these to cause the main processing thread to hang, anyway.

Can anyone suggest what might be causing the delay?
Is there something in the configuration worth checking in this regard?

Forum
AS2

Comments

Submitted by m.ewald on Tue, 03/05/2024 - 11:30

My current suspicion is that the outgoing messages are merely placed in the 'outbox' directory, but the polling interval I can find in the documentation is directly responsible for the delay (though in that case, the observed delay should be more random).

There is a topic "directory monitoring" in the documentation which talks about recommended poll intervals, but so far, I don't see any settings controlling these polling intervals anywhere. A search of the entire AS2 server directory (including binary files) for "poll" yielded nothing.

Submitted by m.ewald on Tue, 03/05/2024 - 11:57

Reducing the directory monitoring poll interval in the partner settings to 2s did not help.

The exact delay seems to be 36 seconds, non-varying.