Exchange beendet unerwartet den Mailempfang

    Hallo LC,


    kannst Du uns mal eine oder zwei von Deinen Problemmails zur Verfügung stellen?
    Ich würde das Problem gern mal bei uns untersuchen.

    Beste Grüsse,


    Claus (von Servolutions)

    Würde ich ja gerne machen aber dafür müsste ich ersteinmal wissen welche Mails ab wann die Problematik hervorrufen.


    Hier mal ein Fehler der Mitgeloggt wurde:



    Logfile aus dem Exchange:


    2009-11-12 10:04:17 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:04:22 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 68 5000 - - -
    2009-11-12 10:04:27 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 13 5000 - - -
    2009-11-12 10:04:32 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:04:37 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:04:42 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 76 5000 - - -
    2009-11-12 10:04:47 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 66 5000 - - -
    2009-11-12 10:04:52 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 68 5000 - - -
    2009-11-12 10:04:57 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:05:02 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 59 5000 - - -
    2009-11-12 10:05:07 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:05:12 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:05:17 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 =20 0 3 5000 - - -
    2009-11-12 10:05:22 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:05:27 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 35 5000 - - -
    2009-11-12 10:05:32 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:05:37 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 60 5000 - - -
    2009-11-12 10:05:42 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 58 5000 - - -
    2009-11-12 10:05:47 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 50 74 5000 - - -
    2009-11-12 10:05:47 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 QUIT 50 74 5000 - - -
    2009-11-12 10:07:41 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 HELO 43 32 0 - - -
    2009-11-12 10:07:41 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 MAIL 55 43 0 - - -
    2009-11-12 10:07:41 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 RCPT 44 42 0 - - -
    2009-11-12 10:07:43 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 DATA 137 23060 1125 - - -
    2009-11-12 10:07:48 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:07:53 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:07:58 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 70 5000 - - -
    2009-11-12 10:08:03 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:08:08 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 =20 0 3 5000 - - -
    2009-11-12 10:08:13 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:08:18 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 65 5000 - - -
    2009-11-12 10:08:23 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 http://www.secrypt.de=20 0 17 5000 - - -
    2009-11-12 10:08:28 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:08:33 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 =20 0 3 5000 - - -
    2009-11-12 10:08:38 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 32 5000 - - -
    2009-11-12 10:08:43 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:08:48 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:08:53 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 60 5000 - - -
    2009-11-12 10:08:58 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 59 5000 - - -
    2009-11-12 10:09:03 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 72 5000 - - -
    2009-11-12 10:09:08 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:09:13 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 43 5000 - - -
    2009-11-12 10:09:18 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:09:23 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 68 5000 - - -
    2009-11-12 10:09:28 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 13 5000 - - -
    2009-11-12 10:09:33 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 1 5000 - - -
    2009-11-12 10:09:38 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -
    2009-11-12 10:09:43 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 76 5000 - - -
    2009-11-12 10:09:48 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 66 5000 - - -
    2009-11-12 10:09:53 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 68 5000 - - -
    2009-11-12 10:09:58 aaa.bbb.ccc.ddd exchange-pop3-connector.com SMTPSVC1 Domäne01 aaa.bbb.ccc.ddd 0 > 0 74 5000 - - -


    Logfile aus PopCon:


    12.11.2009 10:05:41: Keine Mail auf diesem Host
    12.11.2009 10:05:41: Mail Übertragung an Exchange Server aaa.bbb.ccc.ddd [aaa.bbb.ccc.ddd] startet
    12.11.2009 10:05:42: Mail von regina.regenbogen@hotmail.com...
    12.11.2009 10:05:42: ... wird weitergeleitet an hallo@meine-domäne.de
    12.11.2009 10:06:43: Mail Übertragung an Exchange schlug fehl. Grund:Wartezeitüberschreitung bei Warten auf Antwort vom Host.
    12.11.2009 10:06:43: Mail Zyklus beendet


    LGLC

    Sehr merkwürdig diese 5000 Bytes Geschichte, allerdings.
    POPcon überträgt die Emails zeilenweise und nicht in 5000 Bytes Blöcken. Könnte es sein das sich eventuell ein Virenscanner o.ä. zwischen POPcon und dem Exchange in die SMTP-Kommunikation eingeschaltet hat? Das Problem wäre dann eher dort zu suchen.

    Beste Grüsse,


    Claus (von Servolutions)

    Als Virenscanner läuft Panda auf dem Exchange. Ich möchte diesen aber ungerne deaktivieren.


    Ich werde später nochmal die Logfiles durchsuchen.


    Danke für die Zahlreichen Infos.


    LGLC

    Ups zu spät reingeschaut.


    Ich habe mal übergangsweise den Exchange Scanner deinstalliert um zu sehen was Passiert.


    Wenn die Mails jetzt durchlaufen, werde ich diesen wieder installieren und dann mal sehen was ich da noch ändern muss...


    LGLC


    Weitere infos folgen...

    Leider Keine Änderung.


    Es werden immer noch mails nicht an den Exchange übertragen.


    Fehlermeldungen:


    17.11.2009 09:50:44: ... wird weitergeleitet an regina@regenbogen.de
    17.11.2009 09:50:56: Mail Übertragung an Exchange schlug fehl. Grund:Exchange beendete unerwartet den Mailempfang nach 67190 bytes.


    Mail Übertragung an Exchange schlug fehl. Grund:Exchange beendete unerwartet den Mailempfang nach 89317 bytes.
    17.11.2009 09:55:52: Mail Zyklus beendet


    17.11.2009 10:12:04: Mail Übertragung an Exchange schlug fehl. Grund:Exchange beendete unerwartet den Mailempfang nach 74813 bytes.
    17.11.2009 10:12:04: Mail Zyklus beendet


    17.11.2009 10:42:48: Exchange akzeptierte den Mailabsender nicht und antwortete mit folgender Fehlermeldung auf das smtp MAIL FROM Kommando: 500 5.3.3 Unrecognized command
    17.11.2009 10:42:53: -> wird in BADMAIL Verzeichnis verschoben.
    17.11.2009 10:42:53: Mail Übertragung an Exchange beendet
    17.11.2009 10:42:53: Mail Zyklus beendet


    und das gleiche vom SMTP:
    #Fields: date time c-ip cs-username s-sitename s-computername s-ip s-port cs-method sc-bytes cs-bytes time-taken cs(User-Agent) cs(Cookie) cs(Referer)


    2009-11-17 09:47:41 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 52 40 0 - - -
    2009-11-17 09:47:41 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 48 46 0 - - -
    2009-11-17 09:47:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 142 9888 1312 - - -
    2009-11-17 09:47:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 49 37 0 - - -
    2009-11-17 09:47:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 44 42 0 - - -
    2009-11-17 09:47:46 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 144 20104 1360 - - -
    2009-11-17 09:47:46 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 45 33 0 - - -
    2009-11-17 09:47:46 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 43 41 0 - - -
    2009-11-17 09:47:48 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 135 1631 1109 - - -
    2009-11-17 09:47:48 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 QUIT 64 4 0 - - -



    2009-11-17 10:12:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 HELO 43 32 0 - - -
    2009-11-17 10:12:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 51 39 16 - - -
    2009-11-17 10:12:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 41 39 0 - - -
    2009-11-17 10:12:50 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 134 343042 6922 - - -
    2009-11-17 10:12:50 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 47 35 0 - - -
    2009-11-17 10:12:50 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 38 36 0 - - -
    2009-11-17 10:12:52 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 127 7676 1156 - - -
    2009-11-17 10:12:52 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 QUIT 64 4 0 - - -
    2009-11-17 10:17:41 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 HELO 43 32 0 - - -
    2009-11-17 10:17:41 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 44 32 0 - - -
    2009-11-17 10:17:41 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 43 41 0 - - -
    2009-11-17 10:17:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 110 1419 937 - - -
    2009-11-17 10:17:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 MAIL 45 33 0 - - -
    2009-11-17 10:17:43 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 RCPT 43 41 0 - - -
    2009-11-17 10:17:45 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 DATA 127 14114 1219 - - -
    2009-11-17 10:17:45 192.168.124.3 exchange-pop3-connector.com SMTPSVC1 TOB01 192.168.124.3 0 QUIT 64 4 0 - - -



    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 51 0 63 - - -
    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 EHLO 4 0 63 - - -
    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 24 0 188 - - -
    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 AUTH 4 0 188 - - -
    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 16 0 297 - - -
    2009-11-17 10:42:39 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 35 0 422 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 MAIL 4 0 2938 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 12 0 3032 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 RCPT 4 0 3032 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 RCPT 4 0 3032 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 12 0 3157 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionCommand SMTPSVC1 TOB01 - 25 DATA 4 0 3157 - - -
    2009-11-17 10:42:42 88.198.41.58 OutboundConnectionResponse SMTPSVC1 TOB01 - 25 - 35 0 3282 - - -


    Ich hab keine Ahnung was ich noch tun soll:


    Die Logs passen einfach nicht zusammen. Ist da vielleicht der Fehler zu suchen???



    MFG LC

    Hier einmal die Datei (Natürlich bearbeitet soweit möglich.)


    Ist das eigentlich normal das die Zeit des Exchange LOG 1 Std. nachgeht???


    Und was mich noch wundert ist die Zeit zum Übertragen.


    Der letzte Eintrag war übrigens wieder so ein Fall.


    Die Header der Mails weisen eigentlich auch nix ungewöhnliches auf.


    LGLC


    Edit Norbert 9.12.2010 Anhang entfernt