1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137 138 139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169 170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196 197 198 199 200 201 202 203 204 205 206 207 208 209 210 211 212 213 214 215 216 217 218 219 220 221 222 223 224 225 226 227 228 229 230 231 232 233 234 235 236 237 238 239 240 241 242 243 244 245 246 247 248 249 250 251 252 253 254 255 256 257 258 259 260 261 262 263 264 265 266 267 268 269 270 271 272 273 274 275 276 277 278 279 280 281 282 283 284 285 286 287 288 289 290 291 292 293 294 295 296 297 298 299 300 301 302 303 304 305 306 307 308 309 310 311 312 313 314 315 316 317 318 319 320 321 322 323 324 325 326 327 328 329 330 331 332 333 334 335 336 337 338 339 340 341 342 343 344 345 346 347 348 349 350 351 352 353 354 355 356 357 358 359 360 361 362 363 364 365 366 367 368 369 370 371 372 373 374 375 376 377 378 379 380 381 382 383 384 385 386 387 388 389 390 391 392 393 394 395 396 397 398 399 400 401 402 403 404 405 406 407 408 409 410 411 412 413 414 415 416 417 418 419 420 421 422 423 424 425 426 427 428 429 430 431 432 433 434 435 436 437 438 439 440 441 442 443 444 445 446 447 448 449 450 451 452 453 454 455 456 457 458 459 460 461 462 463 464 465 466 467 468 469 470 471 472 473 474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492 493 494 495 496 497 498 499 500 501 502 503 504 505 506 507 508 509 510 511 512 513 514 515 516 517 518 519 520 521 522 523 524 525 526 527 528 529 530 531 532 533 534 535 536 537 538 539 540 541 542 543 544 545 546 547 548 549 550 551 552 553 554 555 556 557 558 559 560 561 562 563 564 565 566 567 568 569 570 571 572 573 574 575 576 577 578 579 580 581 582 583 584 585 586 587 588 589 590 591 592 593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628 629 630 631 632 633 634 635 636 637 638 639 640 641 642 643 644 645 646 647 648 649 650 651 652 653 654 655 656 657 658 659 660 661 662 663 664 665 666 667 668 669 670 671 672 673 674 675 676 677 678 679 680 681 682 683 684 685 686 687 688 689 690 691 692 693 694 695 696 697 698 699 700 701 702 703 704 705 706 707 708 709 710 711 712 713 714 715 716 717 718 719 720 721 722 723 724 725 726 727 728 729 730 731 732 733 734 735 736 737 738 739 740 741 742 743 744 745 746 747 748 749 750 751 752 753 754 755 756 757 758 759 760 761 762 763 764 765 766 767 768 769 770 771 772 773 774 775 776 777 778 779 780 781 782 783 784 785 786 787 788 789 790
|
<!doctype html public "-//W3C//DTD HTML 4.01 Transitional//EN"
"http://www.w3.org/TR/html4/loose.dtd">
<html>
<head>
<title>Postfix Bottleneck Analysis</title>
<meta http-equiv="Content-Type" content="text/html; charset=us-ascii">
</head>
<body>
<h1><img src="postfix-logo.jpg" width="203" height="98" ALT="">Postfix Bottleneck Analysis</h1>
<hr>
<h2>Purpose of this document </h2>
<p> This document describes the <a href="qshape.1.html">qshape(1)</a> program which helps the
administrator understand the Postfix queue message distribution
sorted by time and by sender or recipient domain. <a href="qshape.1.html">qshape(1)</a> is
bundled with the Postfix 2.1 source under the "auxiliary" directory.
</p>
<p> In order to understand the output of <a href="qshape.1.html">qshape(1)</a>, it useful to
understand the various Postfix queues. To this end the role of each
Postfix queue directory is described briefly in the "Background
info: Postfix queue directories" section near the end of this
document. </p>
<p> This document covers the following topics: </p>
<ul>
<li><a href="#qshape">Introducing the qshape tool</a>
<li><a href="#trouble_shooting">Trouble shooting with qshape</a>
<li><a href="#healthy">Example 1: Healthy queue</a>
<li><a href="#dictionary_bounce">Example 2: Deferred queue full of
dictionary attack bounces</a></li>
<li><a href="#active_congestion">Example 3: Congestion in the active
queue</a></li>
<li><a href="#backlog">Example 4: High volume destination backlog</a>
<li><a href="#queues">Background info: Postfix queue directories</a>
<ul>
<li> <a href="#maildrop_queue"> The "maildrop" queue </a>
<li> <a href="#hold_queue"> The "hold" queue </a>
<li> <a href="#incoming_queue"> The "incoming" queue </a>
<li> <a href="#active_queue"> The "active" queue </a>
<li> <a href="#deferred_queue"> The "deferred" queue </a>
</ul>
<li><a href="#credits">Credits</a>
</ul>
<h2><a name="qshape">Introducing the qshape tool</a></h2>
<p> When mail is draining slowly or the queue is unexpectedly large,
run <a href="qshape.1.html">qshape(1)</a> as the super-user (root) to help zero in on the problem.
The <a href="qshape.1.html">qshape(1)</a> program displays a tabular view of the Postfix queue
contents. </p>
<ul>
<li> <p> On the horizontal axis, it displays the queue age with
fine granularity for recent messages and (geometrically) less fine
granularity for older messages. </p>
<li> <p> The vertical axis displays the destination (or with the
"-s" switch the sender) domain. Domains with the most messages are
listed first. </p>
</ul>
<p> For example, in the output below we see the top 10 lines of
the (mostly forged) sender domain distribution for captured spam
in the "<a href="QSHAPE_README.html#hold_queue">hold" queue</a>: </p>
<blockquote>
<pre>
$ qshape -s hold | head
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 486 0 0 1 0 0 2 4 20 40 419
yahoo.com 14 0 0 1 0 0 0 0 1 0 12
extremepricecuts.net 13 0 0 0 0 0 0 0 2 0 11
ms35.hinet.net 12 0 0 0 0 0 0 0 0 1 11
winnersdaily.net 12 0 0 0 0 0 0 0 2 0 10
hotmail.com 11 0 0 0 0 0 0 0 0 1 10
worldnet.fr 6 0 0 0 0 0 0 0 0 0 6
ms41.hinet.net 6 0 0 0 0 0 0 0 0 0 6
osn.de 5 0 0 0 0 0 1 0 0 0 4
</pre>
</blockquote>
<ul>
<li> <p> The "T" column shows the total (in this case sender) count
for each domain. The columns with numbers above them, show counts
for messages aged fewer than that many minutes, but not younger
than the age limit for the previous column. The row labeled "TOTAL"
shows the total count for all domains. </p>
<li> <p> In this example, there are 14 messages allegedly from
yahoo.com, 1 between 10 and 20 minutes old, 1 between 320 and 640
minutes old and 12 older than 1280 minutes (1440 minutes in a day).
</p>
</ul>
<p> By default, qshape shows statistics for the union of both the
<a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queues</a> which are the most relevant queues to
look at when analyzing performance. </p>
<p> One can request an alternate list of queues: </p>
<blockquote>
<pre>
$ qshape deferred | less
$ qshape incoming active deferred | less
</pre>
</blockquote>
<p> this will show the age distribution of the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> or
the union of the incoming active and <a href="QSHAPE_README.html#deferred_queue">deferred queues</a>. </p>
<p> Command line options control the number of display "buckets",
the age limit for the smallest bucket, display of parent domain
counts and so on. The "-h" option outputs a summary of the available
switches. </p>
<h2><a name="trouble_shooting">Trouble shooting with qshape</a>
</h2>
<p> Large numbers in the qshape output represent a large number of
messages that are destined to (or alleged to come from) a particular
domain. It should be possible to tell at a glance which domains
dominate the queue sender or recipient counts, approximately when
a burst of mail started, and when it stopped. </p>
<p> The problem destinations or sender domains appear near the top
left corner of the output table. Remember that the <a href="QSHAPE_README.html#active_queue">active queue</a>
can accommodate up to 20000 ($<a href="postconf.5.html#qmgr_message_active_limit">qmgr_message_active_limit</a>) messages.
To check wether this limit has been reached, use: </p>
<blockquote>
<pre>
$ qshape -s active | head <i>(show sender statistics)</i>
</pre>
</blockquote>
<p> If the total sender count is below 20000 the <a href="QSHAPE_README.html#active_queue">active queue</a> is
not yet saturated, any high volume sender domains show near the
top of the output.
<p> The <a href="QSHAPE_README.html#active_queue">active queue</a> is also limited to at most 20000 recipient
addresses ($<a href="postconf.5.html#qmgr_message_recipient_limit">qmgr_message_recipient_limit</a>). To check for exhaustion
of this limit use: </p>
<blockquote>
<pre>
$ qshape active | head <i>(show recipient statistics)</i>
</pre>
</blockquote>
<p> Having found the high volume domains, it is often useful to
search the logs for recent messages pertaining to the domains in
question. </p>
<blockquote>
<pre>
# Find deliveries to example.com
#
$ tail -10000 /var/log/maillog |
egrep -i ': to=<.*@example\.com>,' |
less
# Find messages from example.com
#
$ tail -10000 /var/log/maillog |
egrep -i ': from=<.*@example\.com>,' |
less
</pre>
</blockquote>
<p> You may want to drill in on some specific queue ids: </p>
<blockquote>
<pre>
# Find all messages for a specific queue id.
#
$ tail -10000 /var/log/maillog | egrep ': 2B2173FF68: '
</pre>
</blockquote>
<p> Also look for queue manager warning messages in the log. These
warnings can suggest strategies to reduce congestion. </p>
<blockquote>
<pre>
$ egrep 'qmgr.*(panic|fatal|error|warning):' /var/log/maillog
</pre>
</blockquote>
<p> When all else fails try the Postfix mailing list for help, but
please don't forget to include the top 10 or 20 lines of <a href="qshape.1.html">qshape(1)</a>
output. </p>
<h2><a name="healthy">Example 1: Healthy queue</a></h2>
<p> When looking at just the <a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queues</a>, under
normal conditions (no congestion) the <a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queues</a>
are nearly empty. Mail leaves the system almost as quickly as it
comes in or is deferred without congestion in the <a href="QSHAPE_README.html#active_queue">active queue</a>.
</p>
<blockquote>
<pre>
$ qshape <i>(show <a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queue</a> status)</i>
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 5 0 0 0 1 0 0 0 1 1 2
meri.uwasa.fi 5 0 0 0 1 0 0 0 1 1 2
</pre>
</blockquote>
<p> If one looks at the two queues separately, the <a href="QSHAPE_README.html#incoming_queue">incoming queue</a>
is empty or perhaps briefly has one or two messages, while the
<a href="QSHAPE_README.html#active_queue">active queue</a> holds more messages and for a somewhat longer time:
</p>
<blockquote>
<pre>
$ qshape incoming
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 0 0 0 0 0 0 0 0 0 0 0
$ qshape active
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 5 0 0 0 1 0 0 0 1 1 2
meri.uwasa.fi 5 0 0 0 1 0 0 0 1 1 2
</pre>
</blockquote>
<h2><a name="dictionary_bounce">Example 2: Deferred queue full of
dictionary attack bounces</a></h2>
<p> This is from a server where recipient validation is not yet
available for some of the <a href="VIRTUAL_README.html#canonical">hosted domains</a>. Dictionary attacks on
the unvalidated domains result in bounce backscatter. The bounces
dominate the queue, but with proper tuning they do not saturate the
<a href="QSHAPE_README.html#incoming_queue">incoming</a> or <a href="QSHAPE_README.html#active_queue">active queues</a>. The high volume of deferred mail is not
a direct cause for alarm. </p>
<blockquote>
<pre>
$ qshape deferred | head
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 2234 4 2 5 9 31 57 108 201 464 1353
heyhihellothere.com 207 0 0 1 1 6 6 8 25 68 92
pleazerzoneprod.com 105 0 0 0 0 0 0 0 5 44 56
groups.msn.com 63 2 1 2 4 4 14 14 14 8 0
orion.toppoint.de 49 0 0 0 1 0 2 4 3 16 23
kali.com.cn 46 0 0 0 0 1 0 2 6 12 25
meri.uwasa.fi 44 0 0 0 0 1 0 2 8 11 22
gjr.paknet.com.pk 43 1 0 0 1 1 3 3 6 12 16
aristotle.algonet.se 41 0 0 0 0 0 1 2 11 12 15
</pre>
</blockquote>
<p> The domains shown are mostly bulk-mailers and all the volume
is the tail end of the time distribution, showing that short term
arrival rates are moderate. Larger numbers and lower message ages
are more indicative of current trouble. Old mail still going nowhere
is largely harmless so long as the active and <a href="QSHAPE_README.html#incoming_queue">incoming queues</a> are
short. We can also see that the groups.msn.com undeliverables are
low rate steady stream rather than a concentrated dictionary attack
that is now over. </p>
<blockquote>
<pre>
$ qshape -s deferred | head
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 2193 4 4 5 8 33 56 104 205 465 1309
MAILER-DAEMON 1709 4 4 5 8 33 55 101 198 452 849
example.com 263 0 0 0 0 0 0 0 0 2 261
example.org 209 0 0 0 0 0 1 3 6 11 188
example.net 6 0 0 0 0 0 0 0 0 0 6
example.edu 3 0 0 0 0 0 0 0 0 0 3
example.gov 2 0 0 0 0 0 0 0 1 0 1
example.mil 1 0 0 0 0 0 0 0 0 0 1
</pre>
</blockquote>
<p> Looking at the sender distribution, we see that as expected
most of the messages are bounces. </p>
<h2><a name="active_congestion">Example 3: Congestion in the active
queue</a></h2>
<p> This example is taken from a Feb 2004 discussion on the Postfix
Users list. Congestion was reported with the active and incoming
queues large and not shrinking despite very large delivery agent
process limits. The thread is archived at:
<a href="http://groups.google.com/groups?th=636626c645f5bbde">http://groups.google.com/groups?th=636626c645f5bbde</a> </p>
<p> Using an older version of <a href="qshape.1.html">qshape(1)</a> it was quickly determined
that all the messages were for just a few destinations: </p>
<blockquote>
<pre>
$ qshape <i>(show <a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queue</a> status)</i>
T A 5 10 20 40 80 160 320 320+
TOTAL 11775 9996 0 0 1 1 42 94 221 1420
user.sourceforge.net 7678 7678 0 0 0 0 0 0 0 0
lists.sourceforge.net 2313 2313 0 0 0 0 0 0 0 0
gzd.gotdns.com 102 0 0 0 0 0 0 0 2 100
</pre>
</blockquote>
<p> The "A" column showed the count of messages in the <a href="QSHAPE_README.html#active_queue">active queue</a>,
and the numbered columns showed totals for the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a>. At
10000 messages (Postfix 1.x <a href="QSHAPE_README.html#active_queue">active queue</a> size limit) the active
queue is full. The incoming was growing rapidly. </p>
<p> With the trouble destinations clearly identified, the administrator
quickly found and fixed the problem. It is substantially harder to
glean the same information from the logs. While a careful reading
of <a href="mailq.1.html">mailq(1)</a> output should yield similar results, it is much harder
to gauge the magnitude of the problem by looking at the queue
one message at a time. </p>
<h2><a name="backlog">Example 4: High volume destination backlog</a></h2>
<p> When a site you send a lot of email to is down or slow, mail
messages will rapidly build up in the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a>, or worse, in
the <a href="QSHAPE_README.html#active_queue">active queue</a>. The qshape output will show large numbers for
the destination domain in all age buckets that overlap the starting
time of the problem: </p>
<blockquote>
<pre>
$ qshape deferred | head
T 5 10 20 40 80 160 320 640 1280 1280+
TOTAL 5000 200 200 400 800 1600 1000 200 200 200 200
highvolume.com 4000 160 160 320 640 1280 1440 0 0 0 0
...
</pre>
</blockquote>
<p> Here the "highvolume.com" destination is continuing to accumulate
deferred mail. The <a href="QSHAPE_README.html#incoming_queue">incoming</a> and <a href="QSHAPE_README.html#active_queue">active queues</a> are fine, but the
<a href="QSHAPE_README.html#deferred_queue">deferred queue</a> started growing some time between 1 and 2 hours ago
and continues to grow. </p>
<p> If the high volume destination is not down, but is instead
slow, one might see similar congestion in the <a href="QSHAPE_README.html#active_queue">active queue</a>. Active
queue congestion is a greater cause for alarm; one might need to
take measures to ensure that the mail is deferred instead or even
add an <a href="access.5.html">access(5)</a> rule asking the sender to try again later. </p>
<p> If a high volume destination exhibits frequent bursts of
consecutive connections refused by all MX hosts or "421 Server busy
errors", it is possible for the queue manager to mark the destination
as "dead" despite the transient nature of the errors. The destination
will be retried again after the expiration of a $<a href="postconf.5.html#minimal_backoff_time">minimal_backoff_time</a>
timer. If the error bursts are frequent enough it may be that only
a small quantity of email is delivered before the destination is
again marked "dead". </p>
<p> The MTA that has been observed most frequently to exhibit such
bursts of errors is Microsoft Exchange, which refuses connections
under load. Some proxy virus scanners in front of the Exchange
server propagate the refused connection to the client as a "421"
error. </p>
<p> Note that it is now possible to configure Postfix to exhibit
similarly erratic behavior by misconfiguring the <a href="anvil.8.html">anvil(8)</a> server
(not included in Postfix 2.1.). Do not use <a href="anvil.8.html">anvil(8)</a> for steady-state
rate limiting, its purpose is DoS prevention and the rate limits
set should be very generous! </p>
<p> In the long run it is hoped that the Postfix dead host detection
and concurrency control mechanism will be tuned to be more "noise"
tolerant. If one finds oneself needing to deliver a high volume
of mail to a destination that exhibits frequent brief bursts of
errors, there is a subtle workaround. </p>
<ul>
<li> <p> In master.cf set up a dedicated clone of the "smtp"
transport for the destination in question. </p>
<li> <p> In master.cf configure a reasonable process limit for the
transport (a number in the 10-20 range is typical). </p>
<li> <p> IMPORTANT!!! In main.cf configure a very large initial
and destination concurrency limit for this transport (say 200). </p>
<pre>
/etc/postfix/main.cf:
<a href="postconf.5.html#initial_destination_concurrency">initial_destination_concurrency</a> = 200
<i>transportname</i>_destination_concurrency_limit = 200
</pre>
<p> Where <i>transportname</i> is the name of the master.cf entry
in question. </p>
</ul>
<p> The effect of this surprising configuration is that up to 200
consecutive errors are tolerated without marking the destination
dead, while the total concurrency remains reasonable (10-20
processes). This trick is only for a very specialized situation:
high volume delivery into a channel with multi-error bursts
that is capable of high throughput, but is repeatedly throttled by
the bursts of errors.
<p> When a destination is unable to handle the load even after the
Postfix process limit is reduced to 1, a desperate measure is to
insert brief delays between delivery attempts. </p>
<ul>
<li> <p> In the transport map entry for the problem destination,
specify a dead host as the primary nexthop. </p>
<li> <p> In the master.cf entry for the transport specify the
problem destination as the <a href="postconf.5.html#fallback_relay">fallback_relay</a> and specify a small
<a href="postconf.5.html#smtp_connect_timeout">smtp_connect_timeout</a> value. </p>
<pre>
/etc/postfix/transport:
problem.example.com slow:[dead.host]
/etc/postfix/master.cf:
# service type private unpriv chroot wakeup maxproc command
slow unix - - n - 1 smtp
-o <a href="postconf.5.html#fallback_relay">fallback_relay</a>=problem.example.com
-o <a href="postconf.5.html#smtp_connect_timeout">smtp_connect_timeout</a>=1
</pre>
</ul>
<p> This solution forces the Postfix <a href="smtp.8.html">smtp(8)</a> client to wait for
$<a href="postconf.5.html#smtp_connect_timeout">smtp_connect_timeout</a> seconds between deliveries. The solution
depends on Postfix connection management details, and needs to be
updated when SMTP connection caching is introduced. </p>
<p> Hopefully a more elegant solution to these problems will be
found in the future. </p>
<h2><a name="queues">Background info: Postfix queue directories</a></h2>
<p> The following sections describe Postfix queues: their purpose,
what normal behavior looks like, and how to diagnose abnormal
behavior. </p>
<h3> <a name="maildrop_queue"> The "maildrop" queue </a> </h3>
<p> Messages that have been submitted via the Postfix <a href="sendmail.1.html">sendmail(1)</a>
command, but not yet brought into the main Postfix queue by the
<a href="pickup.8.html">pickup(8)</a> service, await processing in the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a>. Messages
can be added to the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a> even when the Postfix system
is not running. They will begin to be processed once Postfix is
started. </p>
<p> The "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a> is drained by the single threaded <a href="pickup.8.html">pickup(8)</a>
service scanning the queue directory periodically or when notified
of new message arrival by the <a href="postdrop.1.html">postdrop(1)</a> program. The <a href="postdrop.1.html">postdrop(1)</a>
program is a setgid helper that allows the unprivileged Postfix
<a href="sendmail.1.html">sendmail(1)</a> program to inject mail into the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a> and
to notify the <a href="pickup.8.html">pickup(8)</a> service of its arrival. </p>
<p> All mail that enters the main Postfix queue does so via the
<a href="cleanup.8.html">cleanup(8)</a> service. The cleanup service is responsible for envelope
and header rewriting, header and body regular expression checks,
automatic bcc recipient processing and guaranteed insertion of the
message into the Postfix "<a href="QSHAPE_README.html#incoming_queue">incoming" queue</a>. </p>
<p> In the absence of excessive CPU consumption in <a href="cleanup.8.html">cleanup(8)</a> header
or body regular expression checks or other software consuming all
available CPU resources, Postfix performance is disk I/O bound.
The rate at which the <a href="pickup.8.html">pickup(8)</a> service can inject messages into
the queue is largely determined by disk access times, since the
<a href="cleanup.8.html">cleanup(8)</a> service must commit the message to stable storage before
returning success. The same is true of the <a href="postdrop.1.html">postdrop(1)</a> program
writing the message to the "maildrop" directory. </p>
<p> As the pickup service is single threaded, it can only deliver
one message at a time at a rate that does not exceed the reciprocal
disk I/O latency (+ CPU if not negligible) of the cleanup service.
</p>
<p> Congestion in this queue is indicative of an excessive local
message submission rate or perhaps excessive CPU consumption in
the <a href="cleanup.8.html">cleanup(8)</a> service due to excessive <a href="postconf.5.html#body_checks">body_checks</a>. </p>
<p> Note, that once the <a href="QSHAPE_README.html#active_queue">active queue</a> is full, the cleanup service
will attempt to slow down message injection by pausing $<a href="postconf.5.html#in_flow_delay">in_flow_delay</a>
for each message. In this case "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a> congestion may be
a consequence of congestion downstream, rather than a problem in
its own right. </p>
<p> Note also, that one should not attempt to deliver large volumes
of mail via the <a href="pickup.8.html">pickup(8)</a> service. High volume sites must avoid
using content filters that reinject scanned mail via Postfix
<a href="sendmail.1.html">sendmail(1)</a> and <a href="postdrop.1.html">postdrop(1)</a>. </p>
<p> A high arrival rate of locally submitted mail may be an indication
of an uncaught forwarding loop, or a run-away notification program.
Try to keep the volume of local mail injection to a moderate level.
</p>
<p> The "postsuper -r" command can place selected messages into
the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a> for reprocessing. This is most useful for
resetting any stale <a href="postconf.5.html#content_filter">content_filter</a> settings. Requeuing a large number
of messages using "postsuper -r" can clearly cause a spike in the
size of the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a>. </p>
<h3> <a name="hold_queue"> The "hold" queue </a> </h3>
<p> The administrator can define "smtpd" <a href="access.5.html">access(5)</a> policies, or
<a href="cleanup.8.html">cleanup(8)</a> header/body checks that cause messages to be automatically
diverted from normal processing and placed indefinitely in the
"<a href="QSHAPE_README.html#hold_queue">hold" queue</a>. Messages placed in the "hold" queue stay there until
the administrator intervenes. No periodic delivery attempts are
made for messages in the "<a href="QSHAPE_README.html#hold_queue">hold" queue</a>. The <a href="postsuper.1.html">postsuper(1)</a> command
can be used to manually release messages into the "<a href="QSHAPE_README.html#deferred_queue">deferred" queue</a>.
</p>
<p> Messages can potentially stay in the "<a href="QSHAPE_README.html#hold_queue">hold" queue</a> for a time
exceeding the normal maximal queue lifetime (after which undelivered
messages are bounced back to the sender). If such "old" messages
need to be released from the "<a href="QSHAPE_README.html#hold_queue">hold" queue</a>, they should typically
be moved into the "<a href="QSHAPE_README.html#maildrop_queue">maildrop" queue</a>, so that the message gets a new
timestamp and is given more than one opportunity to be delivered.
Messages that are "young" can be moved directly into the "deferred"
queue. </p>
<p> The "<a href="QSHAPE_README.html#hold_queue">hold" queue</a> plays little role in Postfix performance, and
monitoring of the "<a href="QSHAPE_README.html#hold_queue">hold" queue</a> is typically more closely motivated
by tracking spam and malware, than by performance issues. </p>
<h3> <a name="incoming_queue"> The "incoming" queue </a> </h3>
<p> All new mail entering the Postfix queue is written by the
<a href="cleanup.8.html">cleanup(8)</a> service into the "<a href="QSHAPE_README.html#incoming_queue">incoming" queue</a>. New queue files are
created owned by the "postfix" user with an access bitmask (or
mode) of 0600. Once a queue file is ready for further processing
the <a href="cleanup.8.html">cleanup(8)</a> service changes the queue file mode to 0700 and
notifies the queue manager of new mail arrival. The queue manager
ignores incomplete queue files whose mode is 0600, as these are
still being written by cleanup. </p>
<p> The queue manager scans the <a href="QSHAPE_README.html#incoming_queue">incoming queue</a> bringing any new
mail into the "<a href="QSHAPE_README.html#active_queue">active" queue</a> if the active queue resource limits
have not been exceeded. By default, the <a href="QSHAPE_README.html#active_queue">active queue</a> accommodates
at most 20000 messages. Once the <a href="QSHAPE_README.html#active_queue">active queue</a> message limit is
reached, the queue manager stops scanning the incoming (and deferred,
see below) queue. </p>
<p> Under normal conditions the <a href="QSHAPE_README.html#incoming_queue">incoming queue</a> is nearly empty (has
only mode 0600 files), with the queue manager able to import new
messages into the <a href="QSHAPE_README.html#active_queue">active queue</a> as soon as they become available.
</p>
<p> The <a href="QSHAPE_README.html#incoming_queue">incoming queue</a> grows when the message input rate spikes
above the rate at which the queue manager can import messages into
the <a href="QSHAPE_README.html#active_queue">active queue</a>. The main factor slowing down the queue manager
is transport queries to the trivial-rewrite service. If the queue
manager is routinely not keeping up, consider not using "slow"
lookup services (MySQL, LDAP, ...) for transport lookups or speeding
up the hosts that provide the lookup service. </p>
<p> The <a href="postconf.5.html#in_flow_delay">in_flow_delay</a> parameter is used to clamp the input rate
when the queue manager starts to fall behind. The <a href="cleanup.8.html">cleanup(8)</a> service
will pause for $<a href="postconf.5.html#in_flow_delay">in_flow_delay</a> seconds before creating a new queue
file if it cannot obtain a "token" from the queue manager. </p>
<p> Since the number of <a href="cleanup.8.html">cleanup(8)</a> processes is limited in most
cases by the SMTP server concurrency, the input rate can exceed
the output rate by at most "SMTP connection count" / $<a href="postconf.5.html#in_flow_delay">in_flow_delay</a>
messages per second. </p>
<p> With a default process limit of 100, and an <a href="postconf.5.html#in_flow_delay">in_flow_delay</a> of
1s, the coupling is strong enough to limit a single run-away injector
to 1 message per second, but is not strong enough to deflect an
excessive input rate from many sources at the same time. </p>
<p> If a server is being hammered from multiple directions, consider
raising the <a href="postconf.5.html#in_flow_delay">in_flow_delay</a> to 10 seconds, but only if the incoming
queue is growing even while the <a href="QSHAPE_README.html#active_queue">active queue</a> is not full and the
trivial-rewrite service is using a fast transport lookup mechanism.
</p>
<h3> <a name="active_queue"> The "active" queue </a> </h3>
<p> The queue manager is a delivery agent scheduler; it works to
ensure fast and fair delivery of mail to all destinations within
designated resource limits. </p>
<p> The <a href="QSHAPE_README.html#active_queue">active queue</a> is somewhat analogous to an operating system's
process run queue. Messages in the <a href="QSHAPE_README.html#active_queue">active queue</a> are ready to be
sent (runnable), but are not necessarily in the process of being
sent (running). </p>
<p> While most Postfix administrators think of the "<a href="QSHAPE_README.html#active_queue">active" queue</a>
as a directory on disk, the real "<a href="QSHAPE_README.html#active_queue">active" queue</a> is a set of data
structures in the memory of the queue manager process. </p>
<p> Messages in the "<a href="QSHAPE_README.html#maildrop_queue">maildrop"</a>, "<a href="QSHAPE_README.html#hold_queue">hold"</a>, "<a href="QSHAPE_README.html#incoming_queue">incoming"</a> and "deferred"
queues (see below) do not occupy memory; they are safely stored on
disk waiting for their turn to be processed. The envelope information
for messages in the "<a href="QSHAPE_README.html#active_queue">active" queue</a> is managed in memory, allowing
the queue manager to do global scheduling, allocating available
delivery agent processes to an appropriate message in the active
queue. </p>
<p> Within the <a href="QSHAPE_README.html#active_queue">active queue</a>, (multi-recipient) messages are broken
up into groups of recipients that share the same transport/nexthop
combination; the group size is capped by the transport's recipient
concurrency limit. </p>
<p> Multiple recipient groups (from one or more messages) are queued
for delivery via the common transport/nexthop combination. The
destination concurrency limit for the transports caps the number
of simultaneous delivery attempts for each nexthop. Transports with
a recipient concurrency limit of 1 are special: these are grouped
by the actual recipient address rather than the nexthop, thereby
enabling per-recipient concurrency limits rather than per-domain
concurrency limits. Per-recipient limits are appropriate when
performing final delivery to mailboxes rather than when relaying
to a remote server. </p>
<p> Congestion occurs in the <a href="QSHAPE_README.html#active_queue">active queue</a> when one or more destinations
drain slower than the corresponding message input rate. If a
destination is down for some time, the queue manager will mark it
dead, and immediately defer all mail for the destination without
trying to assign it to a delivery agent. In this case the messages
will quickly leave the <a href="QSHAPE_README.html#active_queue">active queue</a> and end up in the deferred
queue. If the destination is instead simply slow, or there is a
problem causing an excessive arrival rate the <a href="QSHAPE_README.html#active_queue">active queue</a> will
grow and will become dominated by mail to the congested destination.
</p>
<p> The only way to reduce congestion is to either reduce the input
rate or increase the throughput. Increasing the throughput requires
either increasing the concurrency or reducing the latency of
deliveries. </p>
<p> For high volume sites a key tuning parameter is the number of
"smtp" delivery agents allocated to the "smtp" and "relay" transports.
High volume sites tend to send to many different destinations, many
of which may be down or slow, so a good fraction of the available
delivery agents will be blocked waiting for slow sites. Also mail
destined across the globe will incur large SMTP command-response
latencies, so high message throughput can only be achieved with
more concurrent delivery agents. </p>
<p> The default "smtp" process limit of 100 is good enough for most
sites, and may even need to be lowered for sites with low bandwidth
connections (no use increasing concurrency once the network pipe
is full). When one finds that the queue is growing on an "idle"
system (CPU, disk I/O and network not exhausted) the remaining
reason for congestion is insufficient concurrency in the face of
a high average latency. If the number of outbound SMTP connections
(either ESTABLISHED or SYN_SENT) reaches the process limit, mail
is draining slowly and the system and network are not loaded, raise
the "smtp" and/or "relay" process limits! </p>
<p> Especially for the "relay" transport, consider lower SMTP
connection timeouts (1-5 seconds) and higher than default destination
concurrency limits. Compute the expected latency when 1 out of N
of the MX hosts for a high volume site is down and not responding,
and make sure that the configured concurrency divided by this
latency exceeds the required steady-state message rate. If the
destination is managed by you, consider load balancers in front of
groups of MX hosts. Load balancers have higher uptime and will be
able to hide individual MX host failures. </p>
<p> If necessary, dedicate and tune custom transports for high
volume destinations. </p>
<p> Another common cause of congestion is unwarranted flushing of
the entire <a href="QSHAPE_README.html#deferred_queue">deferred queue</a>. The deferred queue holds messages that
are likely to fail to be delivered and are also likely to be slow
to fail delivery (timeouts). This means that the most common reaction
to a large <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> (flush it!) is more than likely counter-
productive, and is likely to make the problem worse. Do not flush
the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> unless you expect that most of its content has
recently become deliverable (e.g. <a href="postconf.5.html#relayhost">relayhost</a> back up after an outage)!
</p>
<p> Note that whenever the queue manager is restarted, there may
already be messages in the <a href="QSHAPE_README.html#active_queue">active queue</a> directory, but the "real"
<a href="QSHAPE_README.html#active_queue">active queue</a> in memory is empty. In order to recover the in-memory
state, the queue manager moves all the <a href="QSHAPE_README.html#active_queue">active queue</a> messages
back into the <a href="QSHAPE_README.html#incoming_queue">incoming queue</a>, and then uses its normal incoming
queue scan to refill the <a href="QSHAPE_README.html#active_queue">active queue</a>. The process of moving all
the messages back and forth, redoing transport table (<a href="trivial-rewrite.8.html">trivial-rewrite(8)</a>
resolve service) lookups, and re-importing the messages back into
memory is expensive. At all costs, avoid frequent restarts of the
queue manager. </p>
<h3> <a name="deferred_queue"> The "deferred" queue </a> </h3>
<p> When all the deliverable recipients for a message are delivered,
and for some recipients delivery failed for a transient reason (it
might succeed later), the message is placed in the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a>.
</p>
<p> The queue manager scans the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> periodically. The
scan interval is controlled by the <a href="postconf.5.html#queue_run_delay">queue_run_delay</a> parameter.
While a <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> scan is in progress, if an <a href="QSHAPE_README.html#incoming_queue">incoming queue</a>
scan is also in progress (ideally these are brief since the incoming
queue should be short), the queue manager alternates between bringing
a new "incoming" message and a new "deferred" message into the
queue. This "round-robin" strategy prevents starvation of either
the <a href="QSHAPE_README.html#incoming_queue">incoming</a> or the <a href="QSHAPE_README.html#deferred_queue">deferred queues</a>. </p>
<p> Each <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> scan only brings a fraction of the deferred
queue back into the <a href="QSHAPE_README.html#active_queue">active queue</a> for a retry. This is because each
message in the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> is assigned a "cool-off" time when
it is deferred. This is done by time-warping the modification
times of the queue file into the future. The queue file is not
eligible for a retry if its modification time is not yet reached.
</p>
<p> The "cool-off" time is at least $<a href="postconf.5.html#minimal_backoff_time">minimal_backoff_time</a> and at
most $<a href="postconf.5.html#maximal_backoff_time">maximal_backoff_time</a>. The next retry time is set by doubling
the message's age in the queue, and adjusting up or down to lie
within the limits. This means that young messages are initially
retried more often than old messages. </p>
<p> If a high volume site routinely has large <a href="QSHAPE_README.html#deferred_queue">deferred queues</a>, it
may be useful to adjust the <a href="postconf.5.html#queue_run_delay">queue_run_delay</a>, <a href="postconf.5.html#minimal_backoff_time">minimal_backoff_time</a>
and <a href="postconf.5.html#maximal_backoff_time">maximal_backoff_time</a> to provide short enough delays on first
failure, with perhaps longer delays after multiple failures, to
reduce the retransmission rate of old messages and thereby reduce
the quantity of previously deferred mail in the <a href="QSHAPE_README.html#active_queue">active queue</a>. </p>
<p> One common cause of large <a href="QSHAPE_README.html#deferred_queue">deferred queues</a> is failure to validate
recipients at the SMTP input stage. Since spammers routinely launch
dictionary attacks from unrepliable sender addresses, the bounces
for invalid recipient addresses clog the <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> (and at
high volumes proportionally clog the <a href="QSHAPE_README.html#active_queue">active queue</a>). Recipient
validation is strongly recommended through use of the <a href="postconf.5.html#local_recipient_maps">local_recipient_maps</a>
and <a href="postconf.5.html#relay_recipient_maps">relay_recipient_maps</a> parameters. </p>
<p> When a host with lots of deferred mail is down for some time,
it is possible for the entire <a href="QSHAPE_README.html#deferred_queue">deferred queue</a> to reach its retry
time simultaneously. This can lead to a very full <a href="QSHAPE_README.html#active_queue">active queue</a> once
the host comes back up. The phenomenon can repeat approximately
every <a href="postconf.5.html#maximal_backoff_time">maximal_backoff_time</a> seconds if the messages are again deferred
after a brief burst of congestion. Ideally, in the future Postfix
will add a random offset to the retry time (or use a combination
of strategies) to reduce the chances of repeated complete deferred
queue flushes. </p>
<h2><a name="credits">Credits</a></h2>
<p> The <a href="qshape.1.html">qshape(1)</a> program was developed by Victor Duchovni of Morgan
Stanley, who also wrote the initial version of this document. </p>
</body>
</html>
|