Mailinglist Archive: opensuse (1108 mails)

< Previous Next >
Re: [opensuse] Trying to subscribe here with GMX
On 2018-08-16 08:10, Per Jessen wrote:
Carlos E. R. wrote:

Hi,

I got a new email address from GMX, and I'm trying to subscribe, using
Alpine and Postfix.

I get this error sequence:

<2.5> 2018-08-15 23:56:30 Telcontar amavis 7466 - - (07466-17) Passed
CLEAN {RelayedOutbound}, MYNETS LOCAL [127.0.0.1]:43576
<robin.listas@xxxxxx> ->
<opensuse-gnome+confsub-4344a49469404fef-robin.listas=gmx.es@xxxxxxxxxxxx>,

Okay, so this is your confirmation of subscription to
opensuse-gnome@o.o.

<2.6> 2018-08-15 23:56:31 Telcontar postfix 1680 - - Trusted TLS
connection established to mail.gmx.es[212.227.17.184]:25: TLSv1.2 with
cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)

Secure connection established.

<2.6> 2018-08-15 23:56:31 Telcontar postfix 1680 - - D920E320833:
to=<opensuse-gnome+confsub-4344a49469404fef-robin.listas=gmx.es@xxxxxxxxxxxx>,
relay=mail.gmx.es[212.227.17.184]:25, delay=0.81,
delays=0.01/0/0.69/0.11, dsn=5.0.0, status=bounced (host
mail.gmx.es[212.227.17.184] said: 550-Requested action not taken:
mailbox unavailable 550 Failure sending mail. Try again later (in
reply to RCPT TO command))

I don't use GMX, but don't you have to authenticate to send mail ?


It worked initially, with the subscription to opensuse@xxxxxxxxxxxx. Then I
sent an email subscribing to 17 mail lists (in a single email), and problems
started.

So my configuration should be correct, it is them doing something. They have
not responded.

Ah! Despite being gmx.es, it appears I have to tell them in English. Their
error emails are in English.


See, this is a successful send:


<2.6> 2018-08-15 23:29:24 Telcontar amavis 7466 - - (07466-09) Jq8lsOFBrz1Y
FWD from <robin.listas@xxxxxx> -> <opensuse+subscribe@xxxxxxxxxxxx>, BODY=7BIT
250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 2E484320798
<2.5> 2018-08-15 23:29:24 Telcontar amavis 7466 - - (07466-09) Passed CLEAN
{RelayedOutbound}, MYNETS LOCAL [127.0.0.1]:41800 <robin.listas@xxxxxx> ->
<opensuse+subscribe@xxxxxxxxxxxx>, Queue-ID: DFCCD320787, Message-ID:
<alpine.LSU.2.21.1808152329140.30436@telcontar.valinor>, mail_id: Jq8lsOFBrz1Y,
Hits: -, size: 666, queued_as: 2E484320798, 219 ms
<2.6> 2018-08-15 23:29:24 Telcontar postfix 30858 - - DFCCD320787:
to=<opensuse+subscribe@xxxxxxxxxxxx>, relay=127.0.0.1[127.0.0.1]:10024,
delay=0.31, delays=0.06/0.01/0.05/0.19, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 2E484320798)
<2.6> 2018-08-15 23:29:24 Telcontar postfix 4608 - - DFCCD320787: removed
<2.6> 2018-08-15 23:29:24 Telcontar postfix 30862 - - Trusted TLS connection
established to mail.gmx.es[212.227.17.184]:25: TLSv1.2 with cipher
ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
<2.6> 2018-08-15 23:29:25 Telcontar postfix 30862 - - 2E484320798:
to=<opensuse+subscribe@xxxxxxxxxxxx>, relay=mail.gmx.es[212.227.17.184]:25,
delay=1.3, delays=0.01/0.02/0.71/0.53, dsn=2.0.0, status=sent (250 Requested
mail action okay, completed: id=0LyWAQ-1ftr0s40L0-015u9N)
<2.6> 2018-08-15 23:29:25 Telcontar postfix 4608 - - 2E484320798: removed


Postfix is configured to authenticate when they ask. Same method as it works
with Telefónica:


2.6> 2018-08-16 04:29:04 Telcontar amavis 8451 - - (08451-04) flm4TtKjl28c FWD
from <robin.listas@xxxxxxxxxxxxxx> -> <opensuse-offtopic@xxxxxxxxxxxx>,
BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queue
d as 5452B320837
<2.5> 2018-08-16 04:29:04 Telcontar amavis 8451 - - (08451-04) Passed CLEAN
{RelayedOutbound}, MYNETS LOCAL [127.0.0.1]:33282 <robin.listas@xxxxxxxxxxxxxx>
-> <opensuse-offtopic@xxxxxxxxxxxx>, Queue-ID: 2785532082D, Message-I
D: <1011113c-6fd2-a4cb-ca5b-98796cbbf3b7@xxxxxxxxxxxxxx>, mail_id:
flm4TtKjl28c, Hits: -, size: 5593, queued_as: 5452B320837, 175 ms
<2.6> 2018-08-16 04:29:04 Telcontar postfix 18811 - - 2785532082D:
to=<opensuse-offtopic@xxxxxxxxxxxx>, relay=127.0.0.1[127.0.0.1]:10024,
delay=0.23, delays=0.04/0.01/0.01/0.17, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp
:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5452B320837)
<2.6> 2018-08-16 04:29:04 Telcontar postfix 4608 - - 2785532082D: removed
<2.6> 2018-08-16 04:29:04 Telcontar dovecot - - - imap(cer): Connection closed
(noop finished 0.099 secs ago) in=6415 out=805269
<2.6> 2018-08-16 04:29:04 Telcontar postfix 18815 - - Anonymous TLS connection
established to smtp.telefonica.net[86.109.99.70]:25: TLSv1.2 with cipher
AECDH-AES256-SHA (256/256 bits)
<2.6> 2018-08-16 04:29:05 Telcontar postfix 18815 - - 5452B320837:
to=<opensuse-offtopic@xxxxxxxxxxxx>,
relay=smtp.telefonica.net[86.109.99.70]:25, delay=1.3, delays=0.01/0.03/0.24/1,
dsn=2.0.0, status=sent (250 2.0.0 Ok: que
ued as 41rVdY3QF1zfb8g)
<2.6> 2018-08-16 04:29:05 Telcontar postfix 4608 - - 5452B320837: removed


or gmail:


<2.6> 2018-08-11 15:34:06 Telcontar amavis 2469 - - (02469-13) WsTSYjSzwm5G
FWD from <robin.listas@xxxxxxxxx> -> <opensuse-es@xxxxxxxxxxxx>, BODY=7BIT 250
2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as F1B0E3
207A4
<2.5> 2018-08-11 15:34:07 Telcontar amavis 2469 - - (02469-13) Passed CLEAN
{RelayedOutbound}, MYNETS LOCAL [127.0.0.1]:58100 <robin.listas@xxxxxxxxx> ->
<opensuse-es@xxxxxxxxxxxx>, Queue-ID: D465A320746, Message-ID: <681a0af
1-e6b9-2df7-cc03-b4ec4bec5dca@xxxxxxxxx>, mail_id: WsTSYjSzwm5G, Hits: -, size:
3970, queued_as: F1B0E3207A4, 110 ms
<2.6> 2018-08-11 15:34:07 Telcontar postfix 20529 - - D465A320746:
to=<opensuse-es@xxxxxxxxxxxx>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.17,
delays=0.05/0.01/0/0.11, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp:[127.0.0
.1]:10025): 250 2.0.0 Ok: queued as F1B0E3207A4)
<2.6> 2018-08-11 15:34:07 Telcontar postfix 4608 - - D465A320746: removed
<2.6> 2018-08-11 15:34:07 Telcontar postfix 20533 - - Trusted TLS connection
established to smtp.gmail.com[64.233.184.109]:25: TLSv1.2 with cipher
ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
<2.6> 2018-08-11 15:34:07 Telcontar dovecot - - - imap(cer): Connection closed
(IDLE running for 0.001 + waiting input for 0.001 secs, 0.001 in locks, 2 B in
+ 10+10 B out, state=wait-input) in=9708 out=26206
<2.6> 2018-08-11 15:34:08 Telcontar postfix 20533 - - F1B0E3207A4:
to=<opensuse-es@xxxxxxxxxxxx>, relay=smtp.gmail.com[64.233.184.109]:25,
delay=2, delays=0.01/0.01/0.6/1.4, dsn=2.0.0, status=sent (250 2.0.0 OK
1533994448 j6-
v6sm17740909wrs.91 - gsmtp)
<2.6> 2018-08-11 15:34:08 Telcontar postfix 4608 - - F1B0E3207A4: removed



Unless I tell postfix to log SASL, the authentication is not recorded. And I
assure you Gmail is very strict.



I have just added to main.cf:

debug_peer_list = mail.gmx.es

But today, I can send:


<2.6> 2018-08-16 12:53:50 Telcontar amavis 6489 - - (06489-10) LYSY6lljeE8I
FWD from <robin.listas@xxxxxx> -> <robin.listas@xxxxxxxxxxxxxx>, BODY=7BIT 250
2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 9C71A320787
<2.5> 2018-08-16 12:53:50 Telcontar amavis 6489 - - (06489-10) Passed CLEAN
{RelayedOutbound}, MYNETS LOCAL [127.0.0.1]:35344 <robin.listas@xxxxxx> ->
<robin.listas@xxxxxxxxxxxxxx>, Queue-ID: BD06F320746, Message-ID:
<alpine.LSU.2.21.1808161248520.30436@telcontar.valinor>, mail_id: LYSY6lljeE8I,
Hits: -, size: 947, queued_as: 9C71A320787, 770 ms
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11744 - - BD06F320746:
to=<robin.listas@xxxxxxxxxxxxxx>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.91,
delays=0.06/0.01/0.17/0.68, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 9C71A320787)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11494 - - BD06F320746: removed
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 220 gmx.com (mrgmx103) Nemesis ESMTP Service
ready
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: EHLO Telcontar.valinor
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250-gmx.com Hello Telcontar.valinor
[79.156.56.59]
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250-AUTH LOGIN PLAIN
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250-SIZE 69920427
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250 STARTTLS
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: login ~? cram-md5
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: login ~? login
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: plain ~? cram-md5
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: plain ~? login
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - match_list_match: PLAIN:
no match
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - server features: 0x1039
size 69920427
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: STARTTLS
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 220 OK
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr request =
lookup
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr cache_type =
smtp
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr cache_id =
smtp&[mail.gmx.es]&mail.gmx.es&212.227.17.174&&7112783C2C619607B67ED28CB7FBD73D22EAFFEAB67AC87383F393D4B34A88E6
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: status
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
status
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute value:
4294967295
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: session
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
session
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute value:
(end)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: (list terminator)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
(end)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr request = seed
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr size = 32
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: status
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
status
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute value: 0
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: seed
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
seed
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute value:
PioWCyCSjn+PiIYLku+XLthwvJeErDIKw8ttmFG6ENo=
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: (list terminator)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - input attribute name:
(end)
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr request =
update
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr cache_type =
smtp
<2.6> 2018-08-16 12:53:50 Telcontar postfix 11751 - - send attr cache_id =
smtp&[mail.gmx.es]&mail.gmx.es&212.227.17.174&&7112783C2C619607B67ED28CB7FBD73D22EAFFEAB67AC87383F393D4B34A88E6
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - send attr session =
[data 1786 bytes]
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: status
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - input attribute name:
status
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - input attribute value: 0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - private/tlsmgr: wanted
attribute: (list terminator)
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - input attribute name:
(end)
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - Trusted TLS connection
established to mail.gmx.es[212.227.17.174]:25: TLSv1.2 with cipher
ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: EHLO Telcontar.valinor
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250-gmx.com Hello Telcontar.valinor
[79.156.56.59]
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250-AUTH LOGIN PLAIN
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250 SIZE 69920427
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: login ~? cram-md5
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: login ~? login
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: plain ~? cram-md5
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - match_string:
smtp_sasl_mechanism_filter: plain ~? login
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - match_list_match: PLAIN:
no match
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - server features: 0x1029
size 69920427
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - maps_find:
smtp_sasl_password_maps:
hash:/etc/postfix/sasl_passwd(0,lock|fold_fix|utf8_request):
robin.listas@xxxxxx = robin.listas@xxxxxx:.....
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - mail_addr_find:
robin.listas@xxxxxx -> robin.listas@xxxxxx:.....
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_sasl_passwd_lookup:
host `mail.gmx.es' user `robin.listas...' pass `....'
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - starting new SASL client
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - name_mask: noanonymous
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_sasl_authenticate:
mail.gmx.es[212.227.17.174]:25: SASL mechanisms LOGIN
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: AUTH LOGIN
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 334 VXNlcm5hbWU6
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - xsasl_cyrus_client_next:
decoded challenge: Username:
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - -
xsasl_cyrus_client_get_user: ...........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - -
xsasl_cyrus_client_get_passwd: .........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - xsasl_cyrus_client_next:
uncoded client response robin.listas@xxxxxx
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: ..........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 334 ........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - xsasl_cyrus_client_next:
decoded challenge: Password:
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - xsasl_cyrus_client_next:
uncoded client response .........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: ...........
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 235 Authentication succeeded
<=====
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: MAIL FROM:<robin.listas@xxxxxx> SIZE=1398
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250 Requested mail action okay, completed
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: RCPT TO:<robin.listas@xxxxxxxxxxxxxx>
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=300 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250 OK
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: DATA
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=120 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 354 Start mail input; end with <CRLF>.<CRLF>
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=180 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - >
mail.gmx.es[212.227.17.174]:25: .
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - smtp_stream_setup:
maxtime=600 enable_deadline=0
<2.6> 2018-08-16 12:53:51 Telcontar postfix 11751 - - <
mail.gmx.es[212.227.17.174]:25: 250 Requested mail action okay, completed:
id=0McUnM-1fYaXH1VeS-00Hbtf




It seems to me they are not reliable.


--
Cheers / Saludos,

Carlos E. R.
(from 42.3 x86_64 "Malachite" at Telcontar)

< Previous Next >
Follow Ups