2025-11-05 03:25.34: New job: test mirage/arp https://github.com/mirage/arp.git#refs/pull/33/head (461301e3d7e2735c23519312272cb69952bce54f) (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 461301e3 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 60c74a29f5d280ca9330c57b890e2e8c0fbc444c || git fetch origin master) && git reset -q --hard 60c74a29f5d280ca9330c57b890e2e8c0fbc444c && 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.5.0 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 lru.0.3.1 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 psq.0.2.1 re.1.14.0 rresult.0.7.0 seq.base 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-11-05 03:25.34: Using cache hint "mirage/arp-freebsd-14.3-ocaml-4.14-freebsd-14.3-4.14_opam-2.4-28f10c67b9b49ec094a2e57382c1ba38" 2025-11-05 03:25.34: 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 60c74a29f5d280ca9330c57b890e2e8c0fbc444c || git fetch origin master) && git reset -q --hard 60c74a29f5d280ca9330c57b890e2e8c0fbc444c && 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.5.0 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 lru.0.3.1 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 psq.0.2.1 re.1.14.0 rresult.0.7.0 seq.base 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-11-05 03:25.34: Waiting for resource in pool OCluster 2025-11-05 03:25.34: Waiting for worker… 2025-11-05 03:25.34: Got resource from pool OCluster Building on rosemary All commits already cached HEAD is now at 461301e debug (from freebsd-14.3-ocaml-4.14) 2025-11-05 03:24.44 ---> 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-11-05 03:24.44 ---> 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-11-05 03:24.44 ---> 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-11-05 03:24.45 ---> using "199d8be57ed603ac6837a6d6cc7e578c2f630e2b79e2520a8892deff98662ca8" from cache /src: (workdir /src) /src: (run (shell "sudo chown opam /src")) 2025-11-05 03:24.45 ---> 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 60c74a29f5d280ca9330c57b890e2e8c0fbc444c || git fetch origin master) && git reset -q --hard 60c74a29f5d280ca9330c57b890e2e8c0fbc444c && git log --no-decorate -n1 --oneline && opam update -u")) 60c74a29f5 Merge pull request #28709 from hannesm/release-domain-name-v0.5.0 <><> 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-11-05 03:24.45 ---> using "62c99e715ba545cb5825c649a44cb5d4396ff5f52e9cfe3078d7f03b7a8616f4" from cache /src: (copy (src arp.opam) (dst ./)) 2025-11-05 03:24.45 ---> using "ddfa242a9e4398190dccf9c49ffb94e7a251011e146e2236cbf66b8a924bad69" 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-11-05 03:24.46 ---> using "7f3a0705f3f704395b79c634f1d5ed4a222b8aff7550671bfed10d266e9d59a4" from cache /src: (run (network host) (shell "echo '(lang dune 3.0)' > './dune-project'")) 2025-11-05 03:24.46 ---> using "e6230238dee1b83d7f05d4185837285472bf07ef7e285e606f53d62129b812f1" 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.5.0 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 lru.0.3.1 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 psq.0.2.1 re.1.14.0 rresult.0.7.0 seq.base 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-11-05 03:24.46 ---> using "c1ec8971c796136815d0d8d6a489df2994c9e171e45cb70356390c00c70eab64" 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 34 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.5.0 ∗ 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 ∗ lru 0.3.1 ∗ 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 ∗ psq 0.2.1 ∗ re 1.14.0 ∗ rresult 0.7.0 ∗ seq base ∗ 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.5.0 (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 lru.0.3.1 (cached) ⬇ retrieved mirage-net.4.0.0 (cached) ⬇ retrieved mirage-sleep.4.1.0 (cached) ⬇ retrieved mirage-vnetif.0.6.2 (cached) ⬇ retrieved lwt.5.9.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 re.1.14.0 (cached) ⬇ retrieved psq.0.2.1 (cached) ⬇ retrieved seq.base (cached) ∗ installed seq.base ⬇ retrieved rresult.0.7.0 (cached) ⬇ retrieved stdlib-shims.0.3.0 (cached) ⬇ retrieved topkg.1.1.0 (cached) ⬇ retrieved uutf.1.0.4 (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 psq.0.2.1 ∗ installed ocaml-syntax-shims.1.0.0 ∗ installed macaddr.5.6.1 ∗ installed domain-name.0.5.0 ∗ installed cstruct.6.2.0 ∗ installed cppo.1.8.0 ∗ installed lru.0.3.1 ∗ 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-11-05 03:24.46 ---> using "597d0053311daf604533347699a201128a86b5282e51dfa8608a3d4b856942fd" from cache /src: (copy (src .) (dst /src)) 2025-11-05 03:24.47 ---> saved as "5e4d6f335582d2557f4d38ebb2970a2ce053c75313ad34f5b80f9379611495c5" /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 `OYZ9Y7WZ'. [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. [OK] Handler 32 stale to dynamic on same MAC reply. [OK] Handler 33 stale stays stale on different MAC reply. [OK] Handler 34 probing to dynamic on same MAC reply. [OK] Handler 35 probing to stale on different MAC reply. Full test results in `/src/_build/default/test/_build/_tests/ARP tests'. Test Successful in 0.087s. 43 tests run. (cd _build/default/test/mirage && ./tests.exe) Testing `arp'. This run has ID `9DI9EU98'. [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 20.723s. 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 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.10 (1 left) tests.exe: [INFO] ARP timeout after 2 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.10 (02:50:00:00:00:01) [entries_expire] Set up listener with IP 192.168.3.10, MACs: listen=02:50:00:00:00:01 speak=02:50:00:00:00:02 tests.exe: [INFO] entries_expire: Set up listener with IP 192.168.3.10, MACs: listen=02:50:00:00:00:01 speak=02:50:00:00:00:02 tests.exe: [DEBUG] starting arp listener for 02:50:00:00:00:01 tests.exe: [INFO] entries_expire: Establishing cache entry for 192.168.3.1 tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending tests.exe: [DEBUG] entries_expire: Sending ARP reply 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: [INFO] entries_expire: Query succeeded, got expected MAC 02:50:00:00:00:02 tests.exe: [DEBUG] entries_expire: ARP reply sent [entries_expire] Cache entry established successfully tests.exe: [INFO] entries_expire: Cache entry established. ARP cache state: mac 02:50:00:00:00:01 ip 192.168.3.10 entries 2 timeout 800 retries 2 192.168.3.1 at 02:50:00:00:00:02 (timeout in 40) 192.168.3.10 at 02:50:00:00:00:01 (static advertising) tests.exe: [INFO] entries_expire: Sleeping 20s to ensure entry is completely removed from cache [entries_expire] Sleeping 20s to ensure entry is completely removed 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) [entries_expire] After 20s sleep, checking cache state tests.exe: [INFO] entries_expire: After 20s sleep. ARP cache state: mac 02:50:00:00:00:01 ip 192.168.3.10 entries 1 timeout 800 retries 2 192.168.3.10 at 02:50:00:00:00:01 (static advertising) tests.exe: [INFO] entries_expire: Checking that entry is not in cache [entries_expire] Checking that entry is not in cache (should generate ARP request) tests.exe: [DEBUG] sending ARP request for 192.168.3.1 --> Pending ASSERT parsed packet comparison [entries_expire] Test completed successfully! tests.exe: [INFO] entries_expire: Test completed successfully 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 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 2 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 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 2 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 2 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 2 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 2 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 (2 left) tests.exe: [DEBUG] resending ARP request for 192.168.3.1 (1 left) tests.exe: [INFO] ARP timeout after 2 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 (2 left) ASSERT parsed packet comparison 2025-11-05 03:25.09 ---> saved as "19d215320fc884639e9372b759c4a22708d0a28863731339e52ac98fcbbc5ca5" Job succeeded 2025-11-05 03:26.00: Job succeeded