親バカエンジニアのナレッジ帳

webのエンジニアをやっており、日頃の開発で詰まったことや書き残しておきたいことを載せています。

AWSでのメール送信でタイムアウトが頻繁に発生

SpringBoot + EC2の構成で、メール送信エラーが頻発

SpringBoot + EC2のアプリケーションでメルマガ機能を作成していましたが、どうにもメール送信が途中で止まってしまうということで、ログを確認すると以下のようなエラーログが頻発していました。
要はタイムアウトが発生しているようです。

2019-02-13 16:47:51.263 ERROR 27325 --- [pool-4-thread-1] o.s.s.s.TaskUtils$LoggingErrorHandler    : Unexpected error occurred in scheduled task.

org.springframework.mail.MailSendException: Mail server connection failed; nested exception is com.sun.mail.util.MailConnectException: Couldn't connect to host, port: wwwxxxx.sakura.ne.jp, 25; timeout 5000;
  nested exception is:
java.net.SocketTimeoutException: connect timed out. Failed messages: com.sun.mail.util.MailConnectException: Couldn't connect to host, port: wwwxxxx.sakura.ne.jp, 25; timeout 5000;
  nested exception is:
java.net.SocketTimeoutException: connect timed out
    at org.springframework.mail.javamail.JavaMailSenderImpl.doSend(JavaMailSenderImpl.java:432) ~[spring-context-support-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at org.springframework.mail.javamail.JavaMailSenderImpl.send(JavaMailSenderImpl.java:345) ~[spring-context-support-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at org.springframework.mail.javamail.JavaMailSenderImpl.send(JavaMailSenderImpl.java:340) ~[spring-context-support-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at com.thegate12.batch.SendMailBatch.lambda$execute$0(SendMailBatch.java:77) ~[classes/:1.0-SNAPSHOT]
    at java.util.ArrayList.forEach(ArrayList.java:1255) ~[na:1.8.0_151]
    at com.thegate12.batch.SendMailBatch.execute(SendMailBatch.java:47) ~[classes/:1.0-SNAPSHOT]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_151]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_151]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_151]
    at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_151]
    at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:65) ~[spring-context-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) ~[spring-context-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_151]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [na:1.8.0_151]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [na:1.8.0_151]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [na:1.8.0_151]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_151]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_151]
    at java.lang.Thread.run(Thread.java:748) [na:1.8.0_151]
Caused by: com.sun.mail.util.MailConnectException: Couldn't connect to host, port: wwwxxxx.sakura.ne.jp, 25; timeout 5000
    at com.sun.mail.smtp.SMTPTransport.openServer(SMTPTransport.java:2118) ~[javax.mail-1.5.6.jar:1.5.6]
    at com.sun.mail.smtp.SMTPTransport.protocolConnect(SMTPTransport.java:712) ~[javax.mail-1.5.6.jar:1.5.6]
    at javax.mail.Service.connect(Service.java:366) ~[javax.mail-1.5.6.jar:1.5.6]
    at org.springframework.mail.javamail.JavaMailSenderImpl.connectTransport(JavaMailSenderImpl.java:501) ~[spring-context-support-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    at org.springframework.mail.javamail.JavaMailSenderImpl.doSend(JavaMailSenderImpl.java:421) ~[spring-context-support-4.3.5.RELEASE.jar:4.3.5.RELEASE]
    ... 18 common frames omitted
Caused by: java.net.SocketTimeoutException: connect timed out
    at java.net.PlainSocketImpl.socketConnect(Native Method) ~[na:1.8.0_151]
    at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[na:1.8.0_151]
    at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[na:1.8.0_151]
    at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[na:1.8.0_151]
    at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[na:1.8.0_151]
    at java.net.Socket.connect(Socket.java:589) ~[na:1.8.0_151]
    at com.sun.mail.util.WriteTimeoutSocket.connect(WriteTimeoutSocket.java:113) ~[javax.mail-1.5.6.jar:1.5.6]
    at com.sun.mail.util.SocketFetcher.createSocket(SocketFetcher.java:329) ~[javax.mail-1.5.6.jar:1.5.6]
    at com.sun.mail.util.SocketFetcher.getSocket(SocketFetcher.java:238) ~[javax.mail-1.5.6.jar:1.5.6]
    at com.sun.mail.smtp.SMTPTransport.openServer(SMTPTransport.java:2084) ~[javax.mail-1.5.6.jar:1.5.6]
    ... 22 common frames omitted

さくらサーバのサポートセンターに問い合わせ

この時、メールサーバはさくらを使用していたため、さくらインターネットに問い合わせしたのですが、特にサーバに負荷がかかっているわけでもなく原因は不明との回答でした。

で、お決まりのようにお客様のサービスの不具合については調査できません的な文言も。
いやいや、こっちのサービスの話をしているわけじゃなくて、おまえらのサーバの話をしているんだよ!と、なんだかイライラしていたので、コールセンターの担当者に八つ当たり。

日頃からさくらのサーバを信用していなかったこともあり、ついつい…
担当の方本当にごめんなさい。

本当の原因はEC2の送信制限

原因はなんのこっちゃない、EC2でメールの送信制限がかかっていたことが原因でした。
EC2では、外部のメールサーバを使ってメール送信する際に時間あたりの送信制限がかかってしまいます。

解除申請をすれば簡単に解除できるので、EC2でメルマガなどの大量のメールを送信する時は、事前に解除申請が必要です。

以下よりアクセスして、フォームに入力し審査結果を待つこと1日。
審査が簡単に通りました。

https://aws-portal.amazon.com/gp/aws/html-forms-controller/contactus/ec2-email-limit-rdns-request

これにより、大量にメール送信することが可能になりました。

ちなみに…

本編とは関係ないですが、さくらの担当者いわく、15分毎に約100通程度の制限を設けているとのことでした。
今回の対応とは関係なかったですが、今後メルマガをさくらのサーバで送らないように気をつけないとと思いました。

嫌われる勇気―――自己啓発の源流「アドラー」の教え

嫌われる勇気―――自己啓発の源流「アドラー」の教え

「死」とは何か イェール大学で23年連続の人気講義 日本縮約版

「死」とは何か イェール大学で23年連続の人気講義 日本縮約版

休日が楽しみになる昼ごはん

休日が楽しみになる昼ごはん

おしっこちょっぴりもれたろう

おしっこちょっぴりもれたろう