The message could not be sent

Norlig · December 14, 2022, 5:52pm

I get this error when trying to send an e-mail to a company.

My SMTP Processing log states the following:


2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread received signal for processing
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to RECEIVED
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set mail state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start processing mail
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start filter smtp routing
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Processing started
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread finished processing signal
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: No certificate loaded; disabling STARTTLS
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 220 MAILServer Axigen ESMTP ready
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << EHLO My.domain
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set remote delivery to auth
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Greylist disabled
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set max data size to 51200 KB
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set max received headers to 30
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Maximum recipient count set to 1000
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Do not wait for processing response
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: STARTTLS extension allowed
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: 8BIT MIME accepted
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: BINARY DATA extension allowed
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: PIPELINING extension allowed
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: DSN extension denied
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set local delivery to all
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Auto-generate SPF result for EHLO domain <My.domain> connected from <Some.IP.Addr.ess>: Pass (spfHeader = ''; spfExplanation = 'null')
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-MAILServer Axigen ESMTP hello
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-PIPELINING
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-AUTH PLAIN LOGIN CRAM-MD5 DIGEST-MD5 GSSAPI
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-AUTH=PLAIN LOGIN CRAM-MD5 DIGEST-MD5 GSSAPI
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-8BITMIME
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-BINARYMIME
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-CHUNKING
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-SIZE 52428800
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-XAXIORGINFO
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250-HELP
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250 OK
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: session from [Some.IP.Addr.ess] authenticated by <MyName@My.domain>
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << MAIL FROM: MyName@My.domain SIZE=167588 AUTH=MyName@My.domain XAXIORGINFO=35DEA0:00000002
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Created new queue item with id 0028FAD8
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Auto-generate SPF result for MAIL FROM <MyName@My.domain@My.domain> issued from EHLO domain <My.domain> connected from <Some.IP.Addr.ess>: Pass (spfHeader = ''; spfExplanation = 'null')
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250 Sender accepted
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << RCPT TO: support@<recipient.domain
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250 Recipient accepted
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << BDAT 167588 LAST
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << 167588 bytes read
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << Last chunk of 167588 bytes read
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: New mail <1671039969448331211@My.domain> received from My.domain (Some.IP.Addr.ess) with envelope from <MyName@My.domain>, authenticated as <MyName@My.domain>, recipients=1 (support@<recipient.domain), size=167588, enqueued with id 28FAD8 (orgid=35DEA0)
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> 250 Mail queued for delivery
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to FILTER DISCARD
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> data version to 1
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0028FAD8: Set mail state to REMOVED
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Finished filtering mail object 35DEA0 with filter: smtp routing
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start filter AV:Tnef of type socket filter from server
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << welcome to Axigen TNEF Decoder (1.2.0)
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> filter|
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> C:\Program1\Axigen Mail Server\\queue\35\DDEA0.01
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> |
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: >> C:\Program1\Axigen Mail Server\\queue\35\DDEA0.02
2022-12-14 18:46:09 +0100 16 MAILServer PROCESSING:0035DEA0: << done
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Filter AXI-TNEF(127.0.0.1:8888):[PASS]: pass - no changes
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Finished filtering mail object 35DEA0 with filter: AV:Tnef of type socket filter from server
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> data version to 2
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start filter onProcessing event
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Finished filtering mail object 35DEA0 with filter: onProcessing event
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set recipient <support@<recipient.domain> state to PROCESSED - RELAY
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set mail state to PROCESSED
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Processing finished
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread received signal for delivery
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread finished delivery signal
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start mail delivery
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set mail state to SENDING
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Start remote delivery for 1 recipients in domain <recipient.domain
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Use plain connection
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: STARTTLS extension allowed
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Allowed ssl versions set to: tls11 tls12 tls13 
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Relay mail using default host: <<recipient.domain:25>
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Relay mail: found IPv4 MX entry Recip.ient.IP.Address for domain <recipient.domain with priority 10
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Relay mail: found IPv4 MX entry Other.Recipient.IP.Address for domain <recipient.domain with priority 10
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Use Other.Recipient.IP.Address to relay mail 35DEA0 for domain <recipient.domain
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread received signal for cleanup
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Mail removed from queue
2022-12-14 18:46:09 +0100 08 MAILServer PROCESSING:0035DEA0: Set mail state to REMOVED
2022-12-14 18:46:10 +0100 08 MAILServer PROCESSING:0035DEA0: Shepherd thread finished cleanup signal

Can anyone see why I get the error, in this log?

indreias · December 14, 2022, 6:49pm

Hello Petter,

Please retest but after you have set the log level for SMTP Sending service on Protocol Communication as you have shared only PROCESSING logs and most probably the error is from the SMTP-OUT session (so we need those log lines as well).

HTH,
Ioan

Norlig · December 14, 2022, 9:47pm

Thanks @indreias
apparently I had to get my IP Address delisted via: https://sender.office.com/

2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-VI1EUR03FT032.mail.protection.outlook.com Hello [My.ip.add.ress]
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-SIZE 157286400
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-PIPELINING
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-DSN
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-ENHANCEDSTATUSCODES
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-8BITMIME
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-BINARYMIME
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250-CHUNKING
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250 SMTPUTF8
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: Start sending mail 3558C0
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: >> MAIL FROM:<MyName@My.Domain> SIZE=167629
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: >> RCPT TO:<Support@Recipient.domain>
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: >> NOOP
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250 2.1.0 Sender OK
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 550 5.7.606 Access denied, banned sending IP [My.ip.add.ress]. To request removal from this list please visit https://sender.office.com/ and follow the directions. For more information please go to  http://go.microsoft.com/fwlink/?LinkID=526655 AS(1430)
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: RCPT Support@Recipient.domain rejected with message: 5.7.606 Access denied, banned sending IP [My.ip.add.ress]. To request removal from this list please visit https://sender.office.com/ and follow the directions. For more information please go to  http://go.microsoft.com/fwlink/?LinkID=526655 AS(1430)
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: No RCPT accepted for mail 3558C0; aborting transaction
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: Set recipient <Support@Recipient.domain> state to RELAY ERROR
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: Delivery attempt completed for mail 3558C0; schedule for cleanup
2022-12-14 18:39:18 +0100 08 MailServer SMTP-OUT:00000001: Set mail state to SENT
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250 2.0.0 OK
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: >> RSET
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 250 2.0.0 Resetting
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: >> QUIT
2022-12-14 18:39:18 +0100 16 MailServer SMTP-OUT:00000001: << 221 2.0.0 Service closing transmission channel

Would have been nice to have received that as an e-mail instead of just an error that my e-mail could not be sent.

Seems the default is to receive an e-mail?