2025-10-13 03:51.32: New job: test mirage/arp https://github.com/mirage/arp.git#refs/pull/33/head (52491894f1874fffa98f79049641991f4dcbb81d) (freebsd-x86_64:freebsd-14.3-4.14_opam-2.4) Base: freebsd-14.3-ocaml-4.14 Opam project build To reproduce locally: git clone --recursive "https://github.com/mirage/arp.git" && cd "arp" && git fetch origin "refs/pull/33/head" && git reset --hard 52491894 cat > Dockerfile <<'END-OF-DOCKERFILE' FROM freebsd-14.3-ocaml-4.14 # freebsd-14.3-4.14_opam-2.4 USER 1000:1000 ENV CLICOLOR_FORCE="1" ENV OPAMCOLOR="always" WORKDIR /src RUN sudo ln -f /usr/local/bin/opam-2.4 /usr/local/bin/opam RUN opam init --reinit -ni RUN uname -rs && opam exec -- ocaml -version && opam --version WORKDIR /src RUN sudo chown opam /src RUN cd ~/opam-repository && (git cat-file -e 241aae26db44294142794da0ce695e8beac418df || git fetch origin master) && git reset -q --hard 241aae26db44294142794da0ce695e8beac418df && git log --no-decorate -n1 --oneline && opam update -u COPY --chown=1000:1000 arp.opam ./ RUN opam pin add -yn arp.dev './' RUN echo '(lang dune 3.0)' > './dune-project' ENV DEPS="alcotest.1.9.1 astring.0.8.5 base-bigarray.base base-bytes.base base-threads.base base-unix.base bos.0.2.1 cmdliner.2.0.0 cppo.1.8.0 csexp.1.5.2 cstruct.6.2.0 domain-name.0.4.1 dune.3.20.2 dune-configurator.3.20.2 duration.0.2.1 ethernet.3.2.0 fmt.0.11.0 fpath.0.7.3 ipaddr.5.6.1 logs.0.9.0 lwt.5.9.2 macaddr.5.6.1 mirage-net.4.0.0 mirage-sleep.4.1.0 mirage-vnetif.0.6.2 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-config.2 ocaml-options-vanilla.1 ocaml-syntax-shims.1.0.0 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 re.1.14.0 rresult.0.7.0 stdlib-shims.0.3.0 topkg.1.1.0 uutf.1.0.4" ENV CI="true" ENV OCAMLCI="true" RUN opam update --depexts && opam install --cli=2.4 --depext-only -y arp.dev $DEPS RUN opam install $DEPS COPY --chown=1000:1000 . /src RUN opam exec -- dune build @install @check @runtest && rm -rf _build END-OF-DOCKERFILE docker build . END-REPRO-BLOCK 2025-10-13 03:51.32: Using cache hint "mirage/arp-freebsd-14.3-ocaml-4.14-freebsd-14.3-4.14_opam-2.4-71b6fcd9106ec9296d482265b281f660" 2025-10-13 03:51.32: Using OBuilder spec: ((from freebsd-14.3-ocaml-4.14) (comment freebsd-14.3-4.14_opam-2.4) (user (uid 1000) (gid 1000)) (env CLICOLOR_FORCE 1) (env OPAMCOLOR always) (workdir /src) (run (shell "sudo ln -f /usr/local/bin/opam-2.4 /usr/local/bin/opam")) (run (shell "opam init --reinit -ni")) (run (shell "uname -rs && opam exec -- ocaml -version && opam --version")) (workdir /src) (run (shell "sudo chown opam /src")) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "cd ~/opam-repository && (git cat-file -e 241aae26db44294142794da0ce695e8beac418df || git fetch origin master) && git reset -q --hard 241aae26db44294142794da0ce695e8beac418df && git log --no-decorate -n1 --oneline && opam update -u")) (copy (src arp.opam) (dst ./)) (run (network host) (shell "opam pin add -yn arp.dev './'")) (run (network host) (shell "echo '(lang dune 3.0)' > './dune-project'")) (env DEPS "alcotest.1.9.1 astring.0.8.5 base-bigarray.base base-bytes.base base-threads.base base-unix.base bos.0.2.1 cmdliner.2.0.0 cppo.1.8.0 csexp.1.5.2 cstruct.6.2.0 domain-name.0.4.1 dune.3.20.2 dune-configurator.3.20.2 duration.0.2.1 ethernet.3.2.0 fmt.0.11.0 fpath.0.7.3 ipaddr.5.6.1 logs.0.9.0 lwt.5.9.2 macaddr.5.6.1 mirage-net.4.0.0 mirage-sleep.4.1.0 mirage-vnetif.0.6.2 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-config.2 ocaml-options-vanilla.1 ocaml-syntax-shims.1.0.0 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 re.1.14.0 rresult.0.7.0 stdlib-shims.0.3.0 topkg.1.1.0 uutf.1.0.4") (env CI true) (env OCAMLCI true) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam update --depexts && opam install --cli=2.4 --depext-only -y arp.dev $DEPS")) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam install $DEPS")) (copy (src .) (dst /src)) (run (shell "opam exec -- dune build @install @check @runtest && rm -rf _build")) ) 2025-10-13 03:51.32: Waiting for resource in pool OCluster 2025-10-13 03:51.32: Waiting for worker… 2025-10-13 03:51.32: Got resource from pool OCluster Building on rosemary All commits already cached HEAD is now at 5249189 reenable entries_expire test for macOS and windows (from freebsd-14.3-ocaml-4.14) 2025-10-13 03:49.56 ---> using "7e5247e9853d1c2fb95ef9ffdfdb895ef70516f48155f08a9b10db936cb8c6c6" from cache /: (comment freebsd-14.3-4.14_opam-2.4) /: (user (uid 1000) (gid 1000)) /: (env CLICOLOR_FORCE 1) /: (env OPAMCOLOR always) /: (workdir /src) /src: (run (shell "sudo ln -f /usr/local/bin/opam-2.4 /usr/local/bin/opam")) 2025-10-13 03:49.57 ---> using "f8f2ae50f983ba53790a433c81d8570b8becc44c74ab2707cf43746ed1b463d1" from cache /src: (run (shell "opam init --reinit -ni")) No configuration file found, using built-in defaults. Checking for available remotes: rsync and local, git. - you won't be able to use mercurial repositories unless you install the hg command on your system. - you won't be able to use darcs repositories unless you install the darcs command on your system. This version of opam requires an update to the layout of /home/opam/.opam from version 2.1 to version 2.2, which can't be reverted. You may want to back it up before going further. Continue? [Y/n] y [NOTE] The 'jobs' option was reset, its value was 1 and its new value will vary according to the current number of cores on your machine. You can restore the fixed value using: opam option jobs=1 --global Format upgrade done. <><> Updating repositories ><><><><><><><><><><><><><><><><><><><><><><><><><><> [default] no changes from git+file:///home/opam/opam-repository 2025-10-13 03:49.57 ---> using "a95cd97103c96a777086d9508c45964fbffa4285a5c5e6703b704acabfb6945b" from cache /src: (run (shell "uname -rs && opam exec -- ocaml -version && opam --version")) FreeBSD 14.3-RELEASE-p3 The OCaml toplevel, version 4.14.2 2.4.1 2025-10-13 03:49.57 ---> using "199d8be57ed603ac6837a6d6cc7e578c2f630e2b79e2520a8892deff98662ca8" from cache /src: (workdir /src) /src: (run (shell "sudo chown opam /src")) 2025-10-13 03:49.57 ---> using "c9d8693a3c313f3750cea43d98891a3f5490d8d3ab8357236d5eb49fea038272" from cache /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "cd ~/opam-repository && (git cat-file -e 241aae26db44294142794da0ce695e8beac418df || git fetch origin master) && git reset -q --hard 241aae26db44294142794da0ce695e8beac418df && git log --no-decorate -n1 --oneline && opam update -u")) 241aae26db Merge pull request #28629 from dinosaure/release-alcotest-1.9.1 <><> Updating package repositories ><><><><><><><><><><><><><><><><><><><><><><> [default] synchronised from git+file:///home/opam/opam-repository Everything as up-to-date as possible The following packages are not being upgraded because the new versions conflict with other installed packages: - ocaml.5.3.0 - ocaml-config.3 However, you may "opam upgrade" these packages explicitly at these versions (e.g. "opam upgrade ocaml.5.3.0"), which will ask permission to downgrade or uninstall the conflicting packages. Nothing to do. 2025-10-13 03:49.57 ---> using "de0bd8698b2e3bc0711b3652af2879eb45db39585a1fe11d06fcfadb2457ddfe" from cache /src: (copy (src arp.opam) (dst ./)) 2025-10-13 03:49.57 ---> using "5a6a828d1d5b87637b8ac06e7631c8804537a5679d871ac6ac08374f2160c73d" from cache /src: (run (network host) (shell "opam pin add -yn arp.dev './'")) [arp.dev] synchronised (file:///src) arp is now pinned to file:///src (version dev) 2025-10-13 03:49.58 ---> using "d6bee24dbc3b54b1835ad2e99971f228f09cce723c065cefd286ab3914212bfe" from cache /src: (run (network host) (shell "echo '(lang dune 3.0)' > './dune-project'")) 2025-10-13 03:49.58 ---> using "b2f126013c098e2063407515390427ab7696e756319002ee8b4f8ec0496b44a1" from cache /src: (env DEPS "alcotest.1.9.1 astring.0.8.5 base-bigarray.base base-bytes.base base-threads.base base-unix.base bos.0.2.1 cmdliner.2.0.0 cppo.1.8.0 csexp.1.5.2 cstruct.6.2.0 domain-name.0.4.1 dune.3.20.2 dune-configurator.3.20.2 duration.0.2.1 ethernet.3.2.0 fmt.0.11.0 fpath.0.7.3 ipaddr.5.6.1 logs.0.9.0 lwt.5.9.2 macaddr.5.6.1 mirage-net.4.0.0 mirage-sleep.4.1.0 mirage-vnetif.0.6.2 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-config.2 ocaml-options-vanilla.1 ocaml-syntax-shims.1.0.0 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 re.1.14.0 rresult.0.7.0 stdlib-shims.0.3.0 topkg.1.1.0 uutf.1.0.4") /src: (env CI true) /src: (env OCAMLCI true) /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam update --depexts && opam install --cli=2.4 --depext-only -y arp.dev $DEPS")) [WARNING] Unknown update command for bsd, skipping system update <><> Synchronising pinned packages ><><><><><><><><><><><><><><><><><><><><><><> [arp.dev] synchronised (file:///src) [NOTE] Package ocaml-options-vanilla is already installed (current version is 1). [NOTE] Package ocaml-config is already installed (current version is 2). [NOTE] Package ocaml-base-compiler is already installed (current version is 4.14.2). [NOTE] Package ocaml is already installed (current version is 4.14.2). [NOTE] Package base-unix is already installed (current version is base). [NOTE] Package base-threads is already installed (current version is base). [NOTE] Package base-bigarray is already installed (current version is base). 2025-10-13 03:49.58 ---> using "4b71e2255f3ccf406430bd061328247eda05217019711ede03f4c07055279374" from cache /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam install $DEPS")) [NOTE] Package ocaml-options-vanilla is already installed (current version is 1). [NOTE] Package ocaml-config is already installed (current version is 2). [NOTE] Package ocaml-base-compiler is already installed (current version is 4.14.2). [NOTE] Package ocaml is already installed (current version is 4.14.2). [NOTE] Package base-unix is already installed (current version is base). [NOTE] Package base-threads is already installed (current version is base). [NOTE] Package base-bigarray is already installed (current version is base). The following actions will be performed: === install 31 packages ∗ alcotest 1.9.1 ∗ astring 0.8.5 ∗ base-bytes base ∗ bos 0.2.1 ∗ cmdliner 2.0.0 ∗ cppo 1.8.0 ∗ csexp 1.5.2 ∗ cstruct 6.2.0 ∗ domain-name 0.4.1 ∗ dune 3.20.2 ∗ dune-configurator 3.20.2 ∗ duration 0.2.1 ∗ ethernet 3.2.0 ∗ fmt 0.11.0 ∗ fpath 0.7.3 ∗ ipaddr 5.6.1 ∗ logs 0.9.0 ∗ lwt 5.9.2 ∗ macaddr 5.6.1 ∗ mirage-net 4.0.0 ∗ mirage-sleep 4.1.0 ∗ mirage-vnetif 0.6.2 ∗ ocaml-syntax-shims 1.0.0 ∗ ocamlbuild 0.16.1 ∗ ocamlfind 1.9.8 ∗ ocplib-endian 1.2 ∗ re 1.14.0 ∗ rresult 0.7.0 ∗ stdlib-shims 0.3.0 ∗ topkg 1.1.0 ∗ uutf 1.0.4 <><> Processing actions <><><><><><><><><><><><><><><><><><><><><><><><><><><><> ⬇ retrieved bos.0.2.1 (cached) ⬇ retrieved astring.0.8.5 (cached) ⬇ retrieved alcotest.1.9.1 (cached) ⬇ retrieved cppo.1.8.0 (cached) ⬇ retrieved csexp.1.5.2 (cached) ⬇ retrieved cmdliner.2.0.0 (cached) ⬇ retrieved domain-name.0.4.1 (cached) ⬇ retrieved cstruct.6.2.0 (cached) ⬇ retrieved duration.0.2.1 (cached) ⬇ retrieved ethernet.3.2.0 (cached) ⬇ retrieved fmt.0.11.0 (cached) ⬇ retrieved fpath.0.7.3 (cached) ⬇ retrieved ipaddr.5.6.1, macaddr.5.6.1 (cached) ⬇ retrieved logs.0.9.0 (cached) ⬇ retrieved mirage-net.4.0.0 (cached) ⬇ retrieved lwt.5.9.2 (cached) ⬇ retrieved mirage-sleep.4.1.0 (cached) ⬇ retrieved mirage-vnetif.0.6.2 (cached) ⬇ retrieved ocaml-syntax-shims.1.0.0 (cached) ⬇ retrieved ocamlfind.1.9.8 (cached) ⬇ retrieved ocplib-endian.1.2 (cached) ⬇ retrieved ocamlbuild.0.16.1 (cached) ⬇ retrieved rresult.0.7.0 (cached) ⬇ retrieved re.1.14.0 (cached) ⬇ retrieved stdlib-shims.0.3.0 (cached) ⬇ retrieved uutf.1.0.4 (cached) ⬇ retrieved topkg.1.1.0 (cached) ⬇ retrieved dune.3.20.2, dune-configurator.3.20.2 (cached) ∗ installed ocamlbuild.0.16.1 ∗ installed cmdliner.2.0.0 ∗ installed ocamlfind.1.9.8 ∗ installed base-bytes.base ∗ installed topkg.1.1.0 ∗ installed rresult.0.7.0 ∗ installed uutf.1.0.4 ∗ installed fmt.0.11.0 ∗ installed astring.0.8.5 ∗ installed fpath.0.7.3 ∗ installed dune.3.20.2 ∗ installed duration.0.2.1 ∗ installed csexp.1.5.2 ∗ installed stdlib-shims.0.3.0 ∗ installed re.1.14.0 ∗ installed ocaml-syntax-shims.1.0.0 ∗ installed macaddr.5.6.1 ∗ installed domain-name.0.4.1 ∗ installed cstruct.6.2.0 ∗ installed cppo.1.8.0 ∗ installed ocplib-endian.1.2 ∗ installed ipaddr.5.6.1 ∗ installed alcotest.1.9.1 ∗ installed dune-configurator.3.20.2 ∗ installed lwt.5.9.2 ∗ installed mirage-net.4.0.0 ∗ installed mirage-sleep.4.1.0 ∗ installed logs.0.9.0 ∗ installed mirage-vnetif.0.6.2 ∗ installed ethernet.3.2.0 ∗ installed bos.0.2.1 Done. 2025-10-13 03:49.59 ---> using "4b72c9c00b05165d3fc1316ecd7f483754b7d6b189eca788c8d01c03d9ad9493" from cache /src: (copy (src .) (dst /src)) 2025-10-13 03:49.59 ---> saved as "1980c7ed78efca5abb03bd4a502c44c9e1e7b19b9c7c9bc67dec6202f8cc0e3f" /src: (run (shell "opam exec -- dune build @install @check @runtest && rm -rf _build")) (cd _build/default/test && ./tests.exe) Testing `ARP tests'. This run has ID `EHJ5L9Z2'. [OK] Coder 0 valid arp decoding. [OK] Coder 1 unhandled arp decoding. [OK] Coder 2 short arp decoding. [OK] Coder 3 invalid operation decoding. [OK] Coder 4 decoding is inverse of encoding. [OK] Coder 5 encode_into works. [OK] Coder 6 encode_into fails with small bufs. [OK] Handler 0 create raises. [OK] Handler 1 basic tests. [OK] Handler 2 remove test. [OK] Handler 3 remove no test. [OK] Handler 4 alias test. [OK] Handler 5 alias remove test. [OK] Handler 6 static test. [OK] Handler 7 static alias test. [OK] Handler 8 more tests. [OK] Handler 9 handle good. [OK] Handler 10 handle generates req. [OK] Handler 11 handle generates req, next doesn't. [OK] Handler 12 alias wakes. [OK] Handler 13 static wakes. [OK] Handler 14 handle timeout. [OK] Handler 15 request send before timeout. [OK] Handler 16 multiple requests are send. [OK] Handler 17 multiple requests are send 2. [OK] Handler 18 handle reply. [OK] Handler 19 handle garp. [OK] Handler 20 answers broadcast request. [OK] Handler 21 answers unicast request. [OK] Handler 22 not answering random request. [OK] Handler 23 ignoring random. [OK] Handler 24 reply does not harm static entries. [OK] Handler 25 reply is in cache. [OK] Handler 26 dynamic entry can be queried. [OK] Handler 27 reply times out. [OK] Handler 28 dynamic entry overriden by same. [OK] Handler 29 dynamic entry overriden by other. [OK] Handler 30 dynamic entry is not advertised. [OK] Handler 31 reply wakes tasks. Full test results in `/src/_build/default/test/_build/_tests/ARP tests'. Test Successful in 0.083s. 39 tests run. (cd _build/default/test/mirage && ./tests.exe) Testing `arp'. This run has ID `TF79QTYI'. [OK] arp 0 conversions neither lose nor gain information. [OK] arp 1 nonsense requests are ignored. [OK] arp 2 requests are responded to. [OK] arp 3 entries expire. [OK] arp 4 irrelevant requests are ignored. [OK] arp 5 set_ip sets ip, sends GARP. [OK] arp 6 add_ip, get_ip and remove_ip as advertised. [OK] arp 7 GARPs are heard and not cached. [OK] arp 8 unsolicited unicast replies are heard and no... [OK] arp 9 solicited unicast replies resolve pending th... [OK] arp 10 entries are replaced with new information. [OK] arp 11 unreachable IPs time out. [OK] arp 12 queries are tried repeatedly before timing out. Test Successful in 6.759s. 13 tests run. ASSERT serialize/deserialize tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:03 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 3 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 65535 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 255 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 256 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 257 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [INFO] Failed to parse ARP frame ARP message has unsupported operation 0 tests.exe: [DEBUG] sending ARP request for 192.168.3.10 --> Pending ASSERT parsed packet comparison tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:02) tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.10 (02:50:00:00:00:01) tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:02 tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (5 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (4 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (3 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (1 left) tests.exe: [INFO] ARP timeout after 5 retries for 192.168.3.10 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] replying to ARP request for 192.168.3.1 from 192.168.3.10 (mac 02:50:00:00:00:01) ASSERT parsed packet comparison tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:02) tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.10 (02:50:00:00:00:01) tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:02 tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] replying to ARP request for 192.168.3.1 from 192.168.3.10 (mac 02:50:00:00:00:01) tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] ARP reply received for 192.168.3.1/02:50:00:00:00:02, adding cache entry ASSERT mac match tests.exe: [DEBUG] ARP entry 192.168.3.1 (mac 02:50:00:00:00:02) timed out --> Stale tests.exe: [DEBUG] removing stale ARP entry 192.168.3.1 (mac 02:50:00:00:00:02) tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending ASSERT parsed packet comparison tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.10 (02:50:00:00:00:02) tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] ignoring ARP request for 0.0.0.0 from 192.168.3.10 (mac 02:50:00:00:00:02) tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] ignoring ARP request for 255.255.255.255 from 192.168.3.10 (mac 02:50:00:00:00:02) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (5 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (4 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (3 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 5 retries for 192.168.3.1 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) ASSERT parsed packet comparison ASSERT garp emitted when setting ip ASSERT list of bound IPs on initialization tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.10 (02:50:00:00:00:01) ASSERT list of bound IPs after setting two IPs tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 ASSERT bound ips is an empty list on startup tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) ASSERT set ips with duplicate elements result in deduplication ASSERT ip list is empty after removing only ip ASSERT ip list is empty after removing from empty list tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) ASSERT first ip is the only member of the set of bound ips tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:01) ASSERT adding ips is idempotent tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:02) tests.exe: [DEBUG] ARP reply received for 192.168.3.1/02:50:00:00:00:02, adding cache entry ASSERT mismatch for expected query value tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (5 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (4 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (3 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 5 retries for 192.168.3.1 tests.exe: [WARNING] Timeout querying 192.168.3.1. Table contents: mac 02:50:00:00:00:01 ip 0.0.0.0 entries 0 timeout 800 retries 5 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] ARP reply received for 192.168.3.1/02:50:00:00:00:02, adding cache entry ASSERT mismatch for expected query value tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:03 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] arp listener started tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:02) tests.exe: [DEBUG] frame received for arpv4 tests.exe: [DEBUG] ARP reply received for 192.168.3.1/02:50:00:00:00:02, adding cache entry tests.exe: [DEBUG] Set IP for 02:50:00:00:00:02 to 192.168.3.1 tests.exe: [DEBUG] Listener table contents after IP set on claimant: mac 02:50:00:00:00:01 ip 0.0.0.0 entries 1 timeout 800 retries 5 192.168.3.1 at 02:50:00:00:00:02 (timeout in 40) ASSERT mismatch for expected query value tests.exe: [INFO] Sending gratuitous ARP for 192.168.3.1 (02:50:00:00:00:03) tests.exe: [DEBUG] frame received for arpv4 tests.exe: [WARNING] MAC address for 192.168.3.1 moved from 02:50:00:00:00:02 to 02:50:00:00:00:03, marked as stale tests.exe: [DEBUG] Set IP for 02:50:00:00:00:03 to 192.168.3.1 tests.exe: [DEBUG] Listener table contents after IP set on claimant: mac 02:50:00:00:00:01 ip 0.0.0.0 entries 1 timeout 800 retries 5 192.168.3.1 at 02:50:00:00:00:03 (stale, timeout in 800) tests.exe: [DEBUG] request for stale entry 192.168.3.1/02:50:00:00:00:03 --> Probing ASSERT mismatch for expected query value tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (5 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (4 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (3 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 5 retries for 192.168.3.1 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:01 tests.exe: [INFO] Connected Ethernet interface 02:50:00:00:00:02 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending ASSERT parsed packet comparison tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (5 left) ASSERT parsed packet comparison 2025-10-13 03:50.07 ---> saved as "98dfbc72a08ea58243b73b0f3875bde681856508ba977acae603a7d38d134950" Job succeeded 2025-10-13 03:51.44: Job succeeded