Hi All,
since a week some users complain timeout error when are sending new email via smtp.office365.com (TLS port 587).
The users need to retry some time before sending correctly.
Below the log:
--- DEBUG
--- First sending with error
---
1327449920[7f534dd74100]: SMTP Connecting to: smtp.office365.com
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 220 PR2P264CA0022.outlook.office365.com Microsoft ESMTP MAIL Service ready at Fri, 18 May 2018 14:15:45 +0000
1327449920[7f534dd74100]: SMTP entering state: 14
1327449920[7f534dd74100]: SMTP Send: EHLO [192.168.xx.xx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PR2P264CA0022.outlook.office365.com Hello [8x.9x.xxx.xxx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-SIZE 157286400
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PIPELINING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-DSN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-ENHANCEDSTATUSCODES
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-STARTTLS
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-8BITMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-BINARYMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-CHUNKING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 SMTPUTF8
1327449920[7f534dd74100]: SMTP entering state: 4
1327449920[7f534dd74100]: SMTP entering state: 21
1327449920[7f534dd74100]: SMTP Send: STARTTLS
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 220 2.0.0 SMTP server ready
1327449920[7f534dd74100]: SMTP entering state: 19
1327449920[7f534dd74100]: SMTP entering state: 14
1327449920[7f534dd74100]: SMTP Send: EHLO [192.168.xx.xx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PR2P264CA0022.outlook.office365.com Hello [8x.9x.xxx.xxx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-SIZE 157286400
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PIPELINING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-DSN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-ENHANCEDSTATUSCODES
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-AUTH LOGIN XOAUTH2
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-8BITMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-BINARYMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-CHUNKING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 SMTPUTF8
1327449920[7f534dd74100]: SMTP entering state: 4
1327449920[7f534dd74100]: SMTP entering state: 21
1327449920[7f534dd74100]: SMTP auth: server caps 0x30134, pref 0x300, failed 0x0, avail caps 0x100
1327449920[7f534dd74100]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400)
1327449920[7f534dd74100]: trying auth method 0x100
1327449920[7f534dd74100]: SMTP entering state: 15
1327449920[7f534dd74100]: SMTP: MSN or LOGIN auth, step 0
1327449920[7f534dd74100]: SMTP Send: AUTH LOGIN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 334 VXNlcm5hbWU6
1327449920[7f534dd74100]: SMTP entering state: 13
1327449920[7f534dd74100]: SMTP entering state: 16
1327449920[7f534dd74100]: SMTP AuthLoginStep1() for mass*****.vit*****@*** Email address is removed for privacy ***
1327449920[7f534dd74100]: LOGIN auth
1327449920[7f534dd74100]: Logging suppressed for this command (it probably contained authentication information)
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 334 UGFzc3dvcmQ6
1327449920[7f534dd74100]: SMTP entering state: 18
1327449920[7f534dd74100]: SMTP Login response, code 334
1327449920[7f534dd74100]: SMTP entering state: 17
1327449920[7f534dd74100]: SMTP AuthLoginStep2
1327449920[7f534dd74100]: PLAIN/LOGIN auth, step 2
1327449920[7f534dd74100]: Logging suppressed for this command (it probably contained authentication information)
--- DEBUG
--- SIn step 2 AuthLoginStep2 there is a TIMEOUT after 2 minutes
---
--- Second sending is fine
1327449920[7f534dd74100]: SMTP Connecting to: smtp.office365.com
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 220 MR2P264CA0017.outlook.office365.com Microsoft ESMTP MAIL Service ready at Fri, 18 May 2018 14:20:14 +0000
1327449920[7f534dd74100]: SMTP entering state: 14
1327449920[7f534dd74100]: SMTP Send: EHLO [192.168.xx.xx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-MR2P264CA0017.outlook.office365.com Hello [8x.9x.xxx.xxx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-SIZE 157286400
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PIPELINING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-DSN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-ENHANCEDSTATUSCODES
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-STARTTLS
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-8BITMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-BINARYMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-CHUNKING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 SMTPUTF8
1327449920[7f534dd74100]: SMTP entering state: 4
1327449920[7f534dd74100]: SMTP entering state: 21
1327449920[7f534dd74100]: SMTP Send: STARTTLS
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 220 2.0.0 SMTP server ready
1327449920[7f534dd74100]: SMTP entering state: 19
1327449920[7f534dd74100]: SMTP entering state: 14
1327449920[7f534dd74100]: SMTP Send: EHLO [192.168.xx.xx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-MR2P264CA0017.outlook.office365.com Hello [8x.9x.2xx.2xx]
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-SIZE 157286400
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-PIPELINING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-DSN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-ENHANCEDSTATUSCODES
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-AUTH LOGIN XOAUTH2
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-8BITMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-BINARYMIME
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250-CHUNKING
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 SMTPUTF8
1327449920[7f534dd74100]: SMTP entering state: 4
1327449920[7f534dd74100]: SMTP entering state: 21
1327449920[7f534dd74100]: SMTP auth: server caps 0x30134, pref 0x300, failed 0x0, avail caps 0x100
1327449920[7f534dd74100]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400)
1327449920[7f534dd74100]: trying auth method 0x100
1327449920[7f534dd74100]: SMTP entering state: 15
1327449920[7f534dd74100]: SMTP: MSN or LOGIN auth, step 0
1327449920[7f534dd74100]: SMTP Send: AUTH LOGIN
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 334 VXNlcm5hbWU6
1327449920[7f534dd74100]: SMTP entering state: 13
1327449920[7f534dd74100]: SMTP entering state: 16
1327449920[7f534dd74100]: SMTP AuthLoginStep1() for ma****.vit****@*** Email address is removed for privacy ***
1327449920[7f534dd74100]: LOGIN auth
1327449920[7f534dd74100]: Logging suppressed for this command (it probably contained authentication information)
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 334 UGFzc3dvcmQ6
1327449920[7f534dd74100]: SMTP entering state: 18
1327449920[7f534dd74100]: SMTP Login response, code 334
1327449920[7f534dd74100]: SMTP entering state: 17
1327449920[7f534dd74100]: SMTP AuthLoginStep2
1327449920[7f534dd74100]: PLAIN/LOGIN auth, step 2
1327449920[7f534dd74100]: Logging suppressed for this command (it probably contained authentication information)
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 235 2.7.0 Authentication successful target host AM6PR0102MB3271.eurprd01.prod.exchangelabs.com
1327449920[7f534dd74100]: SMTP entering state: 18
1327449920[7f534dd74100]: SMTP Login response, code 235
1327449920[7f534dd74100]: SMTP entering state: 3
1327449920[7f534dd74100]: SMTP Send: MAIL FROM:<mas**.vi***@**.it> BODY=8BITMIME SIZE=5827
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 2.1.0 Sender OK
1327449920[7f534dd74100]: SMTP entering state: 5
1327449920[7f534dd74100]: SMTP Send: RCPT TO:<fa*****.pe*****@**.it>
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 2.1.5 Recipient OK
1327449920[7f534dd74100]: SMTP entering state: 6
1327449920[7f534dd74100]: SMTP Send: DATA
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 354 Start mail input; end with <CRLF>.<CRLF>
1327449920[7f534dd74100]: SMTP entering state: 7
1327449920[7f534dd74100]: SMTP entering state: 8
1327449920[7f534dd74100]: SMTP Send: .
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 250 2.6.0 <de6c41cc-xxxx-xxxx-4dc6-xxxx3b519652@**.it> [InternalId=397xxxxx51884, Hostname=AM6PR0102MB3271.eurprd01.prod.xxxxxxx.com] 8668 bytes in 0.711, 11.893 KB/sec Queued mail for delivery
1327449920[7f534dd74100]: SMTP entering state: 9
1327449920[7f534dd74100]: SMTP Send: QUIT
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP entering state: 0
1327449920[7f534dd74100]: SMTP Response: 221 2.0.0 Service closing transmission channel
1327449920[7f534dd74100]: SMTP entering state: 10
1327449920[7f534dd74100]: SMTP entering state: 12
1327449920[7f534dd74100]: SMTP connection error quitting 804b0002, ignoring
Thank you in advance,
Simone Reale