From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.2 (2018-09-13) on dcvr.yhbt.net X-Spam-Level: X-Spam-Status: No, score=-4.2 required=3.0 tests=AWL,BAYES_00,DKIM_SIGNED, DKIM_VALID,DKIM_VALID_AU,DKIM_VALID_EF,MAILING_LIST_MULTI,NICE_REPLY_A, SPF_HELO_PASS,SPF_PASS shortcircuit=no autolearn=ham autolearn_force=no version=3.4.2 Received: from sourceware.org (server2.sourceware.org [IPv6:2620:52:3:1:0:246e:9693:128c]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256) (No client certificate requested) by dcvr.yhbt.net (Postfix) with ESMTPS id 3585F1F4B4 for ; Wed, 14 Oct 2020 16:32:24 +0000 (UTC) Received: from server2.sourceware.org (localhost [IPv6:::1]) by sourceware.org (Postfix) with ESMTP id 171E0385040C; Wed, 14 Oct 2020 16:32:23 +0000 (GMT) DKIM-Filter: OpenDKIM Filter v2.11.0 sourceware.org 171E0385040C DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=sourceware.org; s=default; t=1602693143; bh=930ZLtmkYjTu63AnIUmZfWi7kkpIQrbVwdFepAellyY=; h=Subject:To:References:Date:In-Reply-To:List-Id:List-Unsubscribe: List-Archive:List-Post:List-Help:List-Subscribe:From:Reply-To: From; b=hkO2IAcjNCcUJEIX+PVJg8Ryf0E3N6Rd8ktSdBvvcjtGXYFYs/RPHTXZpc04yn6xt iDx7noArgkrO4lILe0ni/FaNDo0fexdymp2gmwjFbx0Zw+54VaQMPWMDdLw3Zj1SmC YcyaYuCtaA3envtf/B7i1xgtohMtx1LbUpbAfyCY= Received: from mx0b-001b2d01.pphosted.com (mx0b-001b2d01.pphosted.com [148.163.158.5]) by sourceware.org (Postfix) with ESMTPS id 88F0A3857C46 for ; Wed, 14 Oct 2020 16:32:17 +0000 (GMT) DMARC-Filter: OpenDMARC Filter v1.3.2 sourceware.org 88F0A3857C46 Received: from pps.filterd (m0098417.ppops.net [127.0.0.1]) by mx0a-001b2d01.pphosted.com (8.16.0.42/8.16.0.42) with SMTP id 09EGHEGO154407 for ; Wed, 14 Oct 2020 12:32:17 -0400 Received: from ppma03ams.nl.ibm.com (62.31.33a9.ip4.static.sl-reverse.com [169.51.49.98]) by mx0a-001b2d01.pphosted.com with ESMTP id 3464qhrcr4-1 (version=TLSv1.2 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 verify=NOT) for ; Wed, 14 Oct 2020 12:32:16 -0400 Received: from pps.filterd (ppma03ams.nl.ibm.com [127.0.0.1]) by ppma03ams.nl.ibm.com (8.16.0.42/8.16.0.42) with SMTP id 09EGMaG7007442 for ; Wed, 14 Oct 2020 16:32:14 GMT Received: from b06cxnps4076.portsmouth.uk.ibm.com (d06relay13.portsmouth.uk.ibm.com [9.149.109.198]) by ppma03ams.nl.ibm.com with ESMTP id 3434k84bw9-1 (version=TLSv1.2 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 verify=NOT) for ; Wed, 14 Oct 2020 16:32:14 +0000 Received: from d06av21.portsmouth.uk.ibm.com (d06av21.portsmouth.uk.ibm.com [9.149.105.232]) by b06cxnps4076.portsmouth.uk.ibm.com (8.14.9/8.14.9/NCO v10.0) with ESMTP id 09EGWBm622348142 (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-GCM-SHA384 bits=256 verify=OK) for ; Wed, 14 Oct 2020 16:32:12 GMT Received: from d06av21.portsmouth.uk.ibm.com (unknown [127.0.0.1]) by IMSVA (Postfix) with ESMTP id BD38952052 for ; Wed, 14 Oct 2020 16:32:11 +0000 (GMT) Received: from oc4452167425.ibm.com (unknown [9.145.55.220]) by d06av21.portsmouth.uk.ibm.com (Postfix) with ESMTP id 23F0452050 for ; Wed, 14 Oct 2020 16:32:11 +0000 (GMT) Subject: Re: [PATCH 3/3] resolv: Handle transaction ID collisions in parallel queries (bug 26600) To: libc-alpha@sourceware.org References: <55f9a2d387df8fae7d44275e2cf7dce8eccd120e.1599829382.git.fweimer@redhat.com> <87lfhgqx9u.fsf@oldenburg2.str.redhat.com> Message-ID: <180cdf95-e0cc-8c0d-1594-06a35a2012be@linux.ibm.com> Date: Wed, 14 Oct 2020 18:32:10 +0200 User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:68.0) Gecko/20100101 Thunderbird/68.12.0 MIME-Version: 1.0 In-Reply-To: Content-Type: multipart/mixed; boundary="------------58C6AA9843532EACBC952787" Content-Language: en-US X-TM-AS-GCONF: 00 X-Proofpoint-Virus-Version: vendor=fsecure engine=2.50.10434:6.0.235, 18.0.687 definitions=2020-10-14_09:2020-10-14, 2020-10-14 signatures=0 X-Proofpoint-Spam-Details: rule=outbound_notspam policy=outbound score=0 mlxlogscore=999 malwarescore=0 clxscore=1011 spamscore=0 lowpriorityscore=0 priorityscore=1501 suspectscore=4 impostorscore=0 phishscore=0 bulkscore=0 adultscore=0 mlxscore=0 classifier=spam adjust=0 reason=mlx scancount=1 engine=8.12.0-2009150000 definitions=main-2010140114 X-BeenThere: libc-alpha@sourceware.org X-Mailman-Version: 2.1.29 Precedence: list List-Id: Libc-alpha mailing list List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , From: Stefan Liebler via Libc-alpha Reply-To: Stefan Liebler Errors-To: libc-alpha-bounces@sourceware.org Sender: "Libc-alpha" This is a multi-part message in MIME format. --------------58C6AA9843532EACBC952787 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit On 10/13/20 8:18 PM, Siddhesh Poyarekar wrote: > On 9/11/20 6:35 PM, Florian Weimer via Libc-alpha wrote: ... > > Test is also OK. > > Looks good to me. > > Thanks, > Siddhesh > Hi Florian, I've recognized a test-fail for the new test resolv/tst-resolv-txnid-collision (of course on s390x): tst-resolv-txnid-collision.c:166: numeric comparison failure left: 0 (0x0); from: ctx->server_index right: 1 (0x1); from: previous_server_index error: tst-resolv-txnid-collision.c:167: not true: previous_qtype != qtype error: 2 test failures (See attached tst-resolv-txnid-collision_20201014_174129_4983.log) So far, I've just run the test resolv/tst-resolv-txnid-collision with --direct --verbose in a loop and collected the output (see attachements). It also happens that the test segfaults. Here is one backtrace (also see attached tst-resolv-txnid-collision_20201014_174533_4318.log): (gdb) bt full #0 0x0000000001001c28 in resolv_response_context_free (ctx=0x0) at resolv_response_context_free.c:25 No locals. #1 0x00000000010017cc in response (ctx=0x3fffcf7cab8, b=0x3ffec000b60, qname=0x3fffcf7cb72 "reorder-1-0.rcode-2.example.net", qclass=, qtype=) at tst-resolv-txnid-collision.c:204 parsed = {rcode = 2, reorder = {true, false}} #2 0x000000000100319a in server_thread_udp_process_one (obj=obj@entry=0x10eb3d0, server_index=server_index@entry=1) at resolv_test.c:677 query = "\331\032\001\000\000\001\000\000\000\000\000\000\vreorder-1-0\arcode-2\aexample\003net\000\000\001\000\001", '\000' peer = {ss_family = 2, __ss_padding = "\240\251\177\000\000\001", '\000' , __ss_align = 0} peerlen = 16 length = qinfo = {qname = "reorder-1-0.rcode-2.example.net", '\000' , qclass = 1, qtype = 1, edns = {active = false, extended_rcode = 0 '\000', version = 0 '\000', flags = 0, payload_size = 0}} ctx = {test = 0x10eb3d0, client_address = 0x3fffcf7caf0, client_address_length = 16, query_buffer = 0x3fffcf7cf80 "\331\032\001", query_length = 49, server_index = 1, tcp = false, edns = {active = false, extended_rcode = 0 '\000', version = 0 '\000', flags = 0, payload_size = 0}} b = 0x3ffec000b60 #3 0x00000000010032da in server_thread_udp (obj=0x10eb3d0, server_index=) at resolv_test.c:734 No locals. #4 0x0000000001001c74 in thread_callback_wrapper (arg=0x10eb800) at resolv_test.c:606 closure = 0x10eb800 #5 0x000000000100c93c in start_thread (arg=0x3fffcf7d870) at pthread_create.c:463 ret = start = pd = 0x3fffcf7d870 unwind_buf = {cancel_jmp_buf = {{jmp_buf = {{__gregs = {4397995645040, 4393751543808, 4398046505551, 4397995644112, 4398046505550, 4398046505552, 4397995645040, 4398046507848, 56514505740802731, 56510891581063335}, __fpregs = { 8392704, 0, 4397987254272, 0, 4397995645232, 0, 4398046507237, 2929168734320}}}, mask_was_saved = 0}}, priv = { pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}} not_first_call = #6 0x000000000104df90 in thread_start () at ../sysdeps/unix/sysv/linux/s390/s390-64/clone.S:65 No locals. => ctx (or previous_query in #1) is NULL. It also happened once at a different location. In both cases, previous_query inside response function was NULL. If I remember correctly the other location was at line 187 while accessing previous_query: struct resolv_response_builder *btmp = resolv_response_builder_allocate (previous_query->query_buffer, previous_query->query_length); I hope, this information helps. Otherwise, please let me know which further information is required. Bye, Stefan --------------58C6AA9843532EACBC952787 Content-Type: text/x-log; charset=UTF-8; name="tst-resolv-txnid-collision_20201014_174129_4983.log" Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename="tst-resolv-txnid-collision_20201014_174129_4983.log" info: old timeout value: 5 info: old retry attempt value: 2 info: old _res.options: 0x2c1 info: old _res.nscount value: 1 info: old _res.ndots value: 1 info: new timeout value: 1 info: new retry attempt value: 4 info: new _res.options: 0x2c1 info: new _res.nscount value: 3 info: new _res.ndots value: 1 info: server 0: 127.0.0.1/38873 info: server 1: 127.0.0.1/45199 info: server 2: 127.0.0.1/37231 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/28 info: sending first response for: reorder-0-0.rcode-0.example.net info: sending non-reordered second response for: reorder-0-0.rcode-0.example.net info: UDP server 0: sending response: 49 bytes, RCODE 0 (for reorder-0-0.rcode-0.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-0.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/28 info: sending first response for: reorder-0-0.rcode-0.example.net info: sending non-reordered second response for: reorder-0-0.rcode-0.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-0.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/28 info: sending first response for: reorder-0-1.rcode-0.example.net info: sending non-reordered second response for: reorder-0-1.rcode-0.example.net info: UDP server 0: sending response: 49 bytes, RCODE 0 (for reorder-0-1.rcode-0.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-0.example.net info: sending first response for: reorder-0-1.rcode-0.example.net info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/1 tst-resolv-txnid-collision.c:166: numeric comparison failure left: 0 (0x0); from: ctx->server_index right: 1 (0x1); from: previous_server_index error: tst-resolv-txnid-collision.c:167: not true: previous_qtype != qtype info: sending reordered second response for: reorder-1-0.rcode-0.example.net info: sending first response for: reorder-1-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/28 info: buffering first query for: reorder-1-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-0.example.net/1/28 info: UDP server 1: dropping response to reorder-0-1.rcode-0.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-0.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/28 info: sending first response for: reorder-1-0.rcode-0.example.net info: sending non-reordered second response for: reorder-1-0.rcode-0.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-1-0.rcode-0.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-0.example.net info: sending first response for: reorder-1-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-0.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-0.example.net info: sending first response for: reorder-1-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-0.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-1.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-1.example.net/1/28 info: sending first response for: reorder-0-0.rcode-1.example.net info: sending non-reordered second response for: reorder-0-0.rcode-1.example.net info: UDP server 0: sending response: 49 bytes, RCODE 1 (for reorder-0-0.rcode-1.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-1.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-1.example.net/1/28 info: sending first response for: reorder-0-1.rcode-1.example.net info: sending non-reordered second response for: reorder-0-1.rcode-1.example.net info: UDP server 0: sending response: 49 bytes, RCODE 1 (for reorder-0-1.rcode-1.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-1.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-1.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-1.example.net info: sending first response for: reorder-1-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-1.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-1.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-1.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-1.example.net info: sending first response for: reorder-1-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-1.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/28 info: sending first response for: reorder-0-0.rcode-2.example.net info: sending non-reordered second response for: reorder-0-0.rcode-2.example.net info: UDP server 0: sending response: 49 bytes, RCODE 2 (for reorder-0-0.rcode-2.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/28 info: sending first response for: reorder-0-0.rcode-2.example.net info: sending non-reordered second response for: reorder-0-0.rcode-2.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-2.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-2.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/28 info: sending first response for: reorder-0-1.rcode-2.example.net info: sending non-reordered second response for: reorder-0-1.rcode-2.example.net info: UDP server 0: sending response: 49 bytes, RCODE 2 (for reorder-0-1.rcode-2.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-2.example.net info: sending first response for: reorder-0-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-2.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-2.example.net info: sending first response for: reorder-1-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-2.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-2.example.net info: UDP server 1: dropping response to reorder-1-0.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/28 info: sending first response for: reorder-1-0.rcode-2.example.net info: sending non-reordered second response for: reorder-1-0.rcode-2.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-1-0.rcode-2.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-2.example.net info: sending first response for: reorder-1-1.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-2.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-2.example.net info: sending first response for: reorder-1-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-2.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-3.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-3.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-3.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-3.example.net/1/28 info: sending first response for: reorder-0-0.rcode-3.example.net info: sending non-reordered second response for: reorder-0-0.rcode-3.example.net info: UDP server 0: sending response: 49 bytes, RCODE 3 (for reorder-0-0.rcode-3.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-3.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-3.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-3.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-3.example.net/1/28 info: sending first response for: reorder-0-1.rcode-3.example.net info: sending non-reordered second response for: reorder-0-1.rcode-3.example.net info: UDP server 0: sending response: 49 bytes, RCODE 3 (for reorder-0-1.rcode-3.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-3.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-3.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-3.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-3.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-3.example.net info: sending first response for: reorder-1-0.rcode-3.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-3.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-3.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-3.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-3.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-3.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-3.example.net info: sending first response for: reorder-1-1.rcode-3.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-3.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-4.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-4.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-4.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-4.example.net/1/28 info: sending first response for: reorder-0-0.rcode-4.example.net info: sending non-reordered second response for: reorder-0-0.rcode-4.example.net info: UDP server 0: sending response: 49 bytes, RCODE 4 (for reorder-0-0.rcode-4.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-4.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-4.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-4.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-4.example.net/1/28 info: sending first response for: reorder-0-0.rcode-4.example.net info: sending non-reordered second response for: reorder-0-0.rcode-4.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-4.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-4.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-4.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-4.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-4.example.net/1/28 info: sending first response for: reorder-0-1.rcode-4.example.net info: sending non-reordered second response for: reorder-0-1.rcode-4.example.net info: UDP server 0: sending response: 49 bytes, RCODE 4 (for reorder-0-1.rcode-4.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-4.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-4.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-4.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-4.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-4.example.net info: sending first response for: reorder-0-1.rcode-4.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-4.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-4.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-4.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-4.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-4.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-4.example.net info: sending first response for: reorder-1-0.rcode-4.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-4.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-4.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-4.example.net info: UDP server 1: dropping response to reorder-1-0.rcode-4.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-4.example.net/1/28 info: sending first response for: reorder-1-0.rcode-4.example.net info: sending non-reordered second response for: reorder-1-0.rcode-4.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-1-0.rcode-4.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-4.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-4.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-4.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-4.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-4.example.net info: sending first response for: reorder-1-1.rcode-4.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-4.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-4.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-4.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-4.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-4.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-4.example.net info: sending first response for: reorder-1-1.rcode-4.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-4.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-5.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-5.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-5.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-5.example.net/1/28 info: sending first response for: reorder-0-0.rcode-5.example.net info: sending non-reordered second response for: reorder-0-0.rcode-5.example.net info: UDP server 0: sending response: 49 bytes, RCODE 5 (for reorder-0-0.rcode-5.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-5.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-5.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-5.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-5.example.net/1/28 info: sending first response for: reorder-0-0.rcode-5.example.net info: sending non-reordered second response for: reorder-0-0.rcode-5.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-5.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-5.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-5.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-5.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-5.example.net/1/28 info: sending first response for: reorder-0-1.rcode-5.example.net info: sending non-reordered second response for: reorder-0-1.rcode-5.example.net info: UDP server 0: sending response: 49 bytes, RCODE 5 (for reorder-0-1.rcode-5.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-5.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-5.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-5.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-5.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-5.example.net info: sending first response for: reorder-0-1.rcode-5.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-5.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-5.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-5.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-5.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-5.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-5.example.net info: sending first response for: reorder-1-0.rcode-5.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-5.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-5.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-5.example.net info: UDP server 1: dropping response to reorder-1-0.rcode-5.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-5.example.net/1/28 info: sending first response for: reorder-1-0.rcode-5.example.net info: sending non-reordered second response for: reorder-1-0.rcode-5.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-1-0.rcode-5.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-5.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-5.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-5.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-5.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-5.example.net info: sending first response for: reorder-1-1.rcode-5.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-5.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-5.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-5.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-5.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-5.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-5.example.net info: sending first response for: reorder-1-1.rcode-5.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-5.example.net/1/28 error: 2 test failures exit-status=1 --------------58C6AA9843532EACBC952787 Content-Type: text/x-log; charset=UTF-8; name="tst-resolv-txnid-collision_20201014_174533_4318.log" Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename="tst-resolv-txnid-collision_20201014_174533_4318.log" info: old timeout value: 5 info: old retry attempt value: 2 info: old _res.options: 0x2c1 info: old _res.nscount value: 1 info: old _res.ndots value: 1 info: new timeout value: 1 info: new retry attempt value: 4 info: new _res.options: 0x2c1 info: new _res.nscount value: 3 info: new _res.ndots value: 1 info: server 0: 127.0.0.1/43693 info: server 1: 127.0.0.1/44748 info: server 2: 127.0.0.1/57871 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/28 info: sending first response for: reorder-0-0.rcode-0.example.net info: sending non-reordered second response for: reorder-0-0.rcode-0.example.net info: UDP server 0: sending response: 49 bytes, RCODE 0 (for reorder-0-0.rcode-0.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-0.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-0.example.net/1/28 info: sending first response for: reorder-0-0.rcode-0.example.net info: sending non-reordered second response for: reorder-0-0.rcode-0.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-0.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/28 info: sending first response for: reorder-0-1.rcode-0.example.net info: sending non-reordered second response for: reorder-0-1.rcode-0.example.net info: UDP server 0: sending response: 49 bytes, RCODE 0 (for reorder-0-1.rcode-0.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-0.example.net info: sending first response for: reorder-0-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-0.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-0.example.net info: sending first response for: reorder-1-0.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-0.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-0.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-0.example.net/1/28 info: sending first response for: reorder-1-0.rcode-0.example.net info: sending non-reordered second response for: reorder-1-0.rcode-0.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-1-0.rcode-0.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-0.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-0.example.net info: sending first response for: reorder-1-1.rcode-0.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-0.example.net/1/28 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-0.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-1-1.rcode-0.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-0.example.net info: sending first response for: reorder-1-1.rcode-0.example.net info: UDP server 1: dropping response to reorder-1-1.rcode-0.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-1.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-1.example.net/1/28 info: sending first response for: reorder-0-0.rcode-1.example.net info: sending non-reordered second response for: reorder-0-0.rcode-1.example.net info: UDP server 0: sending response: 49 bytes, RCODE 1 (for reorder-0-0.rcode-1.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-1.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-1.example.net/1/28 info: sending first response for: reorder-0-1.rcode-1.example.net info: sending non-reordered second response for: reorder-0-1.rcode-1.example.net info: UDP server 0: sending response: 49 bytes, RCODE 1 (for reorder-0-1.rcode-1.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-1.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-1.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-1.example.net info: sending first response for: reorder-1-0.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-1.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-1.example.net/1/1 info: buffering first query for: reorder-1-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-1.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-1.rcode-1.example.net/1/28 info: sending reordered second response for: reorder-1-1.rcode-1.example.net info: sending first response for: reorder-1-1.rcode-1.example.net info: UDP server 0: dropping response to reorder-1-1.rcode-1.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-0-0.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/28 info: sending first response for: reorder-0-0.rcode-2.example.net info: sending non-reordered second response for: reorder-0-0.rcode-2.example.net info: UDP server 0: sending response: 49 bytes, RCODE 2 (for reorder-0-0.rcode-2.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-0.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-0.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-0.rcode-2.example.net/1/28 info: sending first response for: reorder-0-0.rcode-2.example.net info: sending non-reordered second response for: reorder-0-0.rcode-2.example.net info: UDP server 1: sending response: 77 bytes, RCODE 0 (for reorder-0-0.rcode-2.example.net/1/28) info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-2.example.net info: UDP server 0: dropping response to reorder-0-1.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/28 info: sending first response for: reorder-0-1.rcode-2.example.net info: sending non-reordered second response for: reorder-0-1.rcode-2.example.net info: UDP server 0: sending response: 49 bytes, RCODE 2 (for reorder-0-1.rcode-2.example.net/1/28) info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/1 info: buffering first query for: reorder-0-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-2.example.net/1/1 info: UDP server 1: incoming query: 49 bytes, reorder-0-1.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-0-1.rcode-2.example.net info: sending first response for: reorder-0-1.rcode-2.example.net info: UDP server 1: dropping response to reorder-0-1.rcode-2.example.net/1/28 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/1 info: buffering first query for: reorder-1-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-2.example.net/1/1 info: UDP server 0: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/28 info: sending reordered second response for: reorder-1-0.rcode-2.example.net info: sending first response for: reorder-1-0.rcode-2.example.net info: UDP server 1: incoming query: 49 bytes, reorder-1-0.rcode-2.example.net/1/1 tst-resolv-txnid-collision.c:166: numeric comparison failure left: 1 (0x1); from: ctx->server_index right: 0 (0x0); from: previous_server_index error: tst-resolv-txnid-collision.c:167: not true: previous_qtype != qtype info: sending first response for: reorder-1-0.rcode-2.example.net info: UDP server 0: dropping response to reorder-1-0.rcode-2.example.net/1/28 info: sending non-reordered second response for: reorder-1-0.rcode-2.example.net exit-status=139 --------------58C6AA9843532EACBC952787--