Problem mit Mailversand #56

Closed
opened 8 months ago by markus.weingaertner · 15 comments
Collaborator

Hallo,

aktuell habe ich bei einer neu aufgesetzten Instanz ein Problem beim Mailversand, gibt es einen Debug-Modus, so dass ich hier dem Problem auf die Schliche kommen kann?

Der Mailserver loggt folgendes, beim Versuch eine Mail aus Lerntools zu senden:

Jun 2 11:32:48 mx postfix/smtpd[1672189]: connect from example.com[xx.xx.xx.xx]
Jun 2 11:32:48 mx postfix/smtpd[1672189]: lost connection after STARTTLS from example.com[xx.xx.xx.xx]
Jun 2 11:32:48 mx postfix/smtpd[1672189]: disconnect from example.com[xx.xx.xx.xx] ehlo=1 starttls=1 commands=2

Config:

"SMTP_CONF" : {
"host": "mx",
"port": 465,
"secure": true,
"auth": {
"user": "noreply@example.com",
"pass": "XXXXXX"
}

Herzlichen Dank

Hallo, aktuell habe ich bei einer neu aufgesetzten Instanz ein Problem beim Mailversand, gibt es einen Debug-Modus, so dass ich hier dem Problem auf die Schliche kommen kann? Der Mailserver loggt folgendes, beim Versuch eine Mail aus Lerntools zu senden: Jun 2 11:32:48 mx postfix/smtpd[1672189]: connect from example.com[xx.xx.xx.xx] Jun 2 11:32:48 mx postfix/smtpd[1672189]: lost connection after STARTTLS from example.com[xx.xx.xx.xx] Jun 2 11:32:48 mx postfix/smtpd[1672189]: disconnect from example.com[xx.xx.xx.xx] ehlo=1 starttls=1 commands=2 Config: "SMTP_CONF" : { "host": "mx", "port": 465, "secure": true, "auth": { "user": "noreply@example.com", "pass": "XXXXXX" } Herzlichen Dank
pw commented 8 months ago
Owner

Hallo,
bitte einmal Folgendes überprüfen:

  1. Stimmt der Host? (ich gehe davon aus)
  2. Stimmt der Port? (da kann es Probleme geben ...) => evtl. 587
  3. Evtl. Secure auf false setzen => evtl. false
  4. Stimmen die Credentials (User / Pass) (gerne gemachter Fehler)

Gruß,
pw

Hallo, bitte einmal Folgendes überprüfen: 1. Stimmt der Host? (ich gehe davon aus) 2. Stimmt der Port? (da kann es Probleme geben ...) => evtl. 587 3. Evtl. Secure auf false setzen => evtl. false 4. Stimmen die Credentials (User / Pass) (gerne gemachter Fehler) Gruß, pw
Poster
Collaborator

Hallo :-)

  1. Stimmt der Host? (ich gehe davon aus)
  2. Stimmt der Port? (da kann es Probleme geben ...) => evtl. 587
  3. Evtl. Secure auf false setzen => evtl. false
  4. Stimmen die Credentials (User / Pass) (gerne gemachter Fehler)
  1. ja
  2. 587, 465 und 25 getestet
  3. alle Ports in beiden Varianten false/true
  4. Ja, Login möglich

Gruß

Hallo :-) > 1. Stimmt der Host? (ich gehe davon aus) > 2. Stimmt der Port? (da kann es Probleme geben ...) => evtl. 587 > 3. Evtl. Secure auf false setzen => evtl. false > 4. Stimmen die Credentials (User / Pass) (gerne gemachter Fehler) 1. ja 2. 587, 465 und 25 getestet 3. alle Ports in beiden Varianten false/true 4. Ja, Login möglich Gruß
pw commented 8 months ago
Owner

Wie alt/neu ist die Lerntools Instanz? Bitte Package.json posten. Wie hoch ist die Last auf dem System?

Wie alt/neu ist die Lerntools Instanz? Bitte Package.json posten. Wie hoch ist die Last auf dem System?
pw commented 8 months ago
Owner

Das Problem scheint eher auf der Config Seite zu liegen. Evtl. mit Netcat überprüfen und per PN an mich. Tip: evtl. den DNS Namen des Mailservers auch überprüfen. Ist dieser auch korrekt in DNS eingetragen? Keine IP Adresse des MX verwenden, sondern den DNS Namen.

Das Problem scheint eher auf der Config Seite zu liegen. Evtl. mit Netcat überprüfen und per PN an mich. Tip: evtl. den DNS Namen des Mailservers auch überprüfen. Ist dieser auch korrekt in DNS eingetragen? Keine IP Adresse des MX verwenden, sondern den DNS Namen.
Poster
Collaborator

Package.json

{
"name": "lerntools",
"version": "0.6.5",
"engines": {
"node": "12.x"
},
"private": true,
"scripts": {
"client-dev": "webpack --config ./webpack.dev.js",
"server-dev": "./bin/serverDev.sh",
"dev": "npm run server-dev & npm run client-dev",
"start": "node ./server.js",
"build-app": "webpack --config ./webpack.prod.js",
"build-locales": "node ./bin/buildLocales.js",
"build": "npm run build-app && npm run build-locales"
},
"dependencies": {
"axios": "^0.21.1",
"bcryptjs": "^2.4.3",
"body-parser": "^1.19.0",
"bootstrap-css-only": "^4.4.1",
"browser-image-compression": "^1.0.14",
"cors": "^2.8.5",
"debug": "^3.2.7",
"express": "^4.17.1",
"express-validator": "^5.3.1",
"express-ws": "^4.0.0",
"helmet": "^3.23.3",
"http-errors": "^1.8.0",
"jsonwebtoken": "^8.5.1",
"jsqr": "^1.3.1",
"katex": "^0.10.2",
"markdown-it": "^11.0.1",
"markdown-it-attrs": "^3.0.3",
"markdown-it-texmath": "^0.6.9",
"md5": "^2.3.0",
"mime": "^2.4.7",
"mongoose": "^5.11.11",
"morgan": "^1.10.0",
"node-cron": "^2.0.3",
"nodemailer": "^4.7.0",
"qrcode": "^1.4.4",
"request-ip": "^2.1.3",
"saslprep": "^1.0.3",
"tslib": "^2.2.0",
"gulp": "^4.0.2",
"svg-captcha": "^1.4.0",
"vue": "^2.6.12",
"vue-i18n": "^8.22.3",
"vue-qrcode": "^0.3.5",
"vue-router": "^3.4.9"
},
"devDependencies": {
"@intlify/vue-i18n-loader": "^0.6.1",
"clean-webpack-plugin": "^3.0.0",
"copy-webpack-plugin": "^5.1.2",
"css-loader": "^3.6.0",
"file-loader": "^5.1.0",
"image-webpack-loader": "^6.0.0",
"mini-css-extract-plugin": "^0.9.0",
"nodemon": "^2.0.7",
"optimize-css-assets-webpack-plugin": "^5.0.4",
"vue-loader": "^15.9.6",
"vue-template-compiler": "^2.6.12",
"webpack": "^4.45.0",
"webpack-cli": "^3.3.12",
"webpack-merge": "^4.2.2"
}
}

es ist so gut wie keine Last auf dem System

Package.json { "name": "lerntools", "version": "0.6.5", "engines": { "node": "12.x" }, "private": true, "scripts": { "client-dev": "webpack --config ./webpack.dev.js", "server-dev": "./bin/serverDev.sh", "dev": "npm run server-dev & npm run client-dev", "start": "node ./server.js", "build-app": "webpack --config ./webpack.prod.js", "build-locales": "node ./bin/buildLocales.js", "build": "npm run build-app && npm run build-locales" }, "dependencies": { "axios": "^0.21.1", "bcryptjs": "^2.4.3", "body-parser": "^1.19.0", "bootstrap-css-only": "^4.4.1", "browser-image-compression": "^1.0.14", "cors": "^2.8.5", "debug": "^3.2.7", "express": "^4.17.1", "express-validator": "^5.3.1", "express-ws": "^4.0.0", "helmet": "^3.23.3", "http-errors": "^1.8.0", "jsonwebtoken": "^8.5.1", "jsqr": "^1.3.1", "katex": "^0.10.2", "markdown-it": "^11.0.1", "markdown-it-attrs": "^3.0.3", "markdown-it-texmath": "^0.6.9", "md5": "^2.3.0", "mime": "^2.4.7", "mongoose": "^5.11.11", "morgan": "^1.10.0", "node-cron": "^2.0.3", "nodemailer": "^4.7.0", "qrcode": "^1.4.4", "request-ip": "^2.1.3", "saslprep": "^1.0.3", "tslib": "^2.2.0", "gulp": "^4.0.2", "svg-captcha": "^1.4.0", "vue": "^2.6.12", "vue-i18n": "^8.22.3", "vue-qrcode": "^0.3.5", "vue-router": "^3.4.9" }, "devDependencies": { "@intlify/vue-i18n-loader": "^0.6.1", "clean-webpack-plugin": "^3.0.0", "copy-webpack-plugin": "^5.1.2", "css-loader": "^3.6.0", "file-loader": "^5.1.0", "image-webpack-loader": "^6.0.0", "mini-css-extract-plugin": "^0.9.0", "nodemon": "^2.0.7", "optimize-css-assets-webpack-plugin": "^5.0.4", "vue-loader": "^15.9.6", "vue-template-compiler": "^2.6.12", "webpack": "^4.45.0", "webpack-cli": "^3.3.12", "webpack-merge": "^4.2.2" } } es ist so gut wie keine Last auf dem System
Poster
Collaborator

habe jetzt mal auf dem Mailserver debugging eingeschaltet, da kommt beim Versuch folgendes an:

Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 220 web99.example.com ESMTP Postfix (Ubuntu)
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: ????T?
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? connect
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? get
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? post
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_list_match: ????T?: no match
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: report unknown command to all milters
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: milter8_unknown_event: milter inet:127.0.0.1:12768: unknown command: ????T?
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: event: SMFIC_UNKNOWN; macros: (none)
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: skipping event SMFIC_UNKNOWN for milter inet:127.0.0.1:12768
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 502 5.5.2 Error: command not recognized
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: ??
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? connect
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? get
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? post
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_list_match: ??: no match
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: report unknown command to all milters
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: milter8_unknown_event: milter inet:127.0.0.1:12768: unknown command: ??
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: event: SMFIC_UNKNOWN; macros: (none)
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: skipping event SMFIC_UNKNOWN for milter inet:127.0.0.1:12768
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 502 5.5.2 Error: command not recognized
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]:
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 500 5.5.2 Error: bad syntax
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]:
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 500 5.5.2 Error: bad syntax
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0
Jun 2 13:22:54 web99 postfix/smtpd[1761864]: smtp_get: EOF

habe jetzt mal auf dem Mailserver debugging eingeschaltet, da kommt beim Versuch folgendes an: Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 220 web99.example.com ESMTP Postfix (Ubuntu) Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: ????T? Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? connect Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? get Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ????t? ~? post Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_list_match: ????T?: no match Jun 2 13:22:54 web99 postfix/smtpd[1761864]: report unknown command to all milters Jun 2 13:22:54 web99 postfix/smtpd[1761864]: milter8_unknown_event: milter inet:127.0.0.1:12768: unknown command: ????T? Jun 2 13:22:54 web99 postfix/smtpd[1761864]: event: SMFIC_UNKNOWN; macros: (none) Jun 2 13:22:54 web99 postfix/smtpd[1761864]: skipping event SMFIC_UNKNOWN for milter inet:127.0.0.1:12768 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 502 5.5.2 Error: command not recognized Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: ?? Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? connect Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? get Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_string: smtpd_forbidden_commands: ?? ~? post Jun 2 13:22:54 web99 postfix/smtpd[1761864]: match_list_match: ??: no match Jun 2 13:22:54 web99 postfix/smtpd[1761864]: report unknown command to all milters Jun 2 13:22:54 web99 postfix/smtpd[1761864]: milter8_unknown_event: milter inet:127.0.0.1:12768: unknown command: ?? Jun 2 13:22:54 web99 postfix/smtpd[1761864]: event: SMFIC_UNKNOWN; macros: (none) Jun 2 13:22:54 web99 postfix/smtpd[1761864]: skipping event SMFIC_UNKNOWN for milter inet:127.0.0.1:12768 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 502 5.5.2 Error: command not recognized Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 500 5.5.2 Error: bad syntax Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: < web108.example.com[xx.xx.xx.xx]: Jun 2 13:22:54 web99 postfix/smtpd[1761864]: > web108.example.com[xx.xx.xx.xx]: 500 5.5.2 Error: bad syntax Jun 2 13:22:54 web99 postfix/smtpd[1761864]: watchdog_pat: 0x55ab894d3ee0 Jun 2 13:22:54 web99 postfix/smtpd[1761864]: smtp_get: EOF
Poster
Collaborator

ich setze das noch mal auf einem anderen Server und melde mich

ich setze das noch mal auf einem anderen Server und melde mich
Poster
Collaborator

ok, neuer Server mit lokalen Postfix:

Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: connect from localhost[127.0.0.1]
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: SSL_accept error from localhost[127.0.0.1]: lost connection
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: lost connection after STARTTLS from localhost[127.0.0.1]
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: disconnect from localhost[127.0.0.1] ehlo=1 starttls=0/1 commands=1/2

config

"SMTP_CONF" : {
"host": "localhost",
"port": 25,
"secure": false
}

ok, neuer Server mit lokalen Postfix: Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: connect from localhost[127.0.0.1] Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: SSL_accept error from localhost[127.0.0.1]: lost connection Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: lost connection after STARTTLS from localhost[127.0.0.1] Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: disconnect from localhost[127.0.0.1] ehlo=1 starttls=0/1 commands=1/2 config "SMTP_CONF" : { "host": "localhost", "port": 25, "secure": false }
pw commented 8 months ago
Owner

Localhost wäre in der Tat ein Problem je nach konfig des Mailservers...das liegt aber außerhalb der Lerntools

Localhost wäre in der Tat ein Problem je nach konfig des Mailservers...das liegt aber außerhalb der Lerntools
Poster
Collaborator

wenn auf localhost ein postfix, der entsprechend zulässt, dass er von lokal genutzt werden darf, läuft sollte es funktionieren, kann da auch 127.0.0.1 eintragen, ändert nichts an der Tatsache, dass die Mailübergabe aus lerntools nicht funktioniert.

wenn auf localhost ein postfix, der entsprechend zulässt, dass er von lokal genutzt werden darf, läuft sollte es funktionieren, kann da auch 127.0.0.1 eintragen, ändert nichts an der Tatsache, dass die Mailübergabe aus lerntools nicht funktioniert.
pw commented 8 months ago
Owner

@markus.weingaertner

ok, neuer Server mit lokalen Postfix:

Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: connect from localhost[127.0.0.1]
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: SSL_accept error from localhost[127.0.0.1]: lost connection
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: lost connection after STARTTLS from localhost[127.0.0.1]
Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: disconnect from localhost[127.0.0.1] ehlo=1 starttls=0/1 commands=1/2

config

"SMTP_CONF" : {
"host": "localhost",
"port": 25,
"secure": false
}

Ok, da sollte aber noch Username und klimbim dazu... was loggen denn die Lerntools?

@markus.weingaertner >ok, neuer Server mit lokalen Postfix: > >Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: connect from localhost[127.0.0.1] >Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: SSL_accept error from localhost[127.0.0.1]: lost connection >Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: lost connection after STARTTLS from localhost[127.0.0.1] >Jun 2 14:24:34 v2202008101240124568 postfix/smtpd[247931]: disconnect from localhost[127.0.0.1] ehlo=1 starttls=0/1 commands=1/2 > > >config > >"SMTP_CONF" : { >"host": "localhost", >"port": 25, >"secure": false >} Ok, da sollte aber noch Username und klimbim dazu... was loggen denn die Lerntools?
pw commented 8 months ago
Owner

@markus.weingaertner

wenn auf localhost ein postfix, der entsprechend zulässt, dass er von lokal genutzt werden darf, läuft sollte es funktionieren, kann da auch 127.0.0.1 eintragen, ändert nichts an der Tatsache, dass die Mailübergabe aus lerntools nicht
Eine IP Adresse kann aber für das Modul nodemailer unter bestimmten Umständen zu Problemen führen...daher der Hinweis. Was sagen die Logs von postfix?

@markus.weingaertner >wenn auf localhost ein postfix, der entsprechend zulässt, dass er von lokal genutzt werden darf, läuft sollte es funktionieren, kann da auch 127.0.0.1 eintragen, ändert nichts an der Tatsache, dass die Mailübergabe aus lerntools nicht Eine IP Adresse kann aber für das Modul nodemailer unter bestimmten Umständen zu Problemen führen...daher der Hinweis. Was sagen die Logs von postfix?
Poster
Collaborator

Lerntools loggt nichts

Da kommen wir zu meinem ersten Post:

gibt es einen Debug-Modus, so dass ich hier dem Problem auf die Schliche kommen kann?

Postfix loggt "SSL_accept error"

Gruß
Markus

Lerntools loggt nichts Da kommen wir zu meinem ersten Post: gibt es einen Debug-Modus, so dass ich hier dem Problem auf die Schliche kommen kann? Postfix loggt "SSL_accept error" Gruß Markus
pw commented 8 months ago
Owner

Ich bastle einmal an einem MWE.

Ich bastle einmal an einem MWE.
Poster
Collaborator

Hey, habs :-)

"requireTLS": true/false, muss mit rein:

    "SMTP_CONF" : {
            "host": "mx.xyz.de",
            "port": 465,
            "requireTLS": true,
            "secure": true,
            "auth": {
                    "user": "noreply@xyz.de",
                    "pass": "xxxxxxxx" }

Gruß
Markus

Hey, habs :-) "requireTLS": true/false, muss mit rein: "SMTP_CONF" : { "host": "mx.xyz.de", "port": 465, "requireTLS": true, "secure": true, "auth": { "user": "noreply@xyz.de", "pass": "xxxxxxxx" } Gruß Markus
gerhardbeck closed this issue 8 months ago
Sign in to join this conversation.
No Milestone
No project
No Assignees
2 Participants
Notifications
Due Date

No due date set.

Dependencies

This issue currently doesn't have any dependencies.

Loading…
There is no content yet.