[INFO] fetching crate sederial 0.2.0...
[INFO] testing sederial-0.2.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate sederial 0.2.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate sederial 0.2.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sederial 0.2.0
[INFO] tweaked toml for crates.io crate sederial 0.2.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sederial 0.2.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sederial 0.2.0 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc
[INFO] running `Command { std: "docker" "start" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", 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" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", 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" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling signal-hook v0.4.4
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.1.6+spec-1.1.0
[INFO] [stderr]    Compiling sederial v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.38s
[INFO] running `Command { std: "docker" "inspect" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", 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" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sederial v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 6.81s
[INFO] running `Command { std: "docker" "inspect" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", 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" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/build/sederial/37734d9e9829a1ed/out/sederial-37734d9e9829a1ed)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test dns::cookie::boundary_tests::response_cookie_lengths_and_local_error_policy ... ok
[INFO] [stdout] test config::tests::defaults_omitted_ports_and_preserves_explicit_ports ... ok
[INFO] [stdout] test dns::tests::compression_rfc1035_section_4_1_4 ... ok
[INFO] [stdout] test config::tests::rejects_invalid_config_as_a_whole ... ok
[INFO] [stdout] test dns::cookie::tests::retry_inserts_the_server_cookie_and_rejects_a_mismatch ... ok
[INFO] [stdout] test dns::tests::counts_trailing_data_and_all_truncations ... ok
[INFO] [stdout] test dns::cookie::tests::growing_a_cookie_before_later_bytes_is_not_rewritten ... ok
[INFO] [stdout] test dns::tests::hostile_compression_and_labels ... ok
[INFO] [stdout] test dns::tests::header_and_question_vector ... ok
[INFO] [stdout] test config::tests::parses_complete_toml_and_ipv6 ... ok
[INFO] [stdout] test dns::tests::malformed_query_flags_and_missing_question_are_rejected ... ok
[INFO] [stdout] test dns::tests::response_correlation_and_safe_truncation ... ok
[INFO] [stdout] test config::tests::default_ports_preserve_loop_and_duplicate_detection ... ok
[INFO] [stdout] test dns::tests::malformed_opt_and_resource_data_rejected ... ok
[INFO] [stdout] test dns::tests::unknown_types_classes_opcodes_and_records_are_representable ... ok
[INFO] [stdout] test dns::tests::structured_opt_mutations_retain_only_safe_error_context ... ok
[INFO] [stdout] test routing::tests::exact_subdomain_longest_boundary_default_and_reverse ... ok
[INFO] [stdout] test dns::tests::edns_size_options_version_and_generated_errors ... ok
[INFO] [stdout] test routing::tests::root_route_and_duplicate_normalization ... ok
[INFO] [stdout] test server::pool::shutdown_tests::timed_out_shutdown_does_not_join_a_blocked_worker_in_drop ... ok
[INFO] [stdout] test dns::tests::names_preserve_binary_labels_and_enforce_limits ... ok
[INFO] [stdout] test server::admission_tests::expired_udp_job_waiting_in_queue_never_reaches_any_upstream ... ok
[INFO] [stdout] test dns::tests::responses_cannot_be_used_as_validated_queries ... ok
[INFO] [stdout] test server::tests::extended_accept_errnos_are_retryable ... ok
[INFO] [stdout] test server::tests::per_connection_accept_errors_do_not_stop_the_listener ... ok
[INFO] [stdout] test server::pool::tests::initialization_failure_stops_and_joins_started_workers ... ok
[INFO] [stdout] test config::tests::listen_keeps_the_canonical_unicast_address ... ok
[INFO] [stdout] test upstream::cancellation_tests::cancellation_is_distinct_from_an_interrupted_io_call ... ok
[INFO] [stdout] test transport::tests::framing_deadline_is_absolute_despite_partial_progress ... ok
[INFO] [stdout] test server::pool::panic_tests::handle_and_maintain_panics_request_service_shutdown ... ok
[INFO] [stdout] test upstream::tests::request_budget_bounds_failover_and_shutdown_never_contacts_next_server ... ok
[INFO] [stdout] test transport::tests::framing_retains_partial_prefix_across_read_timeouts ... ok
[INFO] [stdout] test dns::tests::deterministic_hostile_corpus_never_panics ... ok
[INFO] [stdout] test logging::tests::blocked_sink_has_bounded_queues_and_bounded_teardown ... ok
[INFO] [stdout] test upstream::tests::tc_cookie_and_reused_tcp_share_the_original_request_budget ... ok
[INFO] [stdout] test server::pool::tests::idle_workers_maintain_without_sharing_the_queue_wait ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.61s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/forwarding.rs (/opt/rustwide/target/debug/build/sederial/e16fd123c73624bf/out/forwarding-e16fd123c73624bf)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test edns_unknown_records_flags_and_options_survive ... ok
[INFO] [stdout] test badcookie_retries_the_same_server_then_tcp ... ok
[INFO] [stdout] test invalid_udp_responses_are_ignored_until_correlated_reply ... ok
[INFO] [stdout] test badcookie_with_a_fresh_server_cookie_is_answered_on_udp ... ok
[INFO] [stdout] test cookie_unaware_servers_remain_compatible_on_the_first_exchange ... ok
[INFO] [stdout] test invalid_tcp_responses_fail_over_and_private_routes_do_not_leak ... ok
[INFO] [stdout] test ipv4_mapped_upstream_reaches_the_ipv4_endpoint ... ok
[INFO] [stdout] test ipv6_listener_and_upstream ... ok
[INFO] [stdout] test local_tc_retains_the_validated_response_cookie ... ok
[INFO] [stdout] test malformed_edns_returns_a_fresh_opt_over_both_transports ... ok
[INFO] [stdout] test servfail_after_cookie_retry_tries_the_next_server ... ok
[INFO] [stdout] test cookie_before_padding_unknown_option_or_additional_rr_uses_tcp_without_moving_bytes ... ok
[INFO] [stdout] test every_udp_rcode_checks_cookie_before_accepting_the_response ... ok
[INFO] [stdout] test last_dns_failure_preserves_ede_even_after_transport_failure ... ok
[INFO] [stdout] test tcp_fragmentation_pipelining_and_upstream_connection_reuse ... ok
[INFO] [stdout] test refused_fails_over_but_nxdomain_is_final ... ok
[INFO] [stdout] test udp_ceiling_truncates_large_edns_answer_but_tcp_keeps_it ... ok
[INFO] [stdout] test truncated_udp_retries_tcp_and_respects_client_size ... ok
[INFO] [stdout] test split_dns_routes_ad_srv_reverse_and_default_over_both_transports ... ok
[INFO] [stdout] test cookie_validation_covers_initial_and_retried_tcp_and_udp_responses ... ok
[INFO] [stdout] test unavailable_upstreams_return_servfail ... ok
[INFO] [stdout] test timeout_servfail_and_connection_failure_fail_over_but_nxdomain_is_final ... ok
[INFO] [stdout] test private_route_failures_never_contact_default_over_udp_or_tcp ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.96s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/operations.rs (/opt/rustwide/target/debug/build/sederial/607c29be1c45fad5/out/operations-607c29be1c45fad5)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test malformed_tcp_frames_close_only_the_offending_connection ... ok
[INFO] [stdout] test cli_and_startup_diagnostics ... ok
[INFO] [stdout] test malformed_unsupported_and_unsolicited_packets_do_not_break_service ... ok
[INFO] [stdout] test tcp_capacity_is_bounded_and_udp_stays_available ... ok
[INFO] [stdout] test shutdown_interrupts_pending_upstreams_and_partial_tcp_clients ... ok
[INFO] [stdout] test idle_tcp_clients_do_not_block_shutdown_or_udp_after_receive_timeouts ... ok
[INFO] [stdout] test idle_listeners_shutdown_without_client_traffic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.17s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc", kill_on_drop: false }`
[INFO] [stdout] 4a2c30ceb57e162efad19492241d80f356046fab089e99497069f0e68e4f26dc
