[INFO] cloning repository https://github.com/lc-at/atproxy
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/lc-at/atproxy" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flc-at%2Fatproxy", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flc-at%2Fatproxy'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 214742d5eeba093ae48a5367b15d615aa5507a9f
[INFO] testing lc-at/atproxy against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flc-at%2Fatproxy" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-5-tc2/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/lc-at/atproxy
[INFO] finished tweaking git repo https://github.com/lc-at/atproxy
[INFO] tweaked toml for git repo https://github.com/lc-at/atproxy written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/lc-at/atproxy on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/lc-at/atproxy already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60
[INFO] running `Command { std: "docker" "start" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling log v0.4.30
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling atproxy v0.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling memoffset v0.9.1
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 23.06s
[INFO] running `Command { std: "docker" "inspect" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling atproxy v0.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.23s
[INFO] running `Command { std: "docker" "inspect" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/atproxy-c7670266dffc3c42)
[INFO] [stdout] 
[INFO] [stdout] running 54 tests
[INFO] [stdout] test exclude::tests::cidr_boundary_0_matches_all_ipv4 ... ok
[INFO] [stdout] test exclude::tests::first_invalid_returns_error ... ok
[INFO] [stdout] test exclude::tests::mixed_v4_v6_list ... ok
[INFO] [stdout] test exclude::tests::parse_empty_strings_skipped ... ok
[INFO] [stdout] test exclude::tests::parse_invalid_cidr ... ok
[INFO] [stdout] test exclude::tests::parse_invalid_ipv4 ... ok
[INFO] [stdout] test exclude::tests::parse_invalid_ipv6 ... ok
[INFO] [stdout] test exclude::tests::parse_ipv4_cidr ... ok
[INFO] [stdout] test exclude::tests::parse_empty_list ... ok
[INFO] [stdout] test exclude::tests::cidr_boundary_32_matches_exact ... ok
[INFO] [stdout] test exclude::tests::cidr_normalizes_host_bits ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_64 ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_boundary_0_matches_all ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_boundary_128_matches_exact ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_non_byte_boundary ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_normalizes_host_bits ... ok
[INFO] [stdout] test exclude::tests::parse_single_ipv6 ... ok
[INFO] [stdout] test iptables::tests::test_family_matched_proxy_cross_family_rejected ... ok
[INFO] [stdout] test iptables::tests::test_family_matched_proxy_v6_to_ip6tables ... ok
[INFO] [stdout] test exclude::tests::parse_multiple_ipv4 ... ok
[INFO] [stdout] test iptables::tests::test_family_matched_proxy_empty_or_unspecified ... ok
[INFO] [stdout] test iptables::tests::test_rule_matches_uid_no_owner_flag ... ok
[INFO] [stdout] test iptables::tests::test_rule_matches_uid_no_substring_false_positive ... ok
[INFO] [stdout] test exclude::tests::v4_does_not_match_v6_entries_and_vice_versa ... ok
[INFO] [stdout] test exclude::tests::parse_single_ipv4 ... ok
[INFO] [stdout] test iptables::tests::test_family_matched_proxy_v4_to_iptables ... ok
[INFO] [stdout] test iptables::tests::test_rule_matches_uid_with_other_numbers_present ... ok
[INFO] [stdout] test iptables::tests::test_rule_matches_uid_exact ... ok
[INFO] [stdout] test relay::tests::test_format_target_loopback_v6 ... ok
[INFO] [stdout] test relay::tests::test_format_target_v4 ... ok
[INFO] [stdout] test relay::tests::test_format_target_v6_bracketed ... ok
[INFO] [stdout] test relay::tests::test_parse_host_port ... ok
[INFO] [stdout] test relay::tests::test_parse_status_code_malformed ... ok
[INFO] [stdout] test relay::tests::test_parse_host_port_bracketed_ipv6 ... ok
[INFO] [stdout] test relay::tests::test_parse_status_code_no_false_positive_on_substring ... ok
[INFO] [stdout] test relay::tests::test_parse_host_port_hostname ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_32 ... ok
[INFO] [stdout] test iptables::tests::test_family_matched_proxy_invalid ... ok
[INFO] [stdout] test relay::tests::test_find_header_end ... ok
[INFO] [stdout] test relay::tests::test_parse_status_code_ok_no_reason ... ok
[INFO] [stdout] test relay::tests::test_parse_status_code_non_200 ... ok
[INFO] [stdout] test relay::tests::test_parse_status_code_ok_with_reason ... ok
[INFO] [stdout] test resolve::tests::test_parse_uid_exact_match ... ok
[INFO] [stdout] test resolve::tests::test_parse_uid_multi_uid_field ... ok
[INFO] [stdout] test resolve::tests::test_parse_uid_no_substring_false_positive ... ok
[INFO] [stdout] test resolve::tests::test_parse_uid_not_found ... ok
[INFO] [stdout] test resolve::tests::test_parse_uid_partial_name_no_match ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_bad_uid ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_bad_comma_mixed ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_comma_uids ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_empty_comma_part ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_comma_uids_with_spaces ... ok
[INFO] [stdout] test resolve::tests::test_resolve_target_single_uid ... ok
[INFO] [stdout] test exclude::tests::parse_ipv6_cidr_128_default ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 54 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.28s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-987d4b451aba69fd)
[INFO] [stdout] running 26 tests
[INFO] [stdout] test test_cli_exclude_invalid_cidr ... ok
[INFO] [stdout] test test_cli_exclude_invalid_ip ... ok
[INFO] [stdout] test test_cli_comma_uids_bad_mixed ... ok
[INFO] [stdout] test test_cli_proxy_hostname_rejected ... ok
[INFO] [stdout] test test_cli_help ... ok
[INFO] [stdout] test test_cli_missing_proxy ... ok
[INFO] [stdout] test test_cli_bad_uid ... ok
[INFO] [stdout] test test_cli_version ... ok
[INFO] [stdout] test test_iptables_apply_generates_rules ... ok
[INFO] [stdout] test test_cli_no_args_shows_help ... ok
[INFO] [stdout] test test_iptables_redirect_rule ... ok
[INFO] [stdout] test test_cli_exclude_mixed_family_comma ... ok
[INFO] [stdout] test test_cli_exclude_comma_separated ... ok
[INFO] [stdout] test test_cli_exclude_ipv6_accepted ... ok
[INFO] [stdout] test test_cli_exclude_repeated_flag ... ok
[INFO] [stdout] test test_ipv6_uses_ip6tables ... ok
[INFO] [stdout] test test_root_exclude_bypasses_proxy ... FAILED
[INFO] [stdout] test test_root_exclude_does_not_break_proxy_path ... FAILED
[INFO] [stdout] test test_root_clean_removes_stale_rules ... FAILED
[INFO] [stdout] test test_root_sigterm_graceful_shutdown ... FAILED
[INFO] [stdout] test test_cli_proxy_bracketed_ipv6_accepted_until_root ... FAILED
[INFO] [stdout] test test_clean_multi_uid ... ok
[INFO] [stdout] test test_mock_iptables_infrastructure ... ok
[INFO] [stdout] test test_root_e2e_traffic_relay ... FAILED
[INFO] [stdout] test test_root_iptables_apply_remove ... FAILED
[INFO] [stdout] test test_cli_proxy_plain_ipv4_accepted_until_root has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60", kill_on_drop: false }`
[INFO] [stdout] 94affa05b5bf69ad12fabb5095b45bbcfb15c5cd2b2504533afb06b415dada60
