[ext] dovecot/imap hohe io load

Simon Sutter ssutter at hosttech.ch
Fr Nov 9 16:10:12 CET 2018


Hallo,

>Hi,
>
>mach mal dabei ein iotop - das geht jetzt auch wieder ;) Da siehst du die Last

Ich glaube ich werde falsch verstanden. Ich verwende bereits iotop, aber weil ich nicht immer live dabei sein kann, wird atop zur späteren Untersuchung verwendet.
Bei einem live iotop sehe ich genau dovecot/imap, welches mir 99.9% i/o last generiert.
Ich wollte also bei meiner letzten Frage wissen, wird diese Last alleine durch dovecot, oder von einem vorhergehenden Prozess generiert?
Ist dies überhaupt im Logfile ersichtlich?
Ich sehe beispielsweise keine Logs, wenn jemand seine Mailbox durchsucht, kann dies auch eingeschaltet werden?

Hier noch einmal das Log: https://pastebin.com/czdBp2Qr

>Gruß Michael
>
>Wenn du verstehst, was du tust, wirst du nichts lernen
>
>Am 08.11.18 um 17:34 schrieb Simon Sutter:
>>>> Hallo Ralf, hallo Hajo
>>>>
>>>> Danke vielmals mit dem Tipp für das Log.
>>>> Ich habe es nun eingeschalten und heute war es wieder so weit, dass der Server wieder ins Stocken kam.
>>>> Laut atop ging das Ganze von 08:07 Uhr bis 08:17 Uhr.
>>>
>>> In dem Zeitraum sieht man:
>>>
>>> Nov  8 08:09:24 301 dovecot: service=imap, user=[], ip=[]. Warning: Transaction log file /var/qmail/mailnames/account/user/Maildir/dovecot.index.log was locked for 63 >seconds (appending) ...
>>> Nov  8 08:09:24 301 dovecot: service=imap, user=[], ip=[]. Disconnected in APPEND (1 msgs, 0 secs, 0/12517 bytes) rcvd=178285, sent=10232103 ...
>>> Nov  8 08:16:14 301 dovecot: service=lda, user=[], ip=[]. Warning: Transaction log file /var/qmail/mailnames/account/user/Maildir/dovecot.index.log was locked for 42 >seconds (Mailbox was synchronized) ...
>>> Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir /var/qmail/mailnames/account/user/Maildir: Synchronization took 41 seconds (1 new msgs, 0 flag >change attempts, 0 expunge attempts) Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir /var/qmail/mailnames/account/user/Maildir: >Synchronization took 41 seconds (1 new msgs, 0 flag change attempts, 0 expunge attempts) Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir >/var/qmail/mailnames/account/user/Maildir: Synchronization took 41 seconds (1 new msgs, 0 flag change attempts, 0 expunge attempts) Nov  8 08:16:25 301 dovecot: >service=lda, user=[], ip=[]. Warning: Maildir /var/qmail/mailnames/account/user/Maildir: Synchronization took 41 seconds (1 new msgs, 0 flag change attempts, 0 expunge >attempts) Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir /var/qmail/mailnames/account/user/Maildir: Synchronization took 41 seconds (1 new >msgs, 0 flag change attempts, 0 expunge attempts) Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir /var/qmail/mailnames/account/user/Maildir: >Synchronization took 41 seconds (1 new msgs, 0 flag change attempts, 0 expunge attempts) Nov  8 08:16:25 301 dovecot: service=lda, user=[], ip=[]. Warning: Maildir >/var/qmail/mailnames/account/user/Maildir: Synchronization took 41 seconds (1 new msgs, 0 flag change attempts, 0 expunge attempts) ...
>>> Nov  8 08:16:28 301 dovecot: service=lda, user=[], ip=[]. Warning: 
>>> Maildir /var/qmail/mailnames/account/user/Maildir: Synchronization 
>>> took 42 seconds (1 new msgs, 0 flag >change attempts, 0 expunge 
>>> attempts)
>>>
>>> Sieht für mich so aus als wäre da massiv Disk IO mit massivem IO wait!
>> 
>> Also denkst du es liegt nicht an dovecot selbst, sondern etwas anderes verursacht diese IO wait und das summiert sich dann welches dovecot/imap als den Verursacher darstellt?


Gruss Simon



Mehr Informationen über die Mailingliste Dovecot