[INFO] cloning repository https://github.com/OlympusLedgerOrg/1f916-archive
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/OlympusLedgerOrg/1f916-archive" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOlympusLedgerOrg%2F1f916-archive", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOlympusLedgerOrg%2F1f916-archive'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c4e60c36a807873ad3e5f280e3feac1492b95458
[INFO] testing OlympusLedgerOrg/1f916-archive against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOlympusLedgerOrg%2F1f916-archive" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:   8% (4044/48189)
Updating files:   9% (4338/48189)
Updating files:  10% (4819/48189)
Updating files:  11% (5301/48189)
Updating files:  12% (5783/48189)
Updating files:  13% (6265/48189)
Updating files:  14% (6747/48189)
Updating files:  15% (7229/48189)
Updating files:  16% (7711/48189)
Updating files:  17% (8193/48189)
Updating files:  18% (8675/48189)
Updating files:  19% (9156/48189)
Updating files:  20% (9638/48189)
Updating files:  21% (10120/48189)
Updating files:  22% (10602/48189)
Updating files:  23% (11084/48189)
Updating files:  24% (11566/48189)
Updating files:  25% (12048/48189)
Updating files:  26% (12530/48189)
Updating files:  27% (13012/48189)
Updating files:  28% (13493/48189)
Updating files:  29% (13975/48189)
Updating files:  30% (14457/48189)
Updating files:  31% (14939/48189)
Updating files:  32% (15421/48189)
Updating files:  32% (15455/48189)
Updating files:  33% (15903/48189)
Updating files:  34% (16385/48189)
Updating files:  35% (16867/48189)
Updating files:  36% (17349/48189)
Updating files:  37% (17830/48189)
Updating files:  38% (18312/48189)
Updating files:  39% (18794/48189)
Updating files:  40% (19276/48189)
Updating files:  41% (19758/48189)
Updating files:  42% (20240/48189)
Updating files:  43% (20722/48189)
Updating files:  44% (21204/48189)
Updating files:  45% (21686/48189)
Updating files:  46% (22167/48189)
Updating files:  47% (22649/48189)
Updating files:  48% (23131/48189)
Updating files:  49% (23613/48189)
Updating files:  50% (24095/48189)
Updating files:  51% (24577/48189)
Updating files:  52% (25059/48189)
Updating files:  53% (25541/48189)
Updating files:  53% (25816/48189)
Updating files:  54% (26023/48189)
Updating files:  55% (26504/48189)
Updating files:  56% (26986/48189)
Updating files:  57% (27468/48189)
Updating files:  58% (27950/48189)
Updating files:  59% (28432/48189)
Updating files:  60% (28914/48189)
Updating files:  61% (29396/48189)
Updating files:  62% (29878/48189)
Updating files:  63% (30360/48189)
Updating files:  64% (30841/48189)
Updating files:  65% (31323/48189)
Updating files:  66% (31805/48189)
Updating files:  67% (32287/48189)
Updating files:  68% (32769/48189)
Updating files:  69% (33251/48189)
Updating files:  70% (33733/48189)
Updating files:  71% (34215/48189)
Updating files:  71% (34643/48189)
Updating files:  72% (34697/48189)
Updating files:  73% (35178/48189)
Updating files:  74% (35660/48189)
Updating files:  75% (36142/48189)
Updating files:  76% (36624/48189)
Updating files:  77% (37106/48189)
Updating files:  78% (37588/48189)
Updating files:  79% (38070/48189)
Updating files:  80% (38552/48189)
Updating files:  81% (39034/48189)
Updating files:  82% (39515/48189)
Updating files:  83% (39997/48189)
Updating files:  84% (40479/48189)
Updating files:  85% (40961/48189)
Updating files:  86% (41443/48189)
Updating files:  87% (41925/48189)
Updating files:  87% (42199/48189)
Updating files:  88% (42407/48189)
Updating files:  89% (42889/48189)
Updating files:  90% (43371/48189)
Updating files:  91% (43852/48189)
Updating files:  92% (44334/48189)
Updating files:  93% (44816/48189)
Updating files:  94% (45298/48189)
Updating files:  95% (45780/48189)
Updating files:  96% (46262/48189)
Updating files:  97% (46744/48189)
Updating files:  98% (47226/48189)
Updating files:  98% (47501/48189)
Updating files:  98% (47662/48189)
Updating files:  99% (47708/48189)
Updating files:  99% (47747/48189)
Updating files:  99% (47769/48189)
Updating files:  99% (47804/48189)
Updating files: 100% (48189/48189)
Updating files: 100% (48189/48189), done.
[INFO] started tweaking git repo https://github.com/OlympusLedgerOrg/1f916-archive
[INFO] finished tweaking git repo https://github.com/OlympusLedgerOrg/1f916-archive
[INFO] tweaked toml for git repo https://github.com/OlympusLedgerOrg/1f916-archive written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/OlympusLedgerOrg/1f916-archive 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/OlympusLedgerOrg/1f916-archive 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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded find-msvc-tools v0.1.10
[INFO] [stderr]   Downloaded cc v1.4.2
[INFO] [stderr]   Downloaded blake3 v1.8.6
[INFO] [stderr]   Downloaded rustls v0.23.43
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2
[INFO] running `Command { std: "docker" "start" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", 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" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2" "/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" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", 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" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling find-msvc-tools v0.1.10
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling cc v1.4.2
[INFO] [stderr]    Compiling rustls-pki-types v1.15.1
[INFO] [stderr]    Compiling rustls v0.23.43
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling adler2 v2.0.1
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling webpki-roots v1.0.9
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling arrayref v0.3.9
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling blake3 v1.8.6
[INFO] [stderr]    Compiling displaydoc v0.2.7
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling f916-collector v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 53.08s
[INFO] running `Command { std: "docker" "inspect" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", 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" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling f916-collector v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.19s
[INFO] running `Command { std: "docker" "inspect" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", 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" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/f916_collect-41936f9e31e75174)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test anchored::tests::a_duplicate_declaration_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_declaration_for_a_version_that_does_not_exist_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_declaration_with_an_impossible_date_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_broken_register_is_an_error_not_an_empty_one ... ok
[INFO] [stdout] test anchored::tests::a_manifest_named_inconsistently_with_its_version_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_missing_register_means_everything_must_be_anchored ... ok
[INFO] [stdout] test anchored::tests::a_declaration_does_not_cover_a_different_version ... ok
[INFO] [stdout] test anchored::tests::a_stale_declaration_for_an_anchored_version_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_declaration_with_a_blank_reason_is_not_coverage ... ok
[INFO] [stdout] test anchored::tests::every_undeclared_version_is_listed_not_just_the_first ... ok
[INFO] [stdout] test anchored::tests::a_declaration_whose_date_is_not_a_date_is_rejected ... ok
[INFO] [stdout] test anchored::tests::a_well_formed_declaration_still_passes ... ok
[INFO] [stdout] test anchored::tests::non_manifest_files_in_the_directory_are_ignored ... ok
[INFO] [stdout] test api::tests::a_chain_verified_short_of_its_total_is_reported_as_partial ... ok
[INFO] [stdout] test anchored::tests::iso_dates_accept_real_days_and_reject_impossible_ones ... ok
[INFO] [stdout] test anchored::tests::an_undeclared_unanchored_manifest_is_rejected_and_names_the_version ... ok
[INFO] [stdout] test api::tests::a_non_numeric_id_is_rejected_rather_than_coerced ... ok
[INFO] [stdout] test api::tests::a_page_missing_optional_arrays_is_not_an_error ... ok
[INFO] [stdout] test api::tests::attest_carries_no_string_out_of_the_response ... ok
[INFO] [stdout] test api::tests::attest_coverage_is_read_from_the_real_captured_shape ... ok
[INFO] [stdout] test anchored::tests::every_manifest_anchored_needs_no_register ... ok
[INFO] [stdout] test api::tests::both_chains_can_be_partial_at_once ... ok
[INFO] [stdout] test api::tests::attest_missing_a_counter_is_an_error_not_a_silent_pass ... ok
[INFO] [stdout] test api::tests::parses_a_changes_page_down_to_numbers ... ok
[INFO] [stdout] test args::tests::empty_value_is_treated_as_absent ... ok
[INFO] [stdout] test anchored::tests::a_declared_unanchored_manifest_passes ... ok
[INFO] [stdout] test args::tests::known_bool_flag_does_not_swallow_next_token ... ok
[INFO] [stdout] test api::tests::events_page_without_next_since_is_terminal ... ok
[INFO] [stdout] test api::tests::hostile_strings_are_parsed_but_never_retained ... ok
[INFO] [stdout] test api::tests::malformed_json_is_an_error_not_an_empty_page ... ok
[INFO] [stdout] test collect::tests::urls_are_built_only_from_the_operator_base_and_integers ... ok
[INFO] [stdout] test http::tests::json_expectation_ignores_parameters_and_case ... ok
[INFO] [stdout] test http::tests::retry_after_accepts_seconds_and_ignores_http_date ... ok
[INFO] [stdout] test collect::tests::default_plan_bounds_every_unbounded_loop ... ok
[INFO] [stdout] test collect::tests::every_site_endpoint_path_is_a_constant_without_interpolation ... ok
[INFO] [stdout] test http::tests::user_agent_identifies_the_project_and_a_contact ... ok
[INFO] [stdout] test http::tests::backoff_is_bounded_and_jittered ... ok
[INFO] [stdout] test http::tests::budget_is_enforced_before_any_request ... ok
[INFO] [stdout] test packet::tests::meta_name_splits_on_the_final_dot ... ok
[INFO] [stdout] test packet::tests::packet_names_cannot_express_traversal ... ok
[INFO] [stdout] test args::tests::malformed_number_is_an_error_not_a_silent_default ... ok
[INFO] [stdout] test args::tests::parses_values_and_bool_flags ... ok
[INFO] [stdout] test http::tests::text_expectation_rejects_html ... ok
[INFO] [stdout] test packet::tests::sidecar_escapes_control_characters_in_observed_headers ... ok
[INFO] [stdout] test packet::tests::sequence_max_is_taken_across_all_shards ... ok
[INFO] [stdout] test state::tests::a_previously_absent_id_that_answers_is_no_longer_absent ... ok
[INFO] [stdout] test state::tests::absence_is_cached_only_below_the_present_watermark ... ok
[INFO] [stdout] test state::tests::gap_ids_are_exactly_the_unreachable_moderated_posts ... ok
[INFO] [stdout] test packet::tests::body_bytes_are_stored_verbatim_including_invalid_utf8 ... ok
[INFO] [stdout] test state::tests::rotation_advances_and_wraps_without_repeating_within_a_pass ... ok
[INFO] [stdout] test state::tests::rotation_on_an_empty_corpus_is_empty ... ok
[INFO] [stdout] test anchored::tests::an_empty_manifest_directory_is_an_error ... ok
[INFO] [stdout] test state::tests::missing_file_yields_a_fresh_state ... ok
[INFO] [stdout] test withheld::tests::a_broken_register_is_an_error_not_an_empty_one ... ok
[INFO] [stdout] test state::tests::corrupt_state_is_an_error_not_a_silent_reset ... ok
[INFO] [stdout] test packet::tests::a_new_run_never_reuses_a_sequence ... ok
[INFO] [stdout] test withheld::tests::a_declaration_for_a_different_version_does_not_cover_this_removal ... ok
[INFO] [stdout] test withheld::tests::a_declaration_for_a_different_record_does_not_cover_this_one ... ok
[INFO] [stdout] test withheld::tests::a_declared_removal_at_the_right_version_passes ... ok
[INFO] [stdout] test packet::tests::refuses_to_overwrite_an_existing_packet ... ok
[INFO] [stdout] test withheld::tests::every_undeclared_removal_is_listed_not_just_the_first ... ok
[INFO] [stdout] test withheld::tests::an_undeclared_removal_is_rejected_and_names_the_record ... ok
[INFO] [stdout] test packet::tests::writes_a_packet_and_a_sidecar ... ok
[INFO] [stdout] test withheld::tests::an_additive_diff_needs_no_register ... ok
[INFO] [stdout] test packet::tests::a_repeat_capture_in_a_later_sequence_is_allowed_and_preserves_both ... ok
[INFO] [stdout] test state::tests::save_then_load_round_trips ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/collector_e2e.rs (/opt/rustwide/target/debug/deps/collector_e2e-6254e47f80c6fab1)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test an_unexpected_content_type_is_refused_before_it_is_stored ... ok
[INFO] [stdout] test an_unparseable_changes_page_holds_the_cursor ... ok
[INFO] [stdout] test a_complete_run_advances_the_cursor_and_captures_every_discovered_post ... ok
[INFO] [stdout] test the_id_sweep_reaches_posts_the_changes_feed_never_mentions ... ok
[INFO] [stdout] test a_failed_post_fetch_holds_the_cursor_and_the_rerun_loses_nothing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2", kill_on_drop: false }`
[INFO] [stdout] 29d94635720e2be353710becc375686fbdabed30f7dd697a39ad3ac7f0b5c4c2
