[INFO] cloning repository https://github.com/franzos/vpnmux
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/franzos/vpnmux" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fvpnmux", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fvpnmux'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 610e640fccc66d2f3ef52b990ddd412b4ebc75cc
[INFO] testing franzos/vpnmux against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fvpnmux" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/franzos/vpnmux
[INFO] finished tweaking git repo https://github.com/franzos/vpnmux
[INFO] tweaked toml for git repo https://github.com/franzos/vpnmux written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/franzos/vpnmux on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/franzos/vpnmux 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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36
[INFO] running `Command { std: "docker" "start" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", 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" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", 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" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling vpnmux v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 10.06s
[INFO] running `Command { std: "docker" "inspect" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", 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" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling vpnmux v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 15.69s
[INFO] running `Command { std: "docker" "inspect" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", 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" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/vpnmux-39f3c7bff55ea56a)
[INFO] [stdout] 
[INFO] [stdout] running 87 tests
[INFO] [stdout] test cli::tests::json_escape_handles_specials ... ok
[INFO] [stdout] test cli::tests::status_json_none_is_empty_payload ... ok
[INFO] [stdout] test coexistence::tests::both_active_whitelists_and_disables_magicdns ... ok
[INFO] [stdout] test coexistence::tests::tailscale_only_disables_magicdns_on_transition ... ok
[INFO] [stdout] test coexistence::tests::mullvad_only_does_nothing ... ok
[INFO] [stdout] test dns::tests::classify_comment_header_stops_at_blank_line ... ok
[INFO] [stdout] test dns::tests::classify_symlink_components_match_real_and_reject_lookalikes ... ok
[INFO] [stdout] test dns::tests::detect_all_localhost_resolved_is_systemd ... ok
[INFO] [stdout] test dns::tests::detect_netconfig_symlink ... ok
[INFO] [stdout] test cli::tests::status_json_shape ... ok
[INFO] [stdout] test dns::tests::detect_networkmanager_header ... ok
[INFO] [stdout] test cli::tests::comma_and_space_forms_are_equivalent ... ok
[INFO] [stdout] test cli::tests::status_json_escapes_reason ... ok
[INFO] [stdout] test cli::tests::unknown_provider_is_an_error ... ok
[INFO] [stdout] test coexistence::tests::steady_state_does_not_respawn_accept_dns ... ok
[INFO] [stdout] test dns::tests::detect_resolvconf_symlink ... ok
[INFO] [stdout] test dns::tests::detect_unknown_symlink_is_other_managed ... ok
[INFO] [stdout] test cli::tests::yes_flag_with_empty_set ... ok
[INFO] [stdout] test dns::tests::detect_systemd_stub_symlink ... ok
[INFO] [stdout] test dns::tests::has_nameserver_detects_presence ... ok
[INFO] [stdout] test dns::tests::detect_plain_nameserver_is_static ... ok
[INFO] [stdout] test dns::tests::ensure_resolver_refuses_symlink ... ok
[INFO] [stdout] test dns::tests::detect_resolvconf_header ... ok
[INFO] [stdout] test dns::tests::ensure_resolver_skips_invalid_ip ... ok
[INFO] [stdout] test dns::tests::detect_static_when_resolvconf_mentioned_after_blank_line ... ok
[INFO] [stdout] test dns::tests::inject_prepends_tagged_nameserver_and_keeps_search ... ok
[INFO] [stdout] test dns::tests::no_default_route_is_none ... ok
[INFO] [stdout] test dns::tests::managed_backend_is_noop ... ok
[INFO] [stdout] test dns::tests::lowest_metric_default_route_wins ... ok
[INFO] [stdout] test dns::tests::parses_default_gateway_little_endian ... ok
[INFO] [stdout] test dns::tests::resolvconf_del_invokes_resolvconf_d ... ok
[INFO] [stdout] test dns::tests::resolvectl_shim_is_detected ... ok
[INFO] [stdout] test dns::tests::pick_resolver_precedence ... ok
[INFO] [stdout] test dns::tests::resolvconf_add_invokes_resolvconf_a ... ok
[INFO] [stdout] test log::tests::level_gating ... ok
[INFO] [stdout] test mullvad::tests::blocked_is_not_active ... ok
[INFO] [stdout] test mullvad::tests::check_unavailable_on_rpc_error ... ok
[INFO] [stdout] test dns::tests::ensure_resolver_resolvconf_gates_on_existing_nameserver ... ok
[INFO] [stdout] test mullvad::tests::connecting_is_not_active_but_available ... ok
[INFO] [stdout] test mullvad::tests::deactivate_keeps_table_when_lockdown_on ... ok
[INFO] [stdout] test mullvad::tests::deactivate_deletes_table_when_lockdown_off ... ok
[INFO] [stdout] test mullvad::tests::disconnecting_is_not_active ... ok
[INFO] [stdout] test fsutil::tests::mode_is_exact_under_restrictive_umask ... ok
[INFO] [stdout] test dns::tests::remove_injected_strips_only_tagged_line ... ok
[INFO] [stdout] test fsutil::tests::writes_contents_and_mode ... ok
[INFO] [stdout] test mullvad::tests::lockdown_on_parsed_from_real_cli_sentence ... ok
[INFO] [stdout] test mullvad::tests::nonzero_exit_is_unavailable_and_inactive ... ok
[INFO] [stdout] test nft::tests::delete_table_is_noop_when_absent ... ok
[INFO] [stdout] test mullvad::tests::is_active_false_when_disconnected ... ok
[INFO] [stdout] test mullvad::tests::is_active_true_when_connected ... ok
[INFO] [stdout] test dns::tests::ensure_resolver_writes_only_when_missing ... ok
[INFO] [stdout] test mullvad::tests::lockdown_off_parsed_from_real_cli_sentence ... ok
[INFO] [stdout] test fsutil::tests::overwrites_existing_target_atomically ... ok
[INFO] [stdout] test nft::tests::delete_table_reports_real_removal ... ok
[INFO] [stdout] test nft::tests::inserts_both_rules_when_missing ... ok
[INFO] [stdout] test nft::tests::noop_when_table_absent ... ok
[INFO] [stdout] test nft::tests::skips_when_rules_present ... ok
[INFO] [stdout] test reconcile::tests::available_includes_non_desired_providers ... ok
[INFO] [stdout] test paths::tests::env_override_wins ... ok
[INFO] [stdout] test reconcile::tests::activates_only_when_inactive ... ok
[INFO] [stdout] test paths::tests::unknown_bin_falls_back_to_bare_name ... ok
[INFO] [stdout] test reconcile::tests::both_desired_and_available_become_active_and_whitelisted ... ok
[INFO] [stdout] test reconcile::tests::empty_desired_skips_teardown_when_already_inactive ... ok
[INFO] [stdout] test reconcile::tests::empty_desired_tears_down_active_providers ... ok
[INFO] [stdout] test reconcile::tests::unavailable_provider_is_absent_from_available ... ok
[INFO] [stdout] test runner::tests::mock_unknown_command_is_success_empty ... ok
[INFO] [stdout] test state::tests::desired_empty_set_is_explicit_none ... ok
[INFO] [stdout] test state::tests::desired_roundtrip_with_providers ... ok
[INFO] [stdout] test state::tests::status_empty_available_has_no_trailing_space ... ok
[INFO] [stdout] test state::tests::read_status_without_available_line_yields_empty_set ... ok
[INFO] [stdout] test state::tests::read_absent_desired_is_none ... ok
[INFO] [stdout] test reconcile::tests::unavailable_tailscale_is_reported_and_does_not_block_mullvad ... ok
[INFO] [stdout] test runner::tests::mock_returns_scripted_output_and_records_calls ... ok
[INFO] [stdout] test tailscale::tests::check_available_when_running ... ok
[INFO] [stdout] test tailscale::tests::check_unavailable_when_needs_login ... ok
[INFO] [stdout] test tailscale::tests::check_unavailable_when_no_state ... ok
[INFO] [stdout] test tailscale::tests::is_active_true_when_running ... ok
[INFO] [stdout] test tailscale::tests::parses_backend_state ... ok
[INFO] [stdout] test tailscale::tests::stopped_is_available_but_inactive ... ok
[INFO] [stdout] test types::tests::empty_string_is_empty_set ... ok
[INFO] [stdout] test types::tests::parse_and_format_roundtrip ... ok
[INFO] [stdout] test types::tests::unknown_provider_errors ... ok
[INFO] [stdout] test state::tests::write_then_read_desired ... ok
[INFO] [stdout] test state::tests::status_serialize_includes_unavailable ... ok
[INFO] [stdout] test state::tests::write_then_read_status_preserves_unavailable ... ok
[INFO] [stdout] test state::tests::status_roundtrip_preserves_available ... ok
[INFO] [stdout] test fsutil::tests::concurrent_writes_do_not_collide_on_temp_name ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 87 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36", kill_on_drop: false }`
[INFO] [stdout] d155b302ce517dfa240e5a2b62e90c6bae6cd4f6830bcc74ac10c5e6b6d13d36
