[ https://issues.apache.org/jira/browse/TS-435?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12931001#action_12931001 ]
mohan_zl commented on TS-435: ----------------------------- we have reproduce this problem when using latest trunk, and looks like something messed during queue and handler match: [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 {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.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 we have 3 thread connected to 10.2.1.73:53 unknown DNS id = 50074 show then dns result is mistake by other threads, it took the dns result a unknown dns pkt, and dropped, so you can not resolve any dns will patch it asap > SplitDNS doesn't work > --------------------- > > Key: TS-435 > URL: https://issues.apache.org/jira/browse/TS-435 > Project: Traffic Server > Issue Type: Bug > Components: DNS > Affects Versions: 2.0.1 > Environment: Debian 5.0.5 kernel 2.6.26-1-686 #1 SMP > Reporter: Martin Leventan > Assignee: Leif Hedstrom > Priority: Trivial > Fix For: 2.1.5 > > > 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 -- This message is automatically generated by JIRA. - You can reply to this email to add a comment to the issue online.