search

stalwartlabs / smtp-server

Stalwart SMTP Server
https://stalw.art/smtp
GNU Affero General Public License v3.0
476 stars 24 forks source link

[bug]: No way to further debug smtp authentication failures #31

Closed szaimen closed 7 months ago

szaimen commented 7 months ago

What happened?

Trying to set up my mail server and smtp server but no way to figure out why authentication failed, for example because of wrong password or wrong domain or wrong user name? 2023-12-14T09:42:03.830292Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::auth: context="auth" event="authenticate" result="failed" is unfortunately not verbose enough.

How can we reproduce the problem?

I can reproduce the problem by doing the following steps:

  1. Set up stalwart by following the docs https://stalw.art/docs/install/docker (1. choose Local disk using Maildir, 2. choose No, create a new directory for me, 3. type in your cloud.mydomain.net as domain name and mail.cloud.mydomain.net as server hostname. 4. add DKIM, SPF and DMARC as advised to your DNS config)

  2. set up a user by following https://stalw.art/docs/directory/types/memory/ (by adding athe following to config.toml

    [directory."memory"]
type = "memory"

[[directory."memory".users]]
name = "noreply"
description = "noreply user"
secret = "my-Complicated-password"
email = ["noreply@cloud.mydomain.net"]
member-of = ["users"]

[directory."memory".lookup]
domains = ["cloud.mydomain.net", "mail.cloud.mydomain.net"]
  3. try to authenticate using an smtp client with noreply@cloud.mydomain.net as user, my-Complicated-password as password, 578 as port, mail.cloud.mydomain.net as smtp server domain. Then trying to send mail but only getting 2023-12-14T09:42:03.830292Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::auth: context="auth" event="authenticate" result="failed", so no idea what is wrong.

Version

v0.3.x

Which directory type are you using for local address resolution?

None

What operating system are you using?

Linux

Relevant log output

2023-12-14T09:41:58.819170Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="220 mail.cloud.mydomain.net Stalwart SMTP v0.4.0 at your service.\r\n" size=73
2023-12-14T09:41:58.819990Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:41:58.820048Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::ehlo: context="ehlo" event="ehlo" domain="cloud.mydomain.net"
2023-12-14T09:41:58.820164Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250-AUTH PLAIN LOGIN\r\n250 8BITMIME\r\n" size=203
2023-12-14T09:41:58.820971Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="AUTH LOGIN\r\n" size=12
2023-12-14T09:41:58.821066Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="334 VXNlciBOYW1lAA==\r\n" size=22
2023-12-14T09:41:58.821371Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:41:58.821441Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="334 UGFzc3dvcmQA\r\n" size=18
2023-12-14T09:41:58.821795Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:41:58.823615Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:42:03.825822Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:42:03.827734Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:42:03.827896Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:42:03.829754Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="AUTH PLAIN bm9yZXBseUBjbG91ZC5tYXJjb2FtYnJvc2luaS5uZXQAbm9yZXBseUBjbG91ZC5tYXJjb2FtYnJvc2luaS5uZXQAQW5nbGluZzEtV29tYjItV3JlY2tpbmc2LVJlc2VhbDc=\r\n" size=145
2023-12-14T09:42:03.830292Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:42:08.831756Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:42:08.832258Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:42:08.832317Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:42:08.835003Z TRACE session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::session: event="read" data="" size=0
2023-12-14T09:42:08.835031Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="172.18.0.1" remote.port=48860}: smtp::inbound::spawn: Connection closed by peer. event="disconnect" reason="peer"
2023-12-14T09:42:36.765467Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="220 mail.cloud.mydomain.net Stalwart SMTP v0.4.0 at your service.\r\n" size=73
2023-12-14T09:42:36.765744Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:42:36.765779Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::ehlo: context="ehlo" event="ehlo" domain="cloud.mydomain.net"
2023-12-14T09:42:36.765832Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-STARTTLS\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250 8BITMIME\r\n" size=195
2023-12-14T09:42:36.766480Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="STARTTLS\r\n" size=10
2023-12-14T09:42:36.766536Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="220 2.0.0 Ready to start TLS.\r\n" size=31
2023-12-14T09:42:36.798366Z  INFO session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: utils::listener::listen: context="tls" event="handshake" version=TLSv1_3 cipher=TLS13_AES_256_GCM_SHA384
2023-12-14T09:42:36.798535Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:42:36.798661Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250-AUTH PLAIN LOGIN\r\n250 8BITMIME\r\n" size=203
2023-12-14T09:42:36.799292Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="AUTH LOGIN\r\n" size=12
2023-12-14T09:42:36.799351Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="334 VXNlciBOYW1lAA==\r\n" size=22
2023-12-14T09:42:36.799617Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:42:36.799687Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="334 UGFzc3dvcmQA\r\n" size=18
2023-12-14T09:42:36.799966Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:42:36.800282Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:42:41.802840Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:42:41.803902Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:42:41.803978Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:42:41.804321Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="AUTH PLAIN bm9yZXBseUBjbG91ZC5tYXJjb2FtYnJvc2luaS5uZXQAbm9yZXBseUBjbG91ZC5tYXJjb2FtYnJvc2luaS5uZXQAQW5nbGluZzEtV29tYjItV3JlY2tpbmc2LVJlc2VhbDc=\r\n" size=145
2023-12-14T09:42:41.804597Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:42:46.805859Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:42:46.806742Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:42:46.806792Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:42:46.807697Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::session: event="read" data="" size=0
2023-12-14T09:42:46.807758Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=35998}: smtp::inbound::spawn: Connection closed by peer. event="disconnect" reason="peer"
x`2023-12-14T09:44:59.950966Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="write" data="220 mail.cloud.mydomain.net Stalwart SMTP v0.4.0 at your service.\r\n" size=73
2023-12-14T09:44:59.952034Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:44:59.952130Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::ehlo: context="ehlo" event="ehlo" domain="cloud.mydomain.net"
2023-12-14T09:44:59.952302Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-STARTTLS\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250 8BITMIME\r\n" size=195
2023-12-14T09:44:59.953299Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="read" data="STARTTLS\r\n" size=10
2023-12-14T09:44:59.953458Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="write" data="220 2.0.0 Ready to start TLS.\r\n" size=31
2023-12-14T09:44:59.982231Z  INFO session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: utils::listener::listen: context="tls" event="handshake" version=TLSv1_3 cipher=TLS13_AES_256_GCM_SHA384
2023-12-14T09:44:59.982662Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::session: event="read" data="" size=0
2023-12-14T09:44:59.982682Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36002}: smtp::inbound::spawn: Connection closed by peer. event="disconnect" reason="peer"
2023-12-14T09:45:16.566080Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="220 mail.cloud.mydomain.net Stalwart SMTP v0.4.0 at your service.\r\n" size=73
2023-12-14T09:45:16.566578Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:45:16.566598Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::ehlo: context="ehlo" event="ehlo" domain="cloud.mydomain.net"
2023-12-14T09:45:16.566655Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-STARTTLS\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250 8BITMIME\r\n" size=195
2023-12-14T09:45:16.567256Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="STARTTLS\r\n" size=10
2023-12-14T09:45:16.567303Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="220 2.0.0 Ready to start TLS.\r\n" size=31
2023-12-14T09:45:16.599780Z  INFO session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: utils::listener::listen: context="tls" event="handshake" version=TLSv1_3 cipher=TLS13_AES_256_GCM_SHA384
2023-12-14T09:45:16.599980Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:45:16.600100Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250-AUTH PLAIN LOGIN\r\n250 8BITMIME\r\n" size=203
2023-12-14T09:45:16.600506Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="AUTH LOGIN\r\n" size=12
2023-12-14T09:45:16.600572Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="334 VXNlciBOYW1lAA==\r\n" size=22
2023-12-14T09:45:16.600782Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="[DATA]" size=14
2023-12-14T09:45:16.600819Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="334 UGFzc3dvcmQA\r\n" size=18
2023-12-14T09:45:16.601395Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:45:16.602224Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:45:21.602821Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:45:21.603675Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:45:21.603837Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:45:21.604147Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="AUTH PLAIN bm9yZXBseQBub3JlcGx5AEFuZ2xpbmcxLVdvbWIyLVdyZWNraW5nNi1SZXNlYWw3\r\n" size=77
2023-12-14T09:45:21.604521Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:45:26.606146Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:45:26.606918Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:45:26.606961Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:45:26.608739Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::session: event="read" data="" size=0
2023-12-14T09:45:26.608791Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36004}: smtp::inbound::spawn: Connection closed by peer. event="disconnect" reason="peer"
2023-12-14T09:45:50.538963Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="220 mail.cloud.mydomain.net Stalwart SMTP v0.4.0 at your service.\r\n" size=73
2023-12-14T09:45:50.539621Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:45:50.539686Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::ehlo: context="ehlo" event="ehlo" domain="cloud.mydomain.net"
2023-12-14T09:45:50.539737Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-STARTTLS\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250 8BITMIME\r\n" size=195
2023-12-14T09:45:50.540198Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="STARTTLS\r\n" size=10
2023-12-14T09:45:50.540272Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="220 2.0.0 Ready to start TLS.\r\n" size=31
2023-12-14T09:45:50.566747Z  INFO session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: utils::listener::listen: context="tls" event="handshake" version=TLSv1_3 cipher=TLS13_AES_256_GCM_SHA384
2023-12-14T09:45:50.566827Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="EHLO cloud.mydomain.net\r\n" size=31
2023-12-14T09:45:50.566888Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="250-mail.cloud.mydomain.net says hello\r\n250-SMTPUTF8\r\n250-SIZE 104857600\r\n250-REQUIRETLS\r\n250-PIPELINING\r\n250-ENHANCEDSTATUSCODES\r\n250-CHUNKING\r\n250-BINARYMIME\r\n250-AUTH PLAIN LOGIN\r\n250 8BITMIME\r\n" size=203
2023-12-14T09:45:50.567703Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="AUTH LOGIN\r\n" size=12
2023-12-14T09:45:50.567812Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="334 VXNlciBOYW1lAA==\r\n" size=22
2023-12-14T09:45:50.568053Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="[DATA]" size=14
2023-12-14T09:45:50.568122Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="334 UGFzc3dvcmQA\r\n" size=18
2023-12-14T09:45:50.568290Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="[DATA]" size=46
2023-12-14T09:45:50.568529Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:45:55.569680Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:45:55.570773Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:45:55.570831Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:45:55.571519Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="AUTH PLAIN bm9yZXBseQBub3JlcGx5AEFuZ2xpbmcxLVdvbWIyLVdyZWNraW5nNi1SZXNlYWw3\r\n" size=77
2023-12-14T09:45:55.571831Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::auth: context="auth" event="authenticate" result="failed"
2023-12-14T09:46:00.573548Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="535 5.7.8 Authentication credentials invalid.\r\n" size=47
2023-12-14T09:46:00.574472Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="RSET\r\n" size=6
2023-12-14T09:46:00.574542Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="write" data="250 2.0.0 OK\r\n" size=14
2023-12-14T09:46:00.575069Z TRACE session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::session: event="read" data="" size=0
2023-12-14T09:46:00.575108Z DEBUG session{instance="submission" protocol=Smtp remote.ip="172.18.0.1" remote.port=36008}: smtp::inbound::spawn: Connection closed by peer. event="disconnect" reason="peer"
2023-12-14T09:47:14.949751Z DEBUG session{instance="submissions" protocol=Smtp remote.ip="192.241.206.30" remote.port=45192}: utils::listener::listen: Failed to accept TLS connection: received corrupt message of type InvalidContentType context="tls" event="error"

Code of Conduct

szaimen commented 7 months ago

Sorry, just noticed image 🙈