search

haraka / Haraka

A fast, highly extensible, and event driven SMTP server
https://haraka.github.io
MIT License
5.02k stars 662 forks source link

[core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.219.27:25 #1897

Closed davejcowen closed 7 years ago

davejcowen commented 7 years ago

Everything looks correct and it's a very basic setup. Message goes into the queue but I keep getting timeouts. Any help is greatly appreciate as this is my first time using Haraka. Rather get this working than start over with something else.

[NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] connect ip=127.0.0.1 port=49486 local_ip=127.0.0.1 local_port=2525 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect_init hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect_init_respond [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running lookup_rdns hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking 127.0.0.1 against connect.rdns_access.whitelist [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking 127.0.0.1 against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking unknown against connect.rdns_access.whitelist [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking unknown against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking 127.0.0.1 against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] checking unknown against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=connect plugin=access function=rdns_access params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [access] domain detect failed on hook: connect [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=connect plugin=access function=any params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect hook in dnsbl plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [dnsbl] skip private: 127.0.0.1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running connect hook in max_unrecognized_commands plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=connect plugin=max_unrecognized_commands function=hook_connect params="" retval=CONT msg="" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 220 renegade-15.systemtest.com ESMTP Haraka 2.8.13 ready [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] C: EHLO renegade-15.systemtest.com state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=access function=any params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=init params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=match_re params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=bare_ip params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=dynamic params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=big_company params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=valid_hostname params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=rdns_match params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running ehlo hook in helo.checks plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=ehlo plugin=helo.checks function=forward_dns params="renegade-15.systemtest.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running capabilities hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running capabilities hook in auth/flat_file plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=capabilities plugin=auth/flat_file function=hook_capabilities params="" retval=CONT msg="" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 250-renegade-15.systemtest.com Hello Unknown [127.0.0.1], Haraka is at your service. [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 250-PIPELINING [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 250-8BITMIME [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 250-SIZE 0 [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 250 AUTH LOGIN CRAM-MD5 [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] C: AUTH CRAM-MD5 state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running unrecognized_command hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running unrecognized_command hook in auth/flat_file plugin [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [auth/flat_file] ticket: 3c6c4.15bc59edf2b@renegade-15.systemtest.com [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 334 PDNjNmM0LjE1YmM1OWVkZjJiQHJlbmVnYWRlLTE1LnN5c3RlbXRlc3QuY29tPg== [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="AUTH" retval=OK msg="" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] C: YWRtaW4gNzU1MTIxZGJjNDhkOWQzZjE4NTdiZDZhYzk2NmZkYzI= state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running unrecognized_command hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] S: 235 Authentication successful [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="YWRtaW4gNzU1MTIxZGJjNDhkOWQzZjE4NTdiZDZhYzk2NmZkYzI=" retval=OK msg="" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93] [core] C: MAIL FROM:admin@myrealflyer.com state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running mail hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running mail hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist_regex [DEBUG] [-] [access] empty file: mail_from.access.whitelist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [access] checking admin@myrealflyer.com against mail_from.access.blacklist_regex [DEBUG] [-] [access] empty file: mail_from.access.blacklist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=mail plugin=access function=mail_from_access params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running mail hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=mail plugin=access function=any params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running mail hook in mail_from.is_resolvable plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [mail_from.is_resolvable] myrealflyer.com: MX 1 aspmx.l.google.com => 74.125.70.27,2607:f8b0:4001:c02::1b [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt1.aspmx.l.google.com => 209.85.232.27,2607:f8b0:400d:c0d::1a [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [mail_from.is_resolvable] myrealflyer.com: MX 10 aspmx2.googlemail.com => 2607:f8b0:400d:c0d::1b,209.85.232.27 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt2.aspx.l.google.com => queryA ENOTFOUND alt2.aspx.l.google.com [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [mail_from.is_resolvable] myrealflyer.com: 74.125.70.27,2607:f8b0:4001:c02::1b,209.85.232.27,2607:f8b0:400d:c0d::1a,2607:f8b0:400d:c0d::1b [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=mail plugin=mail_from.is_resolvable function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running mail hook in rcpt_to.in_host_list plugin [DEBUG] [-] [rcpt_to.in_host_list] checking myrealflyer.com in config/host_list [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=mail plugin=rcpt_to.in_host_list function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] sender admin@myrealflyer.com code=CONT msg="" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] S: 250 sender admin@myrealflyer.com OK [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: RCPT TO:davidcowen69@gmail.com state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running rcpt hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running rcpt hook in access plugin [DEBUG] [-] [access] empty file: rcpt_to.access.whitelist_regex [DEBUG] [-] [access] empty file: rcpt_to.access.blacklist_regex [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=rcpt plugin=access function=rcpt_to_access params="davidcowen69@gmail.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running rcpt hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=rcpt plugin=access function=any params="davidcowen69@gmail.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running rcpt hook in rcpt_to.in_host_list plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [rcpt_to.in_host_list] Checking if davidcowen69@gmail.com host is in host_list [DEBUG] [-] [rcpt_to.in_host_list] checking gmail.com in config/host_list [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=rcpt plugin=rcpt_to.in_host_list function=hook_rcpt params="davidcowen69@gmail.com" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running rcpt_ok hooks [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] recipient davidcowen69@gmail.com code=OK msg="" sender="admin@myrealflyer.com" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] S: 250 recipient davidcowen69@gmail.com OK [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: DATA state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data hooks [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] S: 354 go ahead, make my day [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: Date: Mon, 01 May 2017 16:06:15 -0400\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: To: davidcowen69@gmail.com\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: From: admin@myrealflyer.com\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: Subject: test Mon, 01 May 2017 16:06:15 -0400\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: Message-Id: 20170501160615.035410@renegade-15.systemtest.com\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: X-Mailer: swaks v20170101.0 jetmore.org/john/code/swaks/\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: \r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: This is a test mailing\r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: \r [DATA] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: .\r [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in access plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=access function=data_any params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [data.headers] message date: Mon, 01 May 2017 16:06:15 -0400 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running data_post hook in data.headers plugin [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg="" [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] message mid="20170501160615.035410@renegade-15.systemtest.com" size=296 rcpts=1/0/0 delay=0.005 code=CONT msg="" [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running queue_outbound hooks [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] queue code=CONT msg=" (D01B4388-7099-4560-9A22-C6ABBBDCEA93.1)" [INFO] [-] [core] [outbound] Sending email as a transaction [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: gmail.com [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running queue_ok hooks [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] queue code=OK msg="Message Queued" [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] S: 250 Message Queued [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running reset_transaction hooks [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] C: QUIT state=1 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running quit hooks [PROTOCOL] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] S: 221 renegade-15.systemtest.com closing connection. Have a jolly good day. [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] running disconnect hooks [NOTICE] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1] [core] disconnect ip=127.0.0.1 rdns="Unknown" helo="renegade-15.systemtest.com" relay=Y early=N esmtp=Y tls=N pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=296 lr="" time=0.215 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] running send_email hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Sending mail: 1493669175261_0_35217_852.renegade-15.systemtest.com [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] running get_mx hooks [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: gmail-smtp-in.l.google.com [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 74.125.70.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:74.125.70.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:74.125.70.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=74.125.70.27 port=25 pool_timeout=50 created [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 74.125.70.27:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt1.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:74.125.70.27:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.219.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.219.27 port=25 pool_timeout=50 created [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.219.27:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt2.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.211.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.211.27 port=25 pool_timeout=50 created [INFO] [-] [core] [outbound] [outbound::25:74.125.70.27:undefined:50] dispense() clients=0 available=0 [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.211.27:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt3.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.211.26:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.211.26:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.211.26:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.211.26 port=25 pool_timeout=50 created [INFO] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] dispense() clients=0 available=0 [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.211.26:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt4.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:173.194.211.26:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 209.85.203.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:209.85.203.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:209.85.203.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=209.85.203.27 port=25 pool_timeout=50 created [INFO] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] dispense() clients=0 available=0 [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 209.85.203.27:25 [DEBUG] [-] [core] Temp fail for: Tried all MXs [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] running deferred hooks [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Temp failing 1493669175261_0_35217_852.renegade-15.systemtest.com for 64 seconds: Tried all MXs [INFO] [-] [core] [outbound] [outbound::25:209.85.203.27:undefined:50] dispense() clients=0 available=0 [INFO] [-] [core] [outbound] [outbound::25:173.194.211.26:undefined:50] dispense() clients=0 available=0 [INFO] [-] [core] [outbound] [outbound::25:209.85.203.27:undefined:50] dispense() clients=0 available=0 [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] running send_email hooks [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Sending mail: 1493669389802_1_35217_852.renegade-15.systemtest.com [DEBUG] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] running get_mx hooks [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: gmail-smtp-in.l.google.com [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.66.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.66.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.66.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.66.27 port=25 pool_timeout=50 created [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.66.27:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt1.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:173.194.66.27:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.219.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.219.27 port=25 pool_timeout=50 created [ERROR] [-] [core] [outbound] Failed to get pool entry: Outbound connection timed out to 173.194.219.27:25 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Looking up A records for: alt2.gmail-smtp-in.l.google.com [INFO] [-] [core] [outbound] [outbound::25:173.194.219.27:undefined:50] dispense() clients=0 available=0 [INFO] [D01B4388-7099-4560-9A22-C6ABBBDCEA93.1.1] [outbound] Attempting to deliver to: 173.194.211.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.211.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.211.27 port=25 pool_timeout=50 created

smfreegard commented 7 years ago

Looks like whatever server you are running Haraka on is firewalled from accessing port 25 outbound.