I tried to change the first expect to ^220 ([^ ]+) ESMTP$. But I'm not sure
if the change is acceptable from blackbox's view.
smtp_starttls:
prober: tcp
timeout: 20s
tcp:
query_response:
#- expect: "^220 ([^ ]+) ESMTP (.+)$"
- expect: "^220 ([^ ]+) ESMTP$"
send: "EHLO prober\r"
- expect: "^250-STARTTLS"
- expect: "^250 .*$"
send: "STARTTLS\r"
- expect: "^220"
starttls: true
- send: "EHLO prober\r"
- expect: "^250 .*$"
send: "QUIT\r"
Curled and got the following error. Does it mean the mail server doesn't
contain any IP SANs? And on mail server certificate cannot be validated?
ts=2022-07-05T04:18:10.539326118Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=error msg="TLS Handshake (client) failed"
err="x509: cannot validate certificate for 1.1.1.1 because it doesn't
contain any IP SANs"
curl -g
'http://0.0.0.0:9115/probe?module=smtp_starttls&target=1.1.1.1:25&debug=true'
Logs for the probe:
ts=2022-07-05T04:18:10.527958378Z caller=main.go:320 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Beginning probe" probe=tcp
timeout_seconds=20
ts=2022-07-05T04:18:10.528040568Z caller=tcp.go:40 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Resolving target address" ip_protocol=ip6
ts=2022-07-05T04:18:10.528063021Z caller=tcp.go:40 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Resolving target address" ip_protocol=ip4
ts=2022-07-05T04:18:10.528076698Z caller=tcp.go:40 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Resolved target address" ip=1.1.1.1
ts=2022-07-05T04:18:10.528115109Z caller=tcp.go:121 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Dialing TCP without TLS"
ts=2022-07-05T04:18:10.529223463Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Successfully dialed"
ts=2022-07-05T04:18:10.529258037Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Processing query response entry"
entry_number=0
ts=2022-07-05T04:18:10.530236839Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line="220 mail.com ESMTP"
ts=2022-07-05T04:18:10.530278493Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Regexp matched" regexp="^220 ([^ ]+)
ESMTP$" line="220 mail.com ESMTP"
ts=2022-07-05T04:18:10.53030557Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Sending line" line="EHLO prober\r"
ts=2022-07-05T04:18:10.530348868Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Processing query response entry"
entry_number=1
ts=2022-07-05T04:18:10.530919204Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-mail.com
ts=2022-07-05T04:18:10.530955761Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-PIPELINING
ts=2022-07-05T04:18:10.530971243Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line="250-SIZE 10240000"
ts=2022-07-05T04:18:10.530985701Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-ETRN
ts=2022-07-05T04:18:10.531006196Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-STARTTLS
ts=2022-07-05T04:18:10.531025088Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Regexp matched" regexp=^250-STARTTLS
line=250-STARTTLS
ts=2022-07-05T04:18:10.531045174Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Processing query response entry"
entry_number=2
ts=2022-07-05T04:18:10.531064549Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line="250-AUTH PLAIN LOGIN"
ts=2022-07-05T04:18:10.531077325Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-ENHANCEDSTATUSCODES
ts=2022-07-05T04:18:10.531088466Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line=250-8BITMIME
ts=2022-07-05T04:18:10.53110099Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line="250 DSN"
ts=2022-07-05T04:18:10.531134286Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Regexp matched" regexp="^250 .*$"
line="250 DSN"
ts=2022-07-05T04:18:10.531186418Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Sending line" line="STARTTLS\r"
ts=2022-07-05T04:18:10.531234743Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Processing query response entry"
entry_number=3
ts=2022-07-05T04:18:10.531762621Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=debug msg="Read line" line="220 2.0.0 Ready to
start TLS"
ts=2022-07-05T04:18:10.531779841Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=info msg="Regexp matched" regexp=^220 line="220
2.0.0 Ready to start TLS"
ts=2022-07-05T04:18:10.539326118Z caller=main.go:130 module=smtp_starttls
target=1.1.1.1:25 level=error msg="TLS Handshake (client) failed"
err="x509: cannot validate certificate for 1.1.1.1 because it doesn't
contain any IP SANs"
ts=2022-07-05T04:18:10.53937693Z caller=main.go:320 module=smtp_starttls
target=1.1.1.1:25 level=error msg="Probe failed"
duration_seconds=0.011375261
On Tuesday, July 5, 2022 at 12:03:50 PM UTC+8 nina guo wrote:
> I may find the root cause - comparing the output between google's mail
> server and my mail server
>
> my mail server
> ts=2022-07-05T02:29:41.608566903Z caller=main.go:130 module=smtp_starttls
> target=1.1.1.1:25 level=info msg="Successfully dialed"
> ts=2022-07-05T02:29:41.608652361Z caller=main.go:130 module=smtp_starttls
> target=1.1.1.1:25 level=info msg="Processing query response entry"
> entry_number=0
> ts=2022-07-05T02:29:41.609572861Z caller=main.go:130 module=smtp_starttls
> target=1.1.1.1:25 level=debug msg="Read line" line="220 mail.com ESMTP"
> ts=2022-07-05T02:29:46.607798497Z caller=main.go:130 module=smtp_starttls
> target=11.1.1.1:25 level=error msg="Error reading from connection"
> err="read tcp4 0.0.0.0:57074->1.1.1.1:25: i/o timeout"
>
> google's mail server
> ts=2022-07-05T02:04:33.385587664Z caller=main.go:130 module=smtp_starttls
> target=142.250.102.27:25 level=info msg="Successfully dialed"
> ts=2022-07-05T02:04:33.385613189Z caller=main.go:130 module=smtp_starttls
> target=142.250.102.27:25 level=info msg="Processing query response entry"
> entry_number=0
> ts=2022-07-05T02:04:33.419235886Z caller=main.go:130 module=smtp_starttls
> target=142.250.102.27:25 level=debug msg="Read line" line="220
> mx.google.com ESMTP dn22-20020a17090794d600b006dfc3945326si6666807ejc.152
> - gsmtp"
> ts=2022-07-05T02:04:33.419280493Z caller=main.go:130 module=smtp_starttls
> target=142.250.102.27:25 level=info msg="Regexp matched" regexp="^220 ([^
> ]+) ESMTP (.+)$" line="220 mx.google.com ESMTP
> dn22-20020a17090794d600b006dfc3945326si6666807ejc.152 - gsmtp"
>
> From the output it shows the regex is not matched for my mail server -
> ^220 ([^ ]+) ESMTP (.+)$
>
> In config file
> smtp_starttls:
> prober: tcp
> timeout: 20s
>
> tcp:
> query_response:
> - expect: "^220 ([^ ]+) ESMTP (.+)$"
> send: "EHLO prober\r"
> - expect: "^250-STARTTLS"
> - expect: "^250 .*$"
> send: "STARTTLS\r"
>
> - expect: "^220"
> starttls: true
> - send: "EHLO prober\r"
> - expect: "^250 .*$"
> send: "QUIT\r"
> On Tuesday, July 5, 2022 at 11:40:06 AM UTC+8 nina guo wrote:
>
>> I searched this info blackbox_exporter for SMTP monitoring w/ TCP
>> module : PrometheusMonitoring (reddit.com)
>> <https://www.reddit.com/r/PrometheusMonitoring/comments/q60357/blackbox_exporter_for_smtp_monitoring_w_tcp_module/>
>> ,
>> added "\r", but still got the same issue..
>>
>> On Tuesday, July 5, 2022 at 11:26:49 AM UTC+8 nina guo wrote:
>>
>>> telnet got succeed.
>>>
>>> telnet 1.1.1.1 25
>>> Trying 1.1.1.1...
>>> Connected to 1.1.1.1.
>>> Escape character is '^]'.
>>> 220 ... ESMTP
>>
>>
>>>
>>> On Tuesday, July 5, 2022 at 11:11:48 AM UTC+8 nina guo wrote:
>>>
>>>> Probe failed due to TLS issue...it is not the same issue with my mail
>>>> server..
>>>> There is no "i/o timeout" issue for google's mail server.
>>>>
>>>> curl -g '
>>>> http://0.0.0.0:9115/probe?module=smtp_starttls&target=142.250.102.27:25&debug=true
>>>>
>>>> <http://100.100.3.9:9115/probe?module=smtp_starttls&target=142.250.102.27:25&debug=true>
>>>> '
>>>> Logs for the probe:
>>>> ts=2022-07-05T02:04:33.373763474Z caller=main.go:320
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Beginning probe" probe=tcp timeout_seconds=5
>>>> ts=2022-07-05T02:04:33.373850167Z caller=tcp.go:40 module=smtp_starttls
>>>> target=142.250.102.27:25 level=info msg="Resolving target address"
>>>> ip_protocol=ip6
>>>> ts=2022-07-05T02:04:33.373867876Z caller=tcp.go:40 module=smtp_starttls
>>>> target=142.250.102.27:25 level=info msg="Resolving target address"
>>>> ip_protocol=ip4
>>>> ts=2022-07-05T02:04:33.373882263Z caller=tcp.go:40 module=smtp_starttls
>>>> target=142.250.102.27:25 level=info msg="Resolved target address"
>>>> ip=142.250.102.27
>>>> ts=2022-07-05T02:04:33.373903614Z caller=tcp.go:121
>>>> module=smtp_starttls target=142.250.102.27:25 level=info msg="Dialing
>>>> TCP without TLS"
>>>> ts=2022-07-05T02:04:33.385587664Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Successfully dialed"
>>>> ts=2022-07-05T02:04:33.385613189Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Processing query response entry" entry_number=0
>>>> ts=2022-07-05T02:04:33.419235886Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line="220 mx.google.com ESMTP
>>>> dn22-20020a17090794d600b006dfc3945326si6666807ejc.152 - gsmtp"
>>>> ts=2022-07-05T02:04:33.419280493Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info msg="Regexp
>>>> matched" regexp="^220 ([^ ]+) ESMTP (.+)$" line="220 mx.google.com ESMTP
>>>> dn22-20020a17090794d600b006dfc3945326si6666807ejc.152 - gsmtp"
>>>> ts=2022-07-05T02:04:33.419310506Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Sending
>>>> line" line="EHLO prober"
>>>> ts=2022-07-05T02:04:33.419356415Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Processing query response entry" entry_number=1
>>>> ts=2022-07-05T02:04:33.43519464Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line="250-mx.google.com at your service, [130.214.226.41]"
>>>> ts=2022-07-05T02:04:33.435229899Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line="250-SIZE 157286400"
>>>> ts=2022-07-05T02:04:33.435242182Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line=250-8BITMIME
>>>> ts=2022-07-05T02:04:33.435254457Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line=250-STARTTLS
>>>> ts=2022-07-05T02:04:33.435270364Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info msg="Regexp
>>>> matched" regexp=^250-STARTTLS line=250-STARTTLS
>>>> ts=2022-07-05T02:04:33.435285968Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Processing query response entry" entry_number=2
>>>> ts=2022-07-05T02:04:33.435310813Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line=250-ENHANCEDSTATUSCODES
>>>> ts=2022-07-05T02:04:33.435323258Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line=250-PIPELINING
>>>> ts=2022-07-05T02:04:33.435337373Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line=250-CHUNKING
>>>> ts=2022-07-05T02:04:33.435373931Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line="250 SMTPUTF8"
>>>> ts=2022-07-05T02:04:33.435392537Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info msg="Regexp
>>>> matched" regexp="^250 .*$" line="250 SMTPUTF8"
>>>> ts=2022-07-05T02:04:33.435409638Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Sending
>>>> line" line=STARTTLS
>>>> ts=2022-07-05T02:04:33.435451742Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info
>>>> msg="Processing query response entry" entry_number=3
>>>> ts=2022-07-05T02:04:33.449015201Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=debug msg="Read
>>>> line" line="220 2.0.0 Ready to start TLS"
>>>> ts=2022-07-05T02:04:33.449039996Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=info msg="Regexp
>>>> matched" regexp=^220 line="220 2.0.0 Ready to start TLS"
>>>> ts=2022-07-05T02:04:33.476697731Z caller=main.go:130
>>>> module=smtp_starttls target=142.250.102.27:25 level=error msg="TLS
>>>> Handshake (client) failed" err="x509: cannot validate certificate for
>>>> 142.250.102.27 because it doesn't contain any IP SANs"
>>>> ts=2022-07-05T02:04:33.47676603Z caller=main.go:320
>>>> module=smtp_starttls target=142.250.102.27:25 level=error msg="Probe
>>>> failed" duration_seconds=0.102953678
>>>>
>>>>
>>>>
>>>>
>>>> Metrics that would have been returned:
>>>> # HELP probe_dns_lookup_time_seconds Returns the time taken for probe
>>>> dns lookup in seconds
>>>> # TYPE probe_dns_lookup_time_seconds gauge
>>>> probe_dns_lookup_time_seconds 5.1452e-05
>>>>
>>>> # HELP probe_duration_seconds Returns how long the probe took to
>>>> complete in seconds
>>>> # TYPE probe_duration_seconds gauge
>>>> probe_duration_seconds 0.102953678
>>>>
>>>> # HELP probe_failed_due_to_regex Indicates if probe failed due to regex
>>>> # TYPE probe_failed_due_to_regex gauge
>>>> probe_failed_due_to_regex 0
>>>> # HELP probe_ip_addr_hash Specifies the hash of IP address. It's useful
>>>> to detect if the IP address changes.
>>>> # TYPE probe_ip_addr_hash gauge
>>>> probe_ip_addr_hash 9.60720384e+08
>>>> # HELP probe_ip_protocol Specifies whether probe ip protocol is IP4 or
>>>> IP6
>>>> # TYPE probe_ip_protocol gauge
>>>> probe_ip_protocol 4
>>>> # HELP probe_success Displays whether or not the probe was a success
>>>> # TYPE probe_success gauge
>>>> probe_success 0
>>>>
>>>> Module configuration:
>>>> prober: tcp
>>>> timeout: 5s
>>>> http:
>>>> ip_protocol_fallback: true
>>>> follow_redirects: true
>>>> tcp:
>>>> ip_protocol_fallback: true
>>>> query_response:
>>>> - expect: ^220 ([^ ]+) ESMTP (.+)$
>>>> send: EHLO prober
>>>> - expect: ^250-STARTTLS
>>>> - expect: ^250 .*$
>>>> send: STARTTLS
>>>> - expect: ^220
>>>> starttls: true
>>>> - send: EHLO prober
>>>> - expect: ^250 .*$
>>>> send: QUIT
>>>> icmp:
>>>> ip_protocol_fallback: true
>>>> dns:
>>>> ip_protocol_fallback: true
>>>>
>>>>
>>>> On Monday, July 4, 2022 at 8:44:49 PM UTC+8 Brian Candler wrote:
>>>>
>>>>> And if you try it with Google's mail server?
>>>>> *curl -g
>>>>> 'localhost:9115/probe?module=smtp_starttls&target=aspmx.l.google.com:25&debug=true'*
>>>>>
>>>>> On Monday, 4 July 2022 at 12:50:47 UTC+1 [email protected] wrote:
>>>>>
>>>>>> Still received the same error msg:
>>>>>>
>>>>>> curl -g '
>>>>>> http://0.0.0.0:9115/probe?module=smtp_starttls&target=1.1.1.1:25&debug=true
>>>>>> '
>>>>>> Logs for the probe:
>>>>>> ts=2022-07-04T11:47:59.071097704Z caller=main.go:320
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Beginning
>>>>>> probe"
>>>>>> probe=tcp timeout_seconds=5
>>>>>> ts=2022-07-04T11:47:59.071251831Z caller=tcp.go:40
>>>>>> module=smtp_starttls target=1 1.1.1.1 :25 level=info msg="Resolving
>>>>>> target
>>>>>> address" ip_protocol=ip6
>>>>>> ts=2022-07-04T11:47:59.071292289Z caller=tcp.go:40
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Resolving
>>>>>> target
>>>>>> address" ip_protocol=ip4
>>>>>> ts=2022-07-04T11:47:59.071327224Z caller=tcp.go:40
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Resolved
>>>>>> target
>>>>>> address" ip= 1.1.1.1
>>>>>> ts=2022-07-04T11:47:59.071354819Z caller=tcp.go:121
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Dialing TCP
>>>>>> without TLS"
>>>>>> ts=2022-07-04T11:47:59.072469713Z caller=main.go:130
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Successfully
>>>>>> dialed"
>>>>>> ts=2022-07-04T11:47:59.072508076Z caller=main.go:130
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=info msg="Processing
>>>>>> query
>>>>>> response entry" entry_number=0
>>>>>> ts=2022-07-04T11:47:59.073453639Z caller=main.go:130
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=debug msg="Read line"
>>>>>> line="220 ESMTP"
>>>>>> ts=2022-07-04T11:48:04.072081695Z caller=main.go:130
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=error msg="Error reading
>>>>>> from connection" err="read tcp4 0.0.0.0:53872->1.1.1.1:25: i/o
>>>>>> timeout"
>>>>>> ts=2022-07-04T11:48:04.072269643Z caller=main.go:320
>>>>>> module=smtp_starttls target= 1.1.1.1 :25 level=error msg="Probe failed"
>>>>>> duration_seconds=5.00106792
>>>>>>
>>>>>>
>>>>>>
>>>>>> Metrics that would have been returned:
>>>>>> # HELP probe_dns_lookup_time_seconds Returns the time taken for probe
>>>>>> dns lookup in seconds
>>>>>> # TYPE probe_dns_lookup_time_seconds gauge
>>>>>> probe_dns_lookup_time_seconds 0.000101683
>>>>>> # HELP probe_duration_seconds Returns how long the probe took to
>>>>>> complete in seconds
>>>>>> # TYPE probe_duration_seconds gauge
>>>>>> probe_duration_seconds 5.00106792
>>>>>> # HELP probe_failed_due_to_regex Indicates if probe failed due to
>>>>>> regex
>>>>>> # TYPE probe_failed_due_to_regex gauge
>>>>>> probe_failed_due_to_regex 0
>>>>>> # HELP probe_ip_addr_hash Specifies the hash of IP address. It's
>>>>>> useful to detect if the IP address changes.
>>>>>> # TYPE probe_ip_addr_hash gauge
>>>>>> probe_ip_addr_hash 2.493392506e+09
>>>>>> # HELP probe_ip_protocol Specifies whether probe ip protocol is IP4
>>>>>> or IP6
>>>>>> # TYPE probe_ip_protocol gauge
>>>>>> probe_ip_protocol 4
>>>>>> # HELP probe_success Displays whether or not the probe was a success
>>>>>> # TYPE probe_success gauge
>>>>>> probe_success 0
>>>>>>
>>>>>>
>>>>>>
>>>>>> Module configuration:
>>>>>> prober: tcp
>>>>>> timeout: 5s
>>>>>> http:
>>>>>> ip_protocol_fallback: true
>>>>>> follow_redirects: true
>>>>>> tcp:
>>>>>> ip_protocol_fallback: true
>>>>>> query_response:
>>>>>> - expect: ^220 ([^ ]+) ESMTP (.+)$
>>>>>> send: EHLO prober
>>>>>> - expect: ^250-STARTTLS
>>>>>> - expect: ^250 .*$
>>>>>> send: STARTTLS
>>>>>> - expect: ^220
>>>>>> starttls: true
>>>>>> - send: EHLO prober
>>>>>> - expect: ^250 .*$
>>>>>> send: QUIT
>>>>>> icmp:
>>>>>> ip_protocol_fallback: true
>>>>>> dns:
>>>>>> ip_protocol_fallback: true
>>>>>>
>>>>>> On Monday, July 4, 2022 at 7:22:08 PM UTC+8 Brian Candler wrote:
>>>>>>
>>>>>>> Could you first just try copy-pasting the working config I gave you,
>>>>>>> and see if it gives a different result to yours?
>>>>>>>
>>>>>>> I doubt a mail server would take more than 5 seconds to give a
>>>>>>> banner, but it's possible. Simply doing "telnet x.x.x.x 25" will show
>>>>>>> you
>>>>>>> how your mail server behaves.
>>>>>>>
>>>>>>> On Monday, 4 July 2022 at 11:14:23 UTC+1 [email protected] wrote:
>>>>>>>
>>>>>>>>
>>>>>>>> Yes in my code the first expect is quoted.
>>>>>>>>
>>>>>>>> smtp_starttls:
>>>>>>>> prober: tcp
>>>>>>>> timeout: 5s
>>>>>>>> tcp:
>>>>>>>> query_response:
>>>>>>>> - expect: "^220 ([^ ]+) ESMTP (.+)$"
>>>>>>>> - send: "EHLO prober\r"
>>>>>>>> - expect: "^250-STARTTLS"
>>>>>>>> - send: "STARTTLS\r"
>>>>>>>> - expect: "^220"
>>>>>>>> - starttls: true
>>>>>>>> - send: "EHLO prober\r"
>>>>>>>> - expect: "^250-AUTH"
>>>>>>>> - send: "QUIT\r"
>>>>>>>>
>>>>>>>> Probably due to the "timeout" is too less? Should I adjust 5s to
>>>>>>>> 15s or 60s?
>>>>>>>> On Monday, July 4, 2022 at 5:55:09 PM UTC+8 Brian Candler wrote:
>>>>>>>>
>>>>>>>>> The following works for me with blackbox_exporter 0.21.0 (maybe
>>>>>>>>> your first 'expect' regexp needs to be quoted?)
>>>>>>>>>
>>>>>>>>> modules:
>>>>>>>>> smtp_starttls:
>>>>>>>>> prober: tcp
>>>>>>>>> timeout: 5s
>>>>>>>>> tcp:
>>>>>>>>> query_response:
>>>>>>>>> - expect: "^220 ([^ ]+) ESMTP (.+)$"
>>>>>>>>> send: "EHLO prober"
>>>>>>>>> - expect: "^250-STARTTLS"
>>>>>>>>> - expect: "^250 .*$"
>>>>>>>>> send: "STARTTLS"
>>>>>>>>> - expect: "^220"
>>>>>>>>>
>>>>>>>>> starttls: true
>>>>>>>>> - send: "EHLO prober"
>>>>>>>>> - expect: "^250 .*$"
>>>>>>>>> send: "QUIT"
>>>>>>>>>
>>>>>>>>> Result:
>>>>>>>>>
>>>>>>>>> # *curl -g
>>>>>>>>> 'localhost:9115/probe?module=smtp_starttls&target=aspmx.l.google.com:25&debug=true'*
>>>>>>>>> Logs for the probe:
>>>>>>>>> ts=2022-07-04T09:50:46.764604425Z caller=main.go:351
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Beginning probe" probe=tcp timeout_seconds=5
>>>>>>>>> ts=2022-07-04T09:50:46.764875668Z caller=tcp.go:40
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Resolving target address" target=aspmx.l.google.com
>>>>>>>>> ip_protocol=ip6
>>>>>>>>> ts=2022-07-04T09:50:46.765954096Z caller=tcp.go:40
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Resolved target address" target=aspmx.l.google.com
>>>>>>>>> ip=2a00:1450:400c:c07::1b
>>>>>>>>> ts=2022-07-04T09:50:46.766011111Z caller=tcp.go:121
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Dialing TCP without TLS"
>>>>>>>>> ts=2022-07-04T09:50:46.776962616Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Successfully dialed"
>>>>>>>>> ts=2022-07-04T09:50:46.77708032Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=0
>>>>>>>>> ts=2022-07-04T09:50:46.788075017Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="220 mx.google.com ESMTP
>>>>>>>>> u1-20020a056000038100b0021bbcef3a69si36572085wrf.542 - gsmtp"
>>>>>>>>> ts=2022-07-04T09:50:46.788168204Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Regexp matched" regexp="^220 ([^ ]+) ESMTP (.+)$" line="220
>>>>>>>>> mx.google.com ESMTP
>>>>>>>>> u1-20020a056000038100b0021bbcef3a69si36572085wrf.542 - gsmtp"
>>>>>>>>> ts=2022-07-04T09:50:46.788223914Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Sending line" line="EHLO prober"
>>>>>>>>> ts=2022-07-04T09:50:46.788362926Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=1
>>>>>>>>> ts=2022-07-04T09:50:46.801755535Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250-mx.google.com at your service,
>>>>>>>>> [xx:xx:xx:xx::33]"
>>>>>>>>> ts=2022-07-04T09:50:46.801856147Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250-SIZE 157286400"
>>>>>>>>> ts=2022-07-04T09:50:46.801889927Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-8BITMIME
>>>>>>>>> ts=2022-07-04T09:50:46.801922312Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-STARTTLS
>>>>>>>>> ts=2022-07-04T09:50:46.801955499Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Regexp matched" regexp=^250-STARTTLS line=250-STARTTLS
>>>>>>>>> ts=2022-07-04T09:50:46.801989646Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=2
>>>>>>>>> ts=2022-07-04T09:50:46.802029066Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-ENHANCEDSTATUSCODES
>>>>>>>>> ts=2022-07-04T09:50:46.802063318Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-PIPELINING
>>>>>>>>> ts=2022-07-04T09:50:46.802094713Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-CHUNKING
>>>>>>>>> ts=2022-07-04T09:50:46.802126198Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250 SMTPUTF8"
>>>>>>>>> ts=2022-07-04T09:50:46.802159858Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Regexp matched" regexp="^250 .*$" line="250 SMTPUTF8"
>>>>>>>>> ts=2022-07-04T09:50:46.802191065Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Sending line" line=STARTTLS
>>>>>>>>> ts=2022-07-04T09:50:46.802271697Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=3
>>>>>>>>> ts=2022-07-04T09:50:46.812211682Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="220 2.0.0 Ready to start TLS"
>>>>>>>>> ts=2022-07-04T09:50:46.812279339Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Regexp matched" regexp=^220 line="220 2.0.0 Ready to start TLS"
>>>>>>>>> ts=2022-07-04T09:50:46.833573801Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="TLS Handshake (client) succeeded."
>>>>>>>>> ts=2022-07-04T09:50:46.833793385Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=4
>>>>>>>>> ts=2022-07-04T09:50:46.833838422Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Sending line" line="EHLO prober"
>>>>>>>>> ts=2022-07-04T09:50:46.833960852Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Processing query response entry" entry_number=5
>>>>>>>>> ts=2022-07-04T09:50:46.84464181Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250-mx.google.com at your service,
>>>>>>>>> [xx:xx:xx:xx::33]"
>>>>>>>>> ts=2022-07-04T09:50:46.844771942Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250-SIZE 157286400"
>>>>>>>>> ts=2022-07-04T09:50:46.844839074Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-8BITMIME
>>>>>>>>> ts=2022-07-04T09:50:46.844910271Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-ENHANCEDSTATUSCODES
>>>>>>>>> ts=2022-07-04T09:50:46.844974538Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-PIPELINING
>>>>>>>>> ts=2022-07-04T09:50:46.845061058Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line=250-CHUNKING
>>>>>>>>> ts=2022-07-04T09:50:46.84512579Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Read line" line="250 SMTPUTF8"
>>>>>>>>> ts=2022-07-04T09:50:46.845193403Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Regexp matched" regexp="^250 .*$" line="250 SMTPUTF8"
>>>>>>>>> ts=2022-07-04T09:50:46.84525809Z caller=main.go:144
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=debug
>>>>>>>>> msg="Sending line" line=QUIT
>>>>>>>>> ts=2022-07-04T09:50:46.845583228Z caller=main.go:351
>>>>>>>>> module=smtp_starttls target=aspmx.l.google.com:25 level=info
>>>>>>>>> msg="Probe succeeded" duration_seconds=0.080912196
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>> Metrics that would have been returned:
>>>>>>>>> # HELP probe_dns_lookup_time_seconds Returns the time taken for
>>>>>>>>> probe dns lookup in seconds
>>>>>>>>> # TYPE probe_dns_lookup_time_seconds gauge
>>>>>>>>> probe_dns_lookup_time_seconds 0.001103057
>>>>>>>>>
>>>>>>>>> # HELP probe_duration_seconds Returns how long the probe took to
>>>>>>>>> complete in seconds
>>>>>>>>> # TYPE probe_duration_seconds gauge
>>>>>>>>> probe_duration_seconds 0.080912196
>>>>>>>>>
>>>>>>>>> # HELP probe_failed_due_to_regex Indicates if probe failed due to
>>>>>>>>> regex
>>>>>>>>> # TYPE probe_failed_due_to_regex gauge
>>>>>>>>> probe_failed_due_to_regex 0
>>>>>>>>> # HELP probe_ip_addr_hash Specifies the hash of IP address. It's
>>>>>>>>> useful to detect if the IP address changes.
>>>>>>>>> # TYPE probe_ip_addr_hash gauge
>>>>>>>>> probe_ip_addr_hash 2.766777767e+09
>>>>>>>>>
>>>>>>>>> # HELP probe_ip_protocol Specifies whether probe ip protocol is
>>>>>>>>> IP4 or IP6
>>>>>>>>> # TYPE probe_ip_protocol gauge
>>>>>>>>> probe_ip_protocol 6
>>>>>>>>> # HELP probe_ssl_earliest_cert_expiry Returns earliest SSL cert
>>>>>>>>> expiry date
>>>>>>>>> # TYPE probe_ssl_earliest_cert_expiry gauge
>>>>>>>>> probe_ssl_earliest_cert_expiry 1.661764429e+09
>>>>>>>>> # HELP probe_ssl_last_chain_expiry_timestamp_seconds Returns last
>>>>>>>>> SSL chain expiry in unixtime
>>>>>>>>> # TYPE probe_ssl_last_chain_expiry_timestamp_seconds gauge
>>>>>>>>> probe_ssl_last_chain_expiry_timestamp_seconds 1.661764429e+09
>>>>>>>>>
>>>>>>>>> # HELP probe_success Displays whether or not the probe was a
>>>>>>>>> success
>>>>>>>>> # TYPE probe_success gauge
>>>>>>>>> probe_success 1
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>> Module configuration:
>>>>>>>>> prober: tcp
>>>>>>>>> timeout: 5s
>>>>>>>>> http:
>>>>>>>>> ip_protocol_fallback: true
>>>>>>>>> follow_redirects: true
>>>>>>>>> enable_http2: true
>>>>>>>>>
>>>>>>>>> tcp:
>>>>>>>>> ip_protocol_fallback: true
>>>>>>>>> query_response:
>>>>>>>>> - expect: ^220 ([^ ]+) ESMTP (.+)$
>>>>>>>>> send: EHLO prober
>>>>>>>>> - expect: ^250-STARTTLS
>>>>>>>>> - expect: ^250 .*$
>>>>>>>>> send: STARTTLS
>>>>>>>>> - expect: ^220
>>>>>>>>>
>>>>>>>>> starttls: true
>>>>>>>>> - send: EHLO prober
>>>>>>>>> - expect: ^250 .*$
>>>>>>>>> send: QUIT
>>>>>>>>>
>>>>>>>>> icmp:
>>>>>>>>> ip_protocol_fallback: true
>>>>>>>>> dns:
>>>>>>>>> ip_protocol_fallback: true
>>>>>>>>> recursion_desired: true
>>>>>>>>>
>>>>>>>>
--
You received this message because you are subscribed to the Google Groups
"Prometheus Users" group.
To unsubscribe from this group and stop receiving emails from it, send an email
to [email protected].
To view this discussion on the web visit
https://groups.google.com/d/msgid/prometheus-users/e46ac147-11e0-4b70-bd87-1f182b46a26en%40googlegroups.com.
