[Postfixbuch-users] Verzögerung von 33 Sekunden

So Okt 13 21:49:27 CEST 2013

Peer Heinlein <p.heinlein at heinlein-support.de> wrote:

> Am 13.10.2013 21:14, schrieb Andreas Meyer:
> 
> 
> > Nunja, die resolv.conf sieht gut aus:
> > (Nicht dass Du jetzt denkst ich wär ein Weibsbild, wo's drauf ankommt,
> > dass es chique ist)
> >
> > nameserver 213.133.99.99
> > nameserver 213.133.100.100
> > nameserver 213.133.98.98
> > nameserver 2a01:4f8:0:a102::add:9999
> > nameserver 2a01:4f8:0:a0a1::add:1010
> > nameserver 2a01:4f8:0:a111::add:9898
> 
> Die Frage war aber trotzdem nicht, ob es gut aussieht, sondern ob Du
> definitiv alle sechs Nameserver einzeln durchgetestet hast, ob sie in
> unter 1 Sekunde reagieren. Ein "cat /etc/resolv.conf" beantwortet das
> nicht, ein "dig heise.de @213.133.99.99" schon eher (und das für alle
> Systeme).

Die ipv4 nameserver hatte ich gepingt vom Server aus und die haben
in Sekundenbruchteilen reagiert.

> Aber: DNS scheint hier in der Tat nicht die Ursache zu sein.
> 
> > Oct 13 19:17:52 mail.bitcorner.eu /usr/sbin/amavisd[20779]: (20779-10) TIMING-SA total 32105 ms - parse: 0.75 (0.0%), extract_message_metadata: 27050 (84.3%), poll_dns_idle: 27034 (84.2%), get_uri_detail_list: 0.24 (0.0%), tests_pri_-1000: 2 (0.0%), tests_pri_-950: 1.46 (0.0%), tests_pri_-900: 1.12 (0.0%), tests_pri_-400: 8 (0.0%), check_bayes: 7 (0.0%), tests_pri_0: 5031 (15.7%), check_spf: 0.27 (0.0%), check_razor2: 5004 (15.6%), check_pyzor: 0.16 (0.0%), tests_pri_500: 3 (0.0%), get_report: 0.86 (0.0%)
> > Oct 13 19:17:53 mail.bitcorner.eu /usr/sbin/amavisd[20779]: (20779-10) size: 484, TIMING [total 32207 ms] - SMTP greeting: 2 (0%)0, SMTP EHLO: 1 (0%)0, SMTP pre-MAIL: 1 (0%)0, SMTP pre-DATA-flush: 2 (0%)0, SMTP DATA: 0 (0%)0, check_init: 0 (0%)0, digest_hdr: 1 (0%)0, digest_body_dkim: 0 (0%)0, mime_decode: 4 (0%)0, get-file-type1: 11 (0%)0, decompose_part: 0 (0%)0, parts_decode: 0 (0%)0, check_header: 0 (0%)0, AV-scan-1: 6 (0%)0, spam-wb-list: 1 (0%)0, SA msg read: 0 (0%)0, SA parse: 1 (0%)0, SA check: 32100 (100%)100, decide_mail_destiny: 6 (0%)100, notif-quar: 1 (0%)100, fwd-connect: 18 (0%)100, fwd-mail-pip: 10 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 0 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 30 (0%)100, prepare-dsn: 0 (0%)100, main_log_entry: 6 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-2-files: 1 (0%)100, rundown: 1 (0%)100
> > Oct 13 19:18:25 mail.bitcorner.eu /usr/sbin/amavisd[20778]: (20778-12) TIMING-SA total 32108 ms - parse: 0.76 (0.0%), extract_message_metadata: 27050 (84.2%), poll_dns_idle: 27035 (84.2%), get_uri_detail_list: 0.31 (0.0%), tests_pri_-1000: 2 (0.0%), tests_pri_-950: 1.46 (0.0%), tests_pri_-900: 1.08 (0.0%), tests_pri_-400: 9 (0.0%), check_bayes: 8 (0.0%), tests_pri_0: 5033 (15.7%), check_spf: 0.28 (0.0%), check_razor2: 5004 (15.6%), check_pyzor: 0.19 (0.0%), tests_pri_500: 3 (0.0%), get_report: 0.91 (0.0%)
> > 
> > Oct 13 19:18:25 mail.bitcorner.eu /usr/sbin/amavisd[20778]: (20778-12) size: 517, TIMING [total 32182 ms] - SMTP greeting: 2 (0%)0, SMTP EHLO: 1 (0%)0, SMTP pre-MAIL: 0 (0%)0, SMTP pre-DATA-flush: 2 (0%)0, SMTP DATA: 0 (0%)0, check_init: 0 (0%)0, digest_hdr: 1 (0%)0, digest_body_dkim: 0 (0%)0, mime_decode: 4 (0%)0, get-file-type1: 13 (0%)0, decompose_part: 0 (0%)0, parts_decode: 0 (0%)0, check_header: 0 (0%)0, AV-scan-1: 7 (0%)0, spam-wb-list: 1 (0%)0, SA msg read: 0 (0%)0, SA parse: 1 (0%)0, SA check: 32103 (100%)100, decide_mail_destiny: 6 (0%)100, notif-quar: 0 (0%)100, fwd-connect: 4 (0%)100, fwd-mail-pip: 2 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 0 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 23 (0%)100, prepare-dsn: 1 (0%)100, main_log_entry: 5 (0%)100, update_snmp: 2 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-2-files: 0 (0%)100, rundown: 0 (0%)100
> 
> Naja, wie man sieht braucht er rund 27 Sekunden um die Mail auszupacken.
> 
> Da kann man natürlich echt nicht mehr viel zu sagen, außer "was um
> Himmels Willen ist das für ein System"?
> 
> Ist es
> 
> a) Ein alter 386er mit 20-Mbyte MFM-Festplatte im 5 1/4-Zoll-Format?
> b) Ein völlig überbuchter 4.99$-VServer?
> c) Ein System ohne Festplatte, dafür aber mit Floppy?
> d) Ein System, daß gnadenlos nur noch mit swappen beschäftigt ist und
> dessen Festplatte 99% Wait-I/O hat?

Es gab kürzlich einen Festplattencrash. In den letzten Tagen stelle ich
immer wieder sporadische Verbindungsabbrüche fest im Sekunden oder
Minutenbereich.

# hwinfo --cpu
01: None 00.0: 10103 CPU                                        
  [Created at cpu.374]
  Unique ID: rdCR.j8NaKXDZtZ6
  Hardware Class: cpu
  Arch: Intel
  Vendor: "AuthenticAMD"
  Model: 15.107.2 "AMD Athlon(tm) 64 X2 Dual Core Processor 5600+"
  Features: fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ht,syscall,nx,mmxext,fxsr_opt,rdtscp,lm,3dnowext,3dnow,extd_apicid,pni,cx16,lahf_lm,cmp_legacy,svm,extapic,cr8_legacy,3dnowprefetch,lbrv
  Clock: 2900 MHz
  BogoMips: 5799.81
  Cache: 512 kb
  Units/Processor: 2
  Config Status: cfg=no, avail=yes, need=no, active=unknown

02: None 01.0: 10103 CPU
  [Created at cpu.374]
  Unique ID: wkFv.j8NaKXDZtZ6
  Hardware Class: cpu
  Arch: Intel
  Vendor: "AuthenticAMD"
  Model: 15.107.2 "AMD Athlon(tm) 64 X2 Dual Core Processor 5600+"
  Features: fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ht,syscall,nx,mmxext,fxsr_opt,rdtscp,lm,3dnowext,3dnow,extd_apicid,pni,cx16,lahf_lm,cmp_legacy,svm,extapic,cr8_legacy,3dnowprefetch,lbrv
  Clock: 2900 MHz
  BogoMips: 5800.39
  Cache: 512 kb
  Units/Processor: 2
  Config Status: cfg=new, avail=yes, need=no, active=unknown

# free
             total       used       free     shared    buffers     cached
Mem:       4005940    2528144    1477796          0     458556    1337004
-/+ buffers/cache:     732584    3273356
Swap:      4194300      16480    4177820

Heute nacht wird die Hardware des Servers bis auf die ohnenhin neuen
Festplattenkomplett ausgetauscht. Vielleicht löst das das Problem.

  Andreas

Two atoms are walking along.  Suddenly, one stops.  The other says, "What's
wrong?"  "I've lost an electron."  "Are you sure?"  "I'm positive!"