Mail Server DOS Hell
It’s been a momentous couple of weeks. The nightmare began one sunny afternoon when I noticed a flurry of activity on both servers here. The drive access lights were on full, and there was the sound of drive noise like I’d never seen before. I decided to access the server via a web application, similar to cpanel, called webmin and see the system messages. The mail log took forever to come up, as it had ballooned to some 120 MB in size, but as soon as it came up I could see what all the noise was about. See below:
Oct 10 13:04:49 s216-232-70-103 postfix/smtpd<sup><a href="#fn16819">16819</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:04:58 s216-232-70-103 postfix/smtpd<sup><a href="#fn16819">16819</a></sup>: 1F39F111D5C: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:04:58 s216-232-70-103 postfix/cleanup<sup><a href="#fn16822">16822</a></sup>: 1F39F111D5C: message-id=<080076058050049054046050051050046055048046054056058049058049057057049057@216.232.70.68>
Oct 10 13:04:58 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 1F39F111D5C: from=<tevisx@sakura-mail.every1.net>, size=608, nrcpt=1 (queue active)
Oct 10 13:04:58 s216-232-70-103 postfix/smtpd<sup><a href="#fn16819">16819</a></sup>: disconnect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:05:09 s216-232-70-103 postfix/smtp<sup><a href="#fn16823">16823</a></sup>: 1F39F111D5C: to=<comerce.br@uol.com.br>, relay=smtp.telus.net[204.209.205.51], delay=12, status=sent (250 Message received: 20051010200504.CZUC29931.priv-edmwes48.telusplanet.net@s216-232-70-103.bc.hsia.telus.net)
Oct 10 13:05:09 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 1F39F111D5C: removed
Oct 10 13:08:27 s216-232-70-103 postfix/smtpd<sup><a href="#fn16826">16826</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:27 s216-232-70-103 postfix/smtpd<sup><a href="#fn16824">16824</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:37 s216-232-70-103 postfix/smtpd<sup><a href="#fn16828">16828</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:42 s216-232-70-103 postfix/smtpd<sup><a href="#fn16827">16827</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:48 s216-232-70-103 postfix/smtpd<sup><a href="#fn16829">16829</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:48 s216-232-70-103 postfix/smtpd<sup><a href="#fn16826">16826</a></sup>: 90919111D5C: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:53 s216-232-70-103 postfix/smtpd<sup><a href="#fn16831">16831</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:54 s216-232-70-103 postfix/smtpd<sup><a href="#fn16828">16828</a></sup>: 06E46111D71: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:54 s216-232-70-103 postfix/smtpd<sup><a href="#fn16833">16833</a></sup>: connect from s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:54 s216-232-70-103 postfix/smtpd<sup><a href="#fn16827">16827</a></sup>: BDE4C111D72: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:55 s216-232-70-103 postfix/cleanup<sup><a href="#fn16834">16834</a></sup>: 06E46111D71: message-id=<AIBSMCAYEAGHDOJVQSMT@sakura-mail.every1.net>
Oct 10 13:08:55 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 06E46111D71: from=<tevisx@sakura-mail.every1.net>, size=2214, nrcpt=17 (queue active)
Oct 10 13:08:55 s216-232-70-103 postfix/smtpd<sup><a href="#fn16824">16824</a></sup>: 10736111D74: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:56 s216-232-70-103 postfix/cleanup<sup><a href="#fn16834">16834</a></sup>: 10736111D74: message-id=<028e01c5cd47$e1a5aab0$b6a7bb72@PEDCITG>
Oct 10 13:08:56 s216-232-70-103 postfix/smtpd<sup><a href="#fn16829">16829</a></sup>: 08A58111D75: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:56 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 10736111D74: from=<ranijox@freemail.savei.net>, size=4124, nrcpt=9 (queue active)
Oct 10 13:08:56 s216-232-70-103 postfix/cleanup<sup><a href="#fn16834">16834</a></sup>: 08A58111D75: message-id=<NYAHWDRIGMKDZBDNTDMO@usairforce.com>
Oct 10 13:08:56 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 08A58111D75: from=<sewebenci@usairforce.com>, size=2124, nrcpt=7 (queue active)
Oct 10 13:08:56 s216-232-70-103 postfix/smtpd<sup><a href="#fn16833">16833</a></sup>: 9024F111D79: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:56 s216-232-70-103 postfix/cleanup<sup><a href="#fn16838">16838</a></sup>: BDE4C111D72: message-id=<EHMCLKFQOSOICHBZUYPG@collegefootballpoll.com>
Oct 10 13:08:56 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: BDE4C111D72: from=<ericksen@collegefootballpoll.com>, size=2261, nrcpt=7 (queue active)
Oct 10 13:08:56 s216-232-70-103 postfix/smtpd<sup><a href="#fn16828">16828</a></sup>: AC317111D7A: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:56 s216-232-70-103 postfix/cleanup<sup><a href="#fn16832">16832</a></sup>: 90919111D5C: message-id=<014f01c5cdd6$71343730$27bad4f3@BUMZQEJ>
Oct 10 13:08:56 s216-232-70-103 postfix/qmgr<sup><a href="#fn12454">12454</a></sup>: 90919111D5C: from=<daselenae@comedycafe.com>, size=4206, nrcpt=7 (queue active)
Oct 10 13:08:56 s216-232-70-103 postfix/smtpd<sup><a href="#fn16824">16824</a></sup>: CDA44111D7B: client=s216-232-70-68.bc.hsia.telus.net[216.232.70.68]
Oct 10 13:08:57 s216-232-70-103 postfix/cleanup<sup><a href="#fn16838">16838</a></sup>: AC317111D7A: message-id=<YOBSBWWHAUXSPURPGVKQ@afghansite.net>
This was just the beginning as eventually both servers would have a relay queue of over 20,000 messages, and the smpt server at Telus would eventually start to deny this traffic. The address 216.232.70.68 is listed as a proxy server, and a known one for allowing outside relaying. The problem is that we are on the same subnet, otherwise, postfix would have immediatley timed out and denied this host relay access. Since I use the SMTP server at Telus for it’s credible PTR (this avoids being listed by spamcop or sorbs for too short a refresh time), the server itself is hardened a begins to deny this kind of constant barrage. Most of the email addresses where unreachable, thereby getting deferred, which means the queue manager just waits a specific period before retrying. A hopeless bottleneck, I then shut the server down and began the long process of deletion of some 20 Thousand deferred, and numbered, text files.
Immediately thereafter, I get a warning from Telus saying that I am risking losing service due to my breaking their anti-spamming rules. Unfortunately for me, they only see my IP and not the proxy in their server logs. I, of course, had sent them a detailed email with snippings of both the email and mail log files to show another IP was the blame, but I sent it via my mail server, quite by accident, and it never got delivered in the mayhem of 20,000 messages. Here’s a sample of the messages being sent, it’s a deffered record, so it’s looks a little harsh:
C? 2062 1183 17 0T
1128977141Ssusanna@idxc.orgA,client_name=s216-232-70-68.bc.hsia.telus.netA
client_address=216.232.70.68A>message_origin=s216-232-70-68.bc.hsia.telus.net
[216.232.70.68]Ahelo_name=sitemail.everyone.netAprotocol_name=ESMTPOtex409@orionpolaris.comR
tex409@orionpolaris.comOtex259@orionpolaris.comRtex259@orionpolaris.comOtetranitrate7@
orionpolaris.comRtetranitrate7@orionpolaris.comOtexagogo@orionpolaris.comRtexagogo@orionpolaris.comO
thorin13@orionpolaris.comRthorin13@orionpolaris.comOthorly@orionpolaris.comRthorly@orionpolaris.comO
thorbald@orionpolaris.comRthorbald@orionpolaris.comOtommytang@orionpolaris.comRtommytang@orionpolaris.comO
tmdance@orionpolaris.comRtmdance@orionpolaris.comOtmkl23@orionpolaris.comRtmkl23@orionpolaris.comO
tndogs@orionpolaris.comRtndogs@orionpolaris.comOtownsend@oriontele.comRtownsend@oriontele.comO
thorten@oriontele.comRthorten@oriontele.comOthreeleggedlou@oriontele.comRthreeleggedlou@oriontele.comO
thornton@oriontele.comRthornton@oriontele.comOthorsvoice@oriontele.comRthorsvoice@oriontele.comO
threescore7@oriontele.comRthreescore7@oriontele.comM
Received: from sitemail.everyone.net (s216-232-70-68.bc.hsia.telus.net [216.232.70.68])
NIby s216-232-70-103.bc.hsia.telus.net (Postfix) with ESMTP id 000011A538;
N&Mon, 10 Oct 2005 13:45:41 -0700 (PDT)N
Received: from 239.144.96.117N7
by 43.182.31.124.idxc.org (Postfix) with SMTP id 88355N
X-Originating-IP: [64.161.50.85]N'X-Originating-Email: [susanna@idxc.org]
NX-Sender: Labovitz@idxc.orgN#From: "Labovitz" <susanna@idxc.org>N#
To: "Ted" <tex409@orionpolaris.com>N(Subject: Would you like to fear NOTHING?
N%Date: Mon, 10 Oct 2005 16:45:42 -0500NMime-Version: 1.0N'Content-Type: text/html;;
format=flowedN+Message-ID: <ONAICCQVORKCBBCVAEKB@idxc.org>N
The message then forwards to a site offering a quick snake-oil fix, and probably some sweet credit card fraud as well. Swell people, eh?
Alive and Well after Server Hell
Kobrashell Hell
|