Email timeouts

Hi,

I have been experiencing problems with email delivery after uninstalling
Railo and installing Lucee.

Some random emails don’t get delivered to any recipient and in the web
admin I can see this message:

smtpout1.core.ignum.cz timeout occurred after 30 seconds while sending
mail messagejavax.mail.MessagingException: timeout occurred after 30
seconds while sending mail messag
e

I have checked my mail server settings in the
C:/lucee/lib/lucee-server/context/lucee-server.xml file:

And compared them with the corresponding settings from the previous install
of Railo:


Cannot see any particular difference in them. Apart from spool-enable is
“yes” in the first case and “true” in the second one. I guess that should
not be a major issue.

I have asked my host to provide the logs from the mail server. Here they
are, together with some comments from the guy administering the mail server:—

Mar 13 06:28:17 smtpout1 postfix/smtpd12413: connect from
coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 06:28:17 smtpout1
postfix/smtpd12413: EFDEFC1707:
client=coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 06:28:17 smtpout1
postfix/cleanup12438: EFDEFC1707:
message-id=1350148495.2141426224498316.JavaMail.COLDFUSION$@COLDFUSIONMar
13 06:28:18 smtpout1 postfix/qmgr8407: EFDEFC1707:
from=info@4kompetence.cz, size=1585, nrcpt=2 (queue active)Mar 13
06:28:18 smtpout1 postfix/relay40/smtp14943: EFDEFC1707:
to=ms_u_jablicek@quick.cz, relay=smtp-in.iol.cz[194.228.2.70]:25,
delay=0.29, delays=0.06/0/0.2/0.03, dsn=2.0.0, status=sent (250 ok: Message
173954465 accepted)Mar 13 06:28:18 smtpout1 postfix/relay40/smtp15561:
EFDEFC1707: to=info@4kompetence.cz,
relay=aspmx.l.google.com[173.194.65.27]:25, delay=0.77,
delays=0.06/0/0.09/0.63, dsn=2.0.0, status=sent (250 2.0.0 OK 1426224498
fa3si1392549wjd.148 - gsmtp)Mar 13 06:28:18 smtpout1 postfix/qmgr8407:
EFDEFC1707: removedMar 13 06:33:18 smtpout1 postfix/smtpd12413: timeout
after END-OF-MESSAGE from coldfusion.cust.ignum.cz[217.31.49.102]Mar 13
06:33:18 smtpout1 postfix/smtpd12413: disconnect from
coldfusion.cust.ignum.cz[217.31.49.102]

This message was sent but the connection was not terminated properly,
that’s why it caused the connection timeout.

Mar 13 08:18:18 smtpout1 postfix/smtpd681: 7FF35C175C:
client=coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 08:18:18 smtpout1
postfix/cleanup1139: 7FF35C175C:
message-id=283798406.2171426231098558.JavaMail.COLDFUSION$@COLDFUSIONMar
13 08:18:18 smtpout1 postfix/qmgr8407: 7FF35C175C:
from=info@4kompetence.cz, size=1486, nrcpt=2 (queue active)Mar 13
08:18:19 smtpout1 postfix/relay40/smtp2795: 7FF35C175C:
to=info@4kompetence.cz, relay=aspmx.l.google.com[74.125.136.27]:25,
delay=0.87, delays=0.04/0/0.09/0.75, dsn=2.0.0, status=sent (250 2.0.0 OK
1426231099 eo8si1746871wjd.58 - gsmtp)Mar 13 08:23:18 smtpout1
postfix/smtpd681: timeout after END-OF-MESSAGE from
coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 08:23:18 smtpout1
postfix/smtpd681: disconnect from coldfusion.cust.ignum.cz[217.31.49.102]

Basically, the same situation as with the first mail

Mar 13 10:38:30 smtpout1 postfix/smtpd26401: connect from
coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:38:30 smtpout1
postfix/smtpd26401: E6F13C5159:
client=coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:38:30 smtpout1
postfix/cleanup25067: E6F13C5159:
message-id=1668133179.2261426239511077.JavaMail.COLDFUSION$@COLDFUSIONMar
13 10:51:38 smtpout1 postfix/smtpd26401: timeout after DATA (4545388 bytes)
from coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:51:38 smtpout1
postfix/smtpd26401: disconnect from coldfusion.cust.ignum.cz[217.31.49.102]


Would anybody know what’s going on here ? Any hint on this would be greatly
appreciated.

Best Regards

Ivan Rotrekl

Hi Ivan,

I was wondering if you ever got any answers on this question elsewhere. I’m
facing a somewhat similar problem when Lucee gets a 250 response from the
SMTP server but still considers the job failed and places it into Task
Scheduler for a retry.On Friday, March 13, 2015 at 10:18:15 PM UTC+1, Ivan Rotrekl wrote:

Hi,

I have been experiencing problems with email delivery after uninstalling
Railo and installing Lucee.

Some random emails don’t get delivered to any recipient and in the web
admin I can see this message:

smtpout1.core.ignum.cz http://smtpout1.core.ignum.cz timeout occurred
after 30 seconds while sending mail messagejavax.mail.MessagingException:
timeout occurred after 30 seconds while sending mail messag
e

I have checked my mail server settings in the
C:/lucee/lib/lucee-server/context/lucee-server.xml file:

And compared them with the corresponding settings from the previous
install of Railo:


Cannot see any particular difference in them. Apart from spool-enable
is “yes” in the first case and “true” in the second one. I guess that
should not be a major issue.

I have asked my host to provide the logs from the mail server. Here they
are, together with some comments from the guy administering the mail server:


Mar 13 06:28:17 smtpout1 postfix/smtpd12413: connect from
coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 06:28:17 smtpout1
postfix/smtpd12413: EFDEFC1707: client=coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 06:28:17 smtpout1
postfix/cleanup12438: EFDEFC1707:
message-id=1350148495.2141426224498316.JavaMail.COLDFUSION$@COLDFUSIONMar
13 06:28:18 smtpout1 postfix/qmgr8407: EFDEFC1707:
from=<in…@4kompetence.cz <javascript:>>, size=1585, nrcpt=2 (queue
active)Mar 13 06:28:18 smtpout1 postfix/relay40/smtp14943: EFDEFC1707:
to=<ms_u_j…@quick.cz <javascript:>>, relay=smtp-in.iol.cz
http://smtp-in.iol.cz[194.228.2.70]:25, delay=0.29,
delays=0.06/0/0.2/0.03, dsn=2.0.0, status=sent (250 ok: Message 173954465
accepted)Mar 13 06:28:18 smtpout1 postfix/relay40/smtp15561: EFDEFC1707:
to=<in…@4kompetence.cz <javascript:>>, relay=aspmx.l.google.com
http://aspmx.l.google.com[173.194.65.27]:25, delay=0.77,
delays=0.06/0/0.09/0.63, dsn=2.0.0, status=sent (250 2.0.0 OK 1426224498
fa3si1392549wjd.148 - gsmtp)Mar 13 06:28:18 smtpout1 postfix/qmgr8407:
EFDEFC1707: removedMar 13 06:33:18 smtpout1 postfix/smtpd12413: timeout
after END-OF-MESSAGE from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 06:33:18 smtpout1
postfix/smtpd12413: disconnect from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]

This message was sent but the connection was not terminated properly,
that’s why it caused the connection timeout.

Mar 13 08:18:18 smtpout1 postfix/smtpd681: 7FF35C175C:
client=coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 08:18:18 smtpout1
postfix/cleanup1139: 7FF35C175C:
message-id=283798406.2171426231098558.JavaMail.COLDFUSION$@COLDFUSIONMar
13 08:18:18 smtpout1 postfix/qmgr8407: 7FF35C175C:
from=<in…@4kompetence.cz <javascript:>>, size=1486, nrcpt=2 (queue
active)Mar 13 08:18:19 smtpout1 postfix/relay40/smtp2795: 7FF35C175C:
to=<in…@4kompetence.cz <javascript:>>, relay=aspmx.l.google.com
http://aspmx.l.google.com[74.125.136.27]:25, delay=0.87,
delays=0.04/0/0.09/0.75, dsn=2.0.0, status=sent (250 2.0.0 OK 1426231099
eo8si1746871wjd.58 - gsmtp)Mar 13 08:23:18 smtpout1 postfix/smtpd681:
timeout after END-OF-MESSAGE from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 08:23:18 smtpout1
postfix/smtpd681: disconnect from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]

Basically, the same situation as with the first mail

Mar 13 10:38:30 smtpout1 postfix/smtpd26401: connect from
coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:38:30 smtpout1
postfix/smtpd26401: E6F13C5159: client=coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:38:30 smtpout1
postfix/cleanup25067: E6F13C5159:
message-id=1668133179.2261426239511077.JavaMail.COLDFUSION$@COLDFUSIONMar
13 10:51:38 smtpout1 postfix/smtpd26401: timeout after DATA (4545388 bytes)
from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]Mar 13 10:51:38 smtpout1
postfix/smtpd26401: disconnect from coldfusion.cust.ignum.cz
http://coldfusion.cust.ignum.cz[217.31.49.102]


Would anybody know what’s going on here ? Any hint on this would be
greatly appreciated.

Best Regards

Ivan Rotrekl