dhcpcd-discuss

Home Other Lists Thread Index Date Index Search
Thread Prev Thread Next Date Prev Date Next Thread Index Date Index

Re: Dhcpcd 8.x.x cannot pass IPv4LL test in Bonjour Conformance Test, instead with 6.x.x and 7.x.x it was ok.

Roy Marples

Tue Oct 01 18:38:51 2019
Hi Stefano

On 30/09/2019 15:17, Stefano Cappa wrote:
I'm trying dhcpcd 8.0.6 on my device to check if it can pass Bonjour Conformance Test and to decide if upgrade or not. However _8.x.x causes lot of issue when running IPV4 Link Local test_/./ With 6.x.x and 7.x.x the situation was ok.
This is the log (very verbose with DD options) of Bonjour Conformance Test 1.5.0 with dhcpcd 8.0.6 executed with*"dhcpcd -q -i eth0"* 

    /
    /
    /sudo ../BonjourConformanceTest -I en0 -DD -F results.txt/
    /
    /
    /
    /
    /********************** IMPORTANT NOTICE **********************/
    /Before proceeding any further, ensure that your computer's Ethernet/
    /port is connected to an isolated Ethernet hub with no other devices/
    /connected to it. Running this test on an operational network will/
    /almost always be disruptive to other devices on that network./
    /
    /
    /Use of this Apple software is subject to the Software License/
    /Agreement ("License") that accompanies it.  Please read the/
    /License carefully before using this software.  If you agree to/
    /be bound by the terms of the License, enter "yes" to proceed/
    /with the test.  Otherwise, enter "no" and the test will abort./
    /
    /
    />yes/
    /Enter the IPv4 address of the access point:/
    />169.254.134.78/
    /BaseStation IPv4: 169.254.134.78/
    /DEBUG_2 16:04:22.502464: Basestation IP Address: 169.254.134.78/
    /DEBUG_2 16:04:22.503601: Sending ARP for 169.254.134.78/
    /DEBUG_2 16:04:22.503712: Device name: en0/
    /DEBUG_2 16:04:22.514831: source Mac:38:c9:86:2f:f1:7a, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.134.78/
    /DEBUG_2 16:04:22.514868: source Mac:98:1:a7:e4:fb:bb, source
    IP:169.254.134.78, target Mac:38:c9:86:2f:f1:7a, target IP:0.0.0.0/
    /BaseStation IPv6: fe80::9a01:a7ff:fee4:fbbb/
    /Enter the MAC address of the access point:/
    />98:01:A7:E4:FB:BB/
    /BaseStation MAC: 98:01:A7:E4:FB:BB/
    /
    /
    /Bonjour Conformance Test Version  1.5.0(1500)/
    /Starting Test: Mon Sep 30 16:04:21 2019/
    /*** Please Power On Device ***/
    /
    /
    /*** Beginning Link-Local Address Allocation Test ***/
    /START (INITIAL PROBING)/
    /DEBUG_2 16:04:28.676101: Device name: en0/
    /DEBUG_2 16:04:29.782811: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:04:32.087670: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:04:33.089771: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:04:34.832729: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:04:35.831368: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:04:59.510541: source Mac:XX:XX:XX:10:0:1c, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.218.98/
    /NOTICE  16:04:59.510691: Received probe for 169.254.218.98
    [/XX:XX:XX/:10:00:1c]./
    /PASSED (INITIAL PROBING)/
    /
    /
    /START (PROBING)/
    /NOTICE  16:04:59.534719: Denying probe for              169.254.218.98/
    /DEBUG_2 16:04:59.535222: Denying ARP for 169.254.218.98/
    /DEBUG_2 16:04:59.535390: Device name: en0/
    /DEBUG_2 16:04:59.545571: source Mac:38:c9:86:2f:f1:7a, source
    IP:169.254.218.98, target Mac:/XX:XX:XX/:10:0:1c, target IP:0.0.0.0/
    /DEBUG_2 16:05:00.538611: source Mac:/XX:XX:XX/:10:0:1c, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.218.98/
    /DEBUG_2 16:05:00.538717: new_device_mac: /XX:XX:XX/:10:0:1c,
    ts->device_mac: /XX:XX:XX/:10:0:1c/
    /DEBUG_2 16:05:00.538772: last: 169.254.218.98, now: 169.254.218.98/
    /ERROR   16:05:00.538785: Device did not probe for new address after
    it's chosen address was denied./
    /NOTICE  16:05:00.539058: Denying probe for              169.254.218.98/
    /DEBUG_2 16:05:00.539220: Denying ARP for 169.254.218.98/
    /DEBUG_2 16:05:00.539325: Device name: en0/
    /DEBUG_2 16:05:00.550465: source Mac:38:c9:86:2f:f1:7a, source
    IP:169.254.218.98, target Mac:/XX:XX:XX/:10:0:1c, target IP:0.0.0.0/
    /DEBUG_2 16:05:00.805079: source Mac:38:c9:86:2f:f1:7a, source
    IP:192.168.86.66, target Mac:0:0:0:0:0:0, target IP:192.168.86.1/
    /DEBUG_2 16:05:01.552665: source Mac:/XX:XX:XX/:10:0:1c, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.218.98/
    /DEBUG_2 16:05:01.552768: new_device_mac: /XX:XX:XX/10:0:1c,
    ts->device_mac: /XX:XX:XX/:10:0:1c/
    /DEBUG_2 16:05:01.552780: last: 169.254.218.98, now: 169.254.218.98/
    /ERROR   16:05:01.552789: Device did not probe for new address after
    it's chosen address was denied./
    /NOTICE  16:05:01.552799: Denying probe for              169.254.218.98/
    /DEBUG_2 16:05:01.552892: Denying ARP for 169.254.218.98/
    /DEBUG_2 16:05:01.552945: Device name: en0/
    /DEBUG_2 16:05:01.563191: source Mac:38:c9:86:2f:f1:7a, source
    IP:169.254.218.98, target Mac:/XX:XX:XX/:10:0:1c, target IP:0.0.0.0/
    /DEBUG_2 16:05:02.566619: source Mac:/XX:XX:XX/:10:0:1c, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.218.98/
    /DEBUG_2 16:05:02.566721: new_device_mac: /XX:XX:XX/:10:0:1c,
    ts->device_mac: /XX:XX:XX/:10:0:1c/
    /DEBUG_2 16:05:02.566734: last: 169.254.218.98, now: 169.254.218.98/
    /ERROR   16:05:02.566743: Device did not probe for new address after
    it's chosen address was denied./
    /NOTICE  16:05:02.566753: Denying probe for              169.254.218.98/
    /DEBUG_2 16:05:02.566847: Denying ARP for 169.254.218.98/
    /DEBUG_2 16:05:02.566902: Device name: en0/
    /DEBUG_2 16:05:02.577999: source Mac:38:c9:86:2f:f1:7a, source
    IP:169.254.218.98, target Mac:/XX:XX:XX/:10:0:1c, target IP:0.0.0.0/
    /DEBUG_2 16:05:03.578626: source Mac:/XX:XX:XX/:10:0:1c, source
    IP:0.0.0.0, target Mac:0:0:0:0:0:0, target IP:169.254.218.98/
    /DEBUG_2 16:05:03.578886: new_device_mac: /XX:XX:XX/:10:0:1c,
    ts->device_mac: /XX:XX:XX/:10:0:1c/
    /DEBUG_2 16:05:03.578946: last: 169.254.218.98, now: 169.254.218.98/
    /ERROR   16:05:03.578999: Device did not probe for new address after
    it's chosen address was denied./
    /FAILED (PROBING)/
    /
    /
    /ERROR   16:05:03.579732: ABORTED (PROBING COMPLETION)/
    /ERROR   16:05:03.580412: ABORTED (SUBSEQUENT CONFLICTS)/
    /ERROR   16:05:03.580604: ABORTED (CABLE CHANGE HANDLING)/
    /ERROR   16:05:03.580639: Unable to recover from failures.  Aborting
    link-local address test./
    /ERROR   16:05:03.580675: Could not recover from failures.  Aborting
    test./
    /*** Skipping Multicast DNS Test ***/
    /SKIP (INITIAL PROBING) because of a failed unrecoverable subtest/
    /SKIP (PROBING) because of a failed unrecoverable subtest/
    /SKIP (WINNING SIMULTANEOUS PROBES) because of a failed
    unrecoverable subtest/
    /SKIP (SRV PROBING/ANNOUNCEMENTS BASIC) because of a failed
    unrecoverable subtest/
    /SKIP (SRV PROBING/ANNOUNCEMENTS) because of a failed unrecoverable
    subtest/
    /SKIP (SUBSEQUENT CONFLICT) because of a failed unrecoverable subtest/
    /SKIP (SIMPLE REPLY VERIFICATION) because of a failed unrecoverable
    subtest/
    /SKIP (SHARED REPLY TIMING) because of a failed unrecoverable subtest/
    /SKIP (DUPLICATE SUPPRESSION) because of a failed unrecoverable subtest/
    /SKIP (DISTRIBUTED DUPLICATE SUPPRESSION) because of a failed
    unrecoverable subtest/
    /SKIP (MULTIPLE QUESTIONS - SHARED REPLY TIMING) because of a failed
    unrecoverable subtest/
    /SKIP (MULTIPLE QUESTIONS - DUPLICATE SUPPRESSION) because of a
    failed unrecoverable subtest/
    /SKIP (MULTIPLE QUESTIONS - DISTRIBUTED DUPLICATE SUPPRESSION)
    because of a failed unrecoverable subtest/
    /SKIP (REPLY AGGREGATION) because of a failed unrecoverable subtest/
    /SKIP (MANUAL NAME CHANGE) because of a failed unrecoverable subtest/
    /SKIP (HOT-PLUGGING) because of a failed unrecoverable subtest/
    /
    /
    /
    /
    /*** Skipping Network Interoperability Test ***/
    /SKIP (UNICAST INTEROPERABILITY) because of a failed unrecoverable
    subtest/
    /SKIP (CHATTINESS) because of a failed unrecoverable subtest/
    /SKIP (mDNS IP TTL CHECK) because of a failed unrecoverable subtest/
    /
    /
    /
    /
    /
    /
    /Write test results to file "results.txt"?  Overwrites any existing
    logfile.  (yes or no)/
    />WARNING 16:05:09.148885: Received address record query (name:
    easykon1-staging.local., address: 169.254.218.98) before device
    successfully acquired a link-local IP address in subtest (null)./
    /yes/
    /
    /
    /
    /

Why is it happening?


I've addressed this in a sequence of patches on my git master branch.
Can you test this please and let me know if it now works for you?

Thanks

Roy
Follow-Ups:
Re: Dhcpcd 8.x.x cannot pass IPv4LL test in Bonjour Conformance Test, instead with 6.x.x and 7.x.x it was ok.Stefano Cappa
References:
Dhcpcd 8.x.x cannot pass IPv4LL test in Bonjour Conformance Test, instead with 6.x.x and 7.x.x it was ok.Stefano Cappa
Archive administrator: postmaster@marples.name