[TS-435] SplitDNS doesn't work

• Type: Bug
• Status: Closed
• Priority: Trivial
• Resolution: Fixed
• Affects Version/s: 2.0.1
• Fix Version/s: 2.1.5
• Component/s: DNS

Environment

Debian 5.0.5 kernel 2.6.26-1-686 #1 SMP

• Assignee: Leif Hedstrom
• Reporter: Martin Leventan
• Created: 08/Sep/10 15:22
• Updated: 25/Jan/11 05:06
• Resolved: 18/Nov/10 15:14

Description

Server configured as forward proxy with SplitDNS enable. At start get system DNS server but at client request fail to resolve URL.

record.config (DNS section)

CONFIG proxy.config.dns.search_default_domains INT 1
CONFIG proxy.config.dns.splitDNS.enabled INT 1
CONFIG proxy.config.dns.splitdns.def_domain STRING lapampa.gov.ar
CONFIG proxy.config.dns.url_expansions STRING NULL
CONFIG proxy.config.dns.round_robin_nameservers INT 0
CONFIG proxy.config.dns.nameservers STRING NULL
CONFIG proxy.config.dns.validate_query_name INT 0

splitdns.config
dest_domain=lapampa.gov.ar named=10.2.1.72 serach_list=lapampa.gov.ar

Debug:

[TrafficServer] using root directory '/opt/trafficserver-2'
[Sep 8 11:55:57.512]

{3079767744} STATUS: opened var/log/trafficserver/diags.log
[Sep 8 11:55:57.514] Server {3079767744}

DEBUG: (http_aeua) [HttpConfig::init_aeua_filter] - Config: "etc/trafficserver/ae_ua.config"
[Sep 8 11:55:57.514] Server

{3079767744} DEBUG: (http_aeua) [HttpConfig::init_aeua_filter] - Opening config "etc/trafficserver/ae_ua.config"
[Sep 8 11:55:57.514] Server {3079767744}

DEBUG: (http_aeua) [HttpConfig::init_aeua_filter] - Added 0 REGEXP filters
[Sep 8 11:55:57.514] Server

{3079767744} DEBUG: (http_aeua) [init_http_aeua_filter] - Total loaded 0 REGEXP for Accept-Enconding/User-Agent filtering
[Sep 8 11:55:57.548] Server {3079767744}

DEBUG: (dns) ink_dns_init: called with init_called = 0
[Sep 8 11:55:57.587] Server

{3079767744} DEBUG: (dns) localhost=h6514asi106.glp.gov.ar
[Sep 8 11:55:57.587] Server {3079767744}

DEBUG: (dns) Round-robin nameservers = 0
[Sep 8 11:55:57.616] Server

{3062635408} DEBUG: (dns) DNSHandler::startEvent_sdns: on thread2
[Sep 8 11:55:57.616] Server {3062635408}

DEBUG: (dns) open_con: opening connection 10.2.1.73:53
[Sep 8 11:55:57.616] Server

{3062635408} DEBUG: (dns) random port = 17507
[Sep 8 11:55:57.616] Server {3062635408}

DEBUG: (dns) opening connection 10.2.1.73:53 SUCCEEDED for 0
[Sep 8 11:55:57.616] Server

{3063688080} DEBUG: (dns) DNSHandler::startEvent_sdns: on thread1
[Sep 8 11:55:57.616] Server {3063688080}

DEBUG: (dns) open_con: opening connection 10.2.1.73:53
[Sep 8 11:55:57.616] Server

{3063688080} DEBUG: (dns) random port = 42681
[Sep 8 11:55:57.616] Server {3063688080}

DEBUG: (dns) opening connection 10.2.1.73:53 SUCCEEDED for 0
[Sep 8 11:55:57.690] Server

{3079767744} DEBUG: (http_init) proxy.config.connection_collapsing.hashtable_enabled = 0
[Sep 8 11:55:57.690] Server {3079767744}

DEBUG: (http_init) proxy.config.connection_collapsing.rww_wait_time = 0
[Sep 8 11:55:57.690] Server

{3079767744} DEBUG: (http_init) proxy.config.connection_collapsing.revalidate_window_period = 0
[Sep 8 11:55:57.690] Server {3079767744}

DEBUG: (http_init) proxy.config.http.redirection_enabled = 0
[Sep 8 11:55:57.690] Server

{3079767744} DEBUG: (http_init) proxy.config.http.number_of_redirections = 1
[Sep 8 11:55:57.690] Server {3079767744}

DEBUG: (http_init) proxy.config.http.post_copy_size = 2048
[Sep 8 11:55:57.713] Server

{3079767744} DEBUG: (dns) DNSHandler::startEvent: on thread0
[Sep 8 11:55:57.713] Server {3079767744}

DEBUG: (dns) open_con: opening connection 10.2.1.73:53
[Sep 8 11:55:57.715] Server

{3079767744} DEBUG: (dns) random port = 40576
[Sep 8 11:55:57.715] Server {3079767744}

DEBUG: (dns) opening connection 10.2.1.73:53 SUCCEEDED for 0
.....
+++++++++ Incoming Request +++++++++
– State Machine Id: 0
GET http://www.lapampa.gov.ar/ HTTP/1.1^M
Host: www.lapampa.gov.ar^M
User-Agent: Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.0.19) Gecko/2010072023 Iceweasel/3.0.6 (Debian-3.0.6-3)^M
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,/;q=0.8^M
Accept-Language: en-us,en;q=0.5^M
Accept-Encoding: gzip,deflate^M
Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7^M
Keep-Alive: 300^M
Proxy-Connection: keep-alive^M
Cookie: LaPampa2_tpl=LaPampa2^M
Cache-Control: max-age=0^M
^M
[Sep 8 11:56:01.134] Server

{3079767744} DEBUG: (http_trans) [DecideCacheLookup] Will do cache lookup.
[Sep 8 11:56:01.134] Server {3079767744}

DEBUG: (http_seq) [DecideCacheLookup] Will do cache lookup
[Sep 8 11:56:01.134] Server

{3079767744} DEBUG: (http_trans) Next action CACHE_LOOKUP; NULL
[Sep 8 11:56:01.134] Server {3079767744}

DEBUG: (http) [0] State Transition: API_READ_REQUEST_HDR -> CACHE_LOOKUP
[Sep 8 11:56:01.134] Server

{3079767744} DEBUG: (http_seq) [HttpSM::do_cache_lookup_and_read] Issuing cache lookup
[Sep 8 11:56:01.134] Server {3079767744}

DEBUG: (http_cache) [0] [&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Sep 8 11:56:01.135] Server

{3079767744} DEBUG: (http) [0] [HttpSM::main_handler, CACHE_EVENT_OPEN_READ_FAILED]
[Sep 8 11:56:01.135] Server {3079767744}

DEBUG: (http) [0] [&HttpSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Sep 8 11:56:01.135] Server

{3079767744} DEBUG: (http) [0] cache_open_read - CACHE_EVENT_OPEN_READ_FAILED
[Sep 8 11:56:01.135] Server {3079767744}

DEBUG: (http) [state_cache_open_read] open read failed.
[Sep 8 11:56:01.135] Server

{3079767744} DEBUG: (http_trans) [HttpTransact::HandleCacheOpenRead]
[Sep 8 11:56:01.135] Server {3079767744}

DEBUG: (http_trans) CacheOpenRead – miss
[Sep 8 11:56:01.135] Server

{3079767744} DEBUG: (http_trans) Next action DNS_LOOKUP; OSDNSLookup
[Sep 8 11:56:01.135] Server {3079767744}

DEBUG: (http) [0] State Transition: CACHE_LOOKUP -> DNS_LOOKUP
[Sep 8 11:56:01.135] Server

{3079767744} DEBUG: (http_seq) [HttpStateMachineGet::do_hostdb_lookup] Doing DNS Lookup
[Sep 8 11:56:01.135] Server {3079767744}

DEBUG: (dns) received query www.lapampa.gov.ar type = 1, timeout = 0
[Sep 8 11:56:01.137] Server

{3079767744} DEBUG: (dns) enqueing query www.lapampa.gov.ar
[Sep 8 11:56:01.137] Server {3079767744}

DEBUG: (dns) adding first to collapsing queue
[Sep 8 11:56:01.137] Server

{3079767744} DEBUG: (dns) in_flight: 0, dns_max_dns_in_flight: 60
[Sep 8 11:56:01.137] Server {3079767744}

DEBUG: (dns) send query for www.lapampa.gov.ar to fd 34
[Sep 8 11:56:01.137] Server

{3079767744} DEBUG: (dns) sent qname = www.lapampa.gov.ar, id = 50074, nameserver = 0
[Sep 8 11:56:01.137] Server {3079767744}

DEBUG: (dns) sent_one: failover_number for resolver 0 is 1
[Sep 8 11:56:01.137] Server

{3063688080} DEBUG: (dns) in_flight: 1, dns_max_dns_in_flight: 60
[Sep 8 11:56:01.138] Server {3079767744} DEBUG: (dns) received packet size = 52
[Sep 8 11:56:01.138] Server {3079767744} DEBUG: (dns) primary DNS response code = 0
[Sep 8 11:56:01.138] Server {3079767744} DEBUG: (dns) unknown DNS id = 50074
[Sep 8 11:56:01.141] Server {3063688080}

DEBUG: (dns) in_flight: 1, dns_max_dns_in_flight: 60