Probleme mit dem E-Mailversand via SMTP

[pummelbaer]

Hallo zusammen,

vielleicht kann mir da jemand bei meinem Problem weiter helfen....

Und zwar stellt sich bei mir folgende Problematik.

Ich habe den Modified Shop 2.0.1-r10403 installiert.

Verwendete Server Config:

PHP-Version:   5.6.29
MySQL-Version:   5.5.5-10.0.28-MariaDB-0+deb8u1
Betriebssystem: Debian 8.6

wenn ich im Shop Backend unter > Konfiguration > E-Mail Optionen folgende Einstellungen vornehme:

E-Mail-Transport-Methode : SMTP
Adresse des SMTP-Servers: meine Server IP xx.xxx.xxx.xx
Adresse des SMTP-Backup-Servers: keinen Eintrag
SMTP-Port: 587
SMTP-Benutzername: vorname.nachname@meinedomain.de
SMTP-Passwort: Passwort

SMTP-Auth: JA
SMTP SECURE: tls

und diese dann speichere, dann erhalte ich keine Fehlermeldung beim peichern der Einstellungen.

Teste ich diese dann über das Kontakt Formular....
Erhalte ich die Meldung: Ihre Anfrage wurde an uns versendet.

Aber es kommt nix an in meinem Postfach.

Nun habe ich in meinen Maillogs nachgeschaut und dabei folgendes entdeckt:

Code: PHP  [Auswählen]

       
        SMTP    #903 - [xx.xxx.xxx.xxx] Disconnected (inbound: 0 / outbound: 0) Heute, 19:34:14
        SMTP    #903 - [xx.xxx.xxx.xxx] TLS negotiation succeeded          Heute, 19:34:14
        SMTP    #903 - [xx.xxx.xxx.xxx] STARTTLS          Heute, 19:34:14
        SMTP    #903 - [xx.xxx.xxx.xxx] EHLO meinedomain.net    Heute, 19:34:14
        SMTP    #903 - [xx.xxx.xxx.xxx] Connected  Heute, 19:34:1

Sende ich aber über den selben mailserver von einer völlig anderen installation aus, dann sieht das wie folgt aus:

Code: PHP  [Auswählen]

        SMTP    #15009 - [89.163.132.189] Message enqueued for 1 recipient(s), queue ID(s): 00000038    Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] DATA  Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] RCPT TO:<user@meinedoman.de>  Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] MAIL FROM:<user@meinedoman.de>        Heute, 20:07:23
        SMTP    #15009 - Logged in as user@meinedoman.de (2)    Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] AUTH  Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] EHLO meinedomain.de   Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] TLS negotiation succeeded     Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] STARTTLS      Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] EHLO meinedomain.de   Heute, 20:07:23
        SMTP    #15009 - [xx.xxx.xxx.xxx] Connected     Heute, 20:07:23

Man sieht also im oberein Teil des Logfileauszuges klar, dass der Shop mit diesen Einstellungen auf den Mailserver zugreift und eine Sichere Verbindung herstellt, aber nicht mehr die den Login durchführt und diesen abbricht.

Ich denke da ist für ihn etwas bei der Übergabe der Logindaten unklar.
Würden Benutzer und Passwort nicht richtig sein, dann würde er das mit

      

Code: PHP  [Auswählen]

SMTP #0000 - Error: 550 No such local user, unauthenticated relaying denied

ausgeben.

kann mir da jemand weiterhelfen???

Gruß
Andre

Linkback: https://www.modified-shop.org/forum/index.php?topic=36244.0

[web28]

Das wird genauso konfiguriert wie bei jedem Emailprogramm (z.B Thunderbird, Outlook usw.)

Gruss Web28

[pummelbaer]

@ web28 Das ist mir schon klar, dass dies genauso wie bei einem Klient konfiguriert wird.
Ich glaube eher das es an der php liegt, dass die Übergabe für den Login nicht funktioniert.

Deshalb habe ich ja auch den Logauszug mit angehängt.

Weil der einfach ohne weitere Meldung abbricht...

Nur mal so als Gedankengang, vielleicht könnte man ja einen Verbindungstest mit eventuellen Fehlermeldungen dort einbauen)

Gruß
Andre

[web28]

Das Shopsystem benutzt den phpmailer (includes/external/phpmailer)

Man kann debuggen, siehe class.phpmailer.php

Code: PHP  [Auswählen]

    /**
     * SMTP class debug output mode.
     * Debug output level.
     * Options:
     * * `0` No output
     * * `1` Commands
     * * `2` Data and commands
     * * `3` As 2 plus connection status
     * * `4` Low-level data output
     * @type integer
     * @see SMTP::$do_debug
     */
    public $SMTPDebug = 0;

Setze mal diese Zeile in inc/xtc_php_mail.inc.php (nach $mail->IsSMTP()

Code: PHP  [Auswählen]

$mail->SMTPDebug = 3;

Das Log sollte jetzt im log Verzeichnis geschrieben werden.

Gruss Web28

[pummelbaer]

@web28

Hallo,

habe jetzt mal das loglevel wie du gesagt hast hochgesetzt und zwischen gespeichert.
So ganz werde ich daraus nicht schlau.
Deshalb habe ich dir das mal hier mit angehängt.

Gruss
Andre

[hpzeller]

Hallo Andre,

da stimmt etwas nicht, im Log solltest Du so etwas finden.

Code: XML  [Auswählen]

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 220 smtp.gmail.com ESMTP up5s34511244wjc.12 - gsmtp

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: EHLO xxx-xxx.com

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 250-smtp.gmail.com at your service, [xxxxxxxxxxx]
250-SIZE 35882577
250-8BITMIME
250-STARTTLS
250-ENHANCEDSTATUSCODES
250-PIPELINING
250-CHUNKING
250 SMTPUTF8

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: STARTTLS

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 220 2.0.0 Ready to start TLS

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: EHLO xxx-xxx.com

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 250-smtp.gmail.com at your service, [xxxxxxxxxxx]
250-SIZE 35882577
250-8BITMIME
250-AUTH LOGIN PLAIN XOAUTH2 PLAIN-CLIENTTOKEN OAUTHBEARER XOAUTH
250-ENHANCEDSTATUSCODES
250-PIPELINING
250-CHUNKING
250 SMTPUTF8

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: AUTH LOGIN

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 334 VXNlcm5hbHKU6

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: aHAuemVsbGVyQfTdtYWlsLmNvbQ==

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 334 UGFzc3dvcmQ6

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: dHVsaXBhbmE=

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 235 2.7.0 Accepted

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: MAIL FROM:<xxxxx@xxxxx.com>

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 250 2.1.0 OK up5sm276121244wjc.12 - gsmtp

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: RCPT TO:<xxxxx@xxxxx.com>

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 250 2.1.5 OK up5sm27676244wjc.12 - gsmtp

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: DATA

[21-Dec-2016 00:28:13 Europe/Berlin] SERVER -> CLIENT: 354  Go ahead up5sm27611244wjc.12 - gsmtp

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Date: Wed, 21 Dec 2016 00:28:13 +0100

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Return-Path: <xxxxx@xxxxx.com>

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: To: Erika Mustermann <xxxxx@xxxxx.com>

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: From: XOS-Shop <xxxxx@xxxxx.com>

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Subject: Test-Nachricht

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Message-ID: <65f6e733093b2c44hm7d38f2895c799@<xxxxx@xxxxx.com>.com>

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: X-Priority: 3

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: X-Mailer: PHPMailer 5.2.7 (https://github.com/PHPMailer/PHPMailer/)

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: MIME-Version: 1.0

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Content-Type: text/plain; charset=UTF-8

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Content-Transfer-Encoding: 8bit

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER:

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: Dies ist eine Test-Nachricht

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER:

[21-Dec-2016 00:28:13 Europe/Berlin] CLIENT -> SERVER: .

[21-Dec-2016 00:28:14 Europe/Berlin] SERVER -> CLIENT: 250 2.0.0 OK 1482276494 up5sm276168444wjc.12 - gsmtp

[21-Dec-2016 00:28:14 Europe/Berlin] CLIENT -> SERVER: QUIT

[21-Dec-2016 00:28:14 Europe/Berlin] SERVER -> CLIENT: 221 2.0.0 closing connection up5sm276139744wjc.12 - gsmtp
 

Denke da ist ein Bug in der 'class.phpmailer.php', teste mal folgendes:

Suche in 'includes/external/phpmailer/class.phpmailer.php' ca. Zeile 670

Code: PHP  [Auswählen]

        if (!in_array($this->Debugoutput, array('error_log', 'html', 'echo')) and is_callable($this->Debugoutput)) {
 

und erstze mit

Code: PHP  [Auswählen]

        if (!in_array($this->Debugoutput, array('error_log', 'html', 'echo', 'trigger_error')) and is_callable($this->Debugoutput)) {
 

Gruss
Hanspeter

[pummelbaer]

Hallo Hanspeter,

habe ich gemacht...
Kann aber keine Änderung feststellen...
(siehe Logfile)

hab hier auch gleich mal die class_phpmailer.php mit angehängt

Gruss
Andre

[pummelbaer]

Hallo Hanspeter,

also ich habe das Problem fast gelöst....
Ohne AUTH funktioniert das jetzt einwandfrei...

Aus Sicherheitsgründen, um Spamming zu vermeiden, sollte es auch mit AUTH funktionieren.
Soweit ich nun in der Mailserver Log sehen konnte macht er nun auch die übergabe der User Auth Daten.
Gibt aber nun noch den Fehler

Error: 535 Authentication failed

aus.

Warum er das macht???
Keine Ahnung....

Das Problem war aber soweit ich es überblicken konnte, ein Update der Pakete von Debian...
Ich hatte den Apache noch mal neu gestartet und dann gings...
Aber wie gesagt, nur ohne Auth...

Gruss Andre

[hpzeller]

[...]
habe ich gemacht...
Kann aber keine Änderung feststellen...
(siehe Logfile)
[...]

Wenn das nicht funktioniert hätte ich noch folgenden Vorschlag:

Suche im Admin unter Hilfsprogramme -> Server Info beim Abschnitt Core in der Spalte Directive nach error_log, dort solltest Du den Pfad zum "normalen" PHP-Logfile finden, wenn dort ein PHP-Logfile eingetragen ist, dann kannst Du im Script 'inc/xtc_php_mail.inc.php' nach

Code: PHP  [Auswählen]

    $mail->SMTPDebug = 3;
 

noch folgendes einfügen

Code: PHP  [Auswählen]

    $mail->Debugoutput = 'error_log';
 

Nach dem Versenden einer E-Mail solltest Du jetzt in diesem "normalen" PHP-Logfile die Debug-Informationen welche der PHPMailer generiert finden.

Gruss
Hanspeter