[INFO] cloning repository https://github.com/czearing/critpath
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/czearing/critpath" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczearing%2Fcritpath", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczearing%2Fcritpath'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 5ff907044ac9c6e98de094c20f2c4d77733116d7
[INFO] testing czearing/critpath against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-3
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczearing%2Fcritpath" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/czearing/critpath
[INFO] finished tweaking git repo https://github.com/czearing/critpath
[INFO] tweaked toml for git repo https://github.com/czearing/critpath written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/czearing/critpath 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/czearing/critpath 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]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating git repository `https://github.com/czearing/fitkit`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e
[INFO] running `Command { std: "docker" "start" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", 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" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e" "/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" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", 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" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling fitkit-core v0.1.0 (https://github.com/czearing/fitkit#591bf472)
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling critpath-grow v0.1.0 (/opt/rustwide/workdir/crates/critpath-grow)
[INFO] [stderr]    Compiling critpath-core v0.1.0 (/opt/rustwide/workdir/crates/critpath-core)
[INFO] [stderr]    Compiling fitkit-ledger v0.1.0 (https://github.com/czearing/fitkit#591bf472)
[INFO] [stderr]    Compiling fitkit-dp v0.1.0 (https://github.com/czearing/fitkit#591bf472)
[INFO] [stderr]    Compiling critpath-graph v0.1.0 (/opt/rustwide/workdir/crates/critpath-graph)
[INFO] [stderr]    Compiling critpath-laws v0.1.0 (/opt/rustwide/workdir/crates/critpath-laws)
[INFO] [stderr]    Compiling critpath-repo v0.1.0 (/opt/rustwide/workdir/crates/critpath-repo)
[INFO] [stderr]    Compiling critpath-source v0.1.0 (/opt/rustwide/workdir/crates/critpath-source)
[INFO] [stderr]    Compiling critpath-trace v0.1.0 (/opt/rustwide/workdir/crates/critpath-trace)
[INFO] [stderr]    Compiling critpath v0.1.0 (/opt/rustwide/workdir/crates/critpath)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 28.72s
[INFO] running `Command { std: "docker" "inspect" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", 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" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e" "/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 critpath-source v0.1.0 (/opt/rustwide/workdir/crates/critpath-source)
[INFO] [stderr]    Compiling critpath-repo v0.1.0 (/opt/rustwide/workdir/crates/critpath-repo)
[INFO] [stderr]    Compiling critpath-trace v0.1.0 (/opt/rustwide/workdir/crates/critpath-trace)
[INFO] [stderr]    Compiling critpath-graph v0.1.0 (/opt/rustwide/workdir/crates/critpath-graph)
[INFO] [stderr]    Compiling critpath-core v0.1.0 (/opt/rustwide/workdir/crates/critpath-core)
[INFO] [stderr]    Compiling critpath-grow v0.1.0 (/opt/rustwide/workdir/crates/critpath-grow)
[INFO] [stderr]    Compiling critpath-laws v0.1.0 (/opt/rustwide/workdir/crates/critpath-laws)
[INFO] [stderr]    Compiling critpath v0.1.0 (/opt/rustwide/workdir/crates/critpath)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 10s
[INFO] running `Command { std: "docker" "inspect" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", 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" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e" "/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.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath-0da6a2bc514f65d8)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test holding::tests::sizes_read_in_the_unit_a_person_would_use ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/critpath-408630e45db5c6a7)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/seeded.rs (/opt/rustwide/target/release/deps/seeded-482d181f8176f833)
[INFO] [stdout] 
[INFO] [stdout] running 59 tests
[INFO] [stdout] test a_loop_over_different_things_is_not_a_repeat_but_the_same_thing_twice_is ... ok
[INFO] [stdout] test a_correlation_that_spans_the_recording_prices_itself_out ... ok
[INFO] [stdout] test a_page_loading_is_never_mistaken_for_a_person_interacting ... ok
[INFO] [stdout] test a_line_inside_an_installed_dependency_is_reported_as_unfixable_here ... ok
[INFO] [stdout] test a_frame_around_other_work_is_not_reported_as_work ... ok
[INFO] [stdout] test a_hole_silences_the_rules_by_name_instead_of_producing_a_blank_result ... ok
[INFO] [stdout] test a_producer_that_states_nothing_is_read_exactly_as_before ... ok
[INFO] [stdout] test a_finding_is_placed_on_the_original_line_the_build_states ... ok
[INFO] [stdout] test a_clean_run_produces_no_findings ... ok
[INFO] [stdout] test a_position_the_source_contradicts_is_marked_rather_than_reported_as_exact ... ok
[INFO] [stdout] test a_handoff_that_lands_in_dead_time_stays_unattached ... ok
[INFO] [stdout] test an_inferred_interval_never_becomes_a_dependency ... ok
[INFO] [stdout] test a_wait_names_what_it_waited_for_only_when_the_source_said_so ... ok
[INFO] [stdout] test an_interaction_chain_never_runs_past_the_frame_that_answered_it ... ok
[INFO] [stdout] test a_stated_interaction_needs_no_separately_spelled_frame_event ... ok
[INFO] [stdout] test a_withheld_finding_can_never_be_chosen_as_a_repair ... ok
[INFO] [stdout] test a_recording_of_someone_clicking_is_admitted ... ok
[INFO] [stdout] test an_event_belonging_to_no_interaction_is_not_reported_as_one ... ok
[INFO] [stdout] test an_arrival_binds_to_the_work_it_hands_to_and_a_stated_binding_point_overrides_it ... ok
[INFO] [stdout] test a_transfer_recorded_as_two_marks_becomes_one_measured_interval ... ok
[INFO] [stdout] test a_producer_whose_spelling_is_unknown_answers_for_the_finish_and_refuses_the_rest ... ok
[INFO] [stdout] test a_recording_of_nobody_doing_anything_cannot_report_on_interaction ... ok
[INFO] [stdout] test an_envelope_is_never_a_step_of_the_chain_that_explains_it ... ok
[INFO] [stdout] test a_script_with_no_map_is_placed_nowhere_at_all ... ok
[INFO] [stdout] test a_value_two_different_things_share_is_not_an_identity ... ok
[INFO] [stdout] test a_stated_interaction_is_measured_from_the_hardware_and_not_from_the_handler ... ok
[INFO] [stdout] test an_interaction_whose_handling_was_never_recorded_is_named_rather_than_dropped ... ok
[INFO] [stdout] test an_origin_the_recording_never_names_is_refused_rather_than_matched_against_nothing ... ok
[INFO] [stdout] test maps_are_opt_in_and_the_report_without_them_is_unchanged ... ok
[INFO] [stdout] test something_that_is_not_a_trace_is_refused_rather_than_analysed ... ok
[INFO] [stdout] test asynchronous_work_is_read_and_never_ordered_by_where_it_sits ... ok
[INFO] [stdout] test the_chain_explains_the_work_inside_the_window ... ok
[INFO] [stdout] test the_chain_is_the_main_thread_and_not_the_biggest_bar ... ok
[INFO] [stdout] test the_phases_name_which_repair_to_make ... ok
[INFO] [stdout] test the_same_identifier_in_another_process_is_not_the_same_thing ... ok
[INFO] [stdout] test the_seeded_defects_are_all_named ... ok
[INFO] [stdout] test one_change_buys_the_larger_defect ... ok
[INFO] [stdout] test the_largest_activity_is_reported_as_not_mattering ... ok
[INFO] [stdout] test asking_why_it_finished_reports_no_interactions_at_all ... ok
[INFO] [stdout] test declaring_no_origin_attributes_nothing_and_reports_everything ... ok
[INFO] [stdout] test one_gesture_is_one_interaction ... ok
[INFO] [stdout] test the_margin_is_set_by_the_tightest_rival_and_not_the_roomiest ... ok
[INFO] [stdout] test work_the_trace_attributes_to_another_origin_is_withheld_from_the_report ... ok
[INFO] [stdout] test overlap_in_time_is_never_offered_as_a_saving ... ok
[INFO] [stdout] test work_with_no_stated_origin_is_reported_apart_rather_than_as_the_products ... ok
[INFO] [stdout] test working_and_waiting_account_for_the_whole_stated_latency ... ok
[INFO] [stdout] test work_off_the_chain_is_reported_with_the_room_it_has_left ... ok
[INFO] [stdout] test the_decoy_repeat_off_the_chain_stays_silent ... ok
[INFO] [stdout] test the_margin_stops_at_the_next_constraint ... ok
[INFO] [stdout] test work_that_states_no_position_is_placed_at_the_call_that_ran_it ... ok
[INFO] [stdout] test work_still_running_when_the_window_closed_does_not_withdraw_the_verdict ... ok
[INFO] [stdout] test placement_never_changes_what_was_proved ... ok
[INFO] [stdout] test work_the_source_described_no_further_is_never_called_repeated ... ok
[INFO] [stdout] test every_step_of_the_chain_itself_has_no_room_at_all ... ok
[INFO] [stdout] test two_changes_buy_both ... ok
[INFO] [stdout] test the_wait_is_given_the_subject_that_was_in_flight_across_it ... ok
[INFO] [stdout] test each_interaction_is_timed_from_its_handler_to_the_next_frame ... ok
[INFO] [stdout] test the_report_says_what_it_set_aside_and_why ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_core-8abad4f071656b8e)
[INFO] [stdout] test a_recording_whose_chain_is_most_of_it_is_answered_in_seconds_not_minutes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.60s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test ask::tests::interactions_without_a_presentation_are_refused_separately ... ok
[INFO] [stdout] test owner::tests::an_origin_is_found_inside_the_subject_the_reader_assembles ... ok
[INFO] [stdout] test owner::tests::an_origin_is_read_without_parsing_a_url ... ok
[INFO] [stdout] test owner::tests::work_naming_no_origin_is_unstated_rather_than_elsewhere ... ok
[INFO] [stdout] test owner::tests::work_naming_only_another_origin_belongs_elsewhere ... ok
[INFO] [stdout] test owner::tests::work_naming_the_declared_origin_is_under_test ... ok
[INFO] [stdout] test site::tests::a_position_without_a_script_is_refused ... ok
[INFO] [stdout] test site::tests::a_subject_without_a_position_states_none ... ok
[INFO] [stdout] test site::tests::an_unnamed_function_still_yields_a_position ... ok
[INFO] [stdout] test owner::tests::a_request_the_product_makes_to_a_third_party_stays_under_test ... ok
[INFO] [stdout] test site::tests::a_position_is_read_from_the_subject_the_reader_assembles ... ok
[INFO] [stdout] test ask::tests::an_idle_recording_cannot_answer_for_an_interaction ... ok
[INFO] [stdout] test site::tests::nested_fields_are_matched_by_their_leaf_not_their_path ... ok
[INFO] [stdout] test ask::tests::the_same_idle_recording_still_answers_for_the_finish ... ok
[INFO] [stdout] test enclosure_tests::a_slice_that_outlives_the_one_it_started_inside_encloses_nothing_and_is_enclosed_by_nothing ... ok
[INFO] [stdout] test ask::tests::an_undeclared_origin_is_refused_rather_than_quietly_matching_nothing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_graph-72f958f65b65b277)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test order::tests::a_dependency_the_clock_agrees_with_is_no_contradiction ... ok
[INFO] [stdout] test tests::an_empty_trace_is_refused ... ok
[INFO] [stdout] test tests::a_trace_with_nothing_off_the_chain_bounds_nothing ... ok
[INFO] [stdout] test order::tests::a_dependency_the_clock_denies_is_not_believed ... ok
[INFO] [stdout] test tests::a_dependency_the_clock_denies_never_reaches_the_chain ... ok
[INFO] [stdout] test order::tests::every_activity_appears_exactly_once_in_the_order ... ok
[INFO] [stdout] test order::tests::a_parent_and_its_own_child_are_not_a_sequence ... ok
[INFO] [stdout] test tests::the_chain_beats_the_biggest_bar ... ok
[INFO] [stdout] test tests::several_tracks_with_no_stated_flow_are_refused_rather_than_ranked ... ok
[INFO] [stdout] test tests::a_recording_with_no_rival_says_so_instead_of_claiming_a_longest_path ... ok
[INFO] [stdout] test tests::the_margin_is_the_gap_to_the_next_constraint ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_grow-cc4a8385fe51407c)
[INFO] [stdout] test tests::waiting_is_counted_apart_from_working ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test degree::tests::a_shared_helper_is_solved_once_and_read_back ... ok
[INFO] [stdout] test rules::tests::growth_hidden_behind_a_call_is_separated_from_growth_written_in_place ... ok
[INFO] [stdout] test rules::tests::the_same_call_moved_to_depend_on_the_loop_stops_being_reported ... ok
[INFO] [stdout] test grammar::tests::a_language_is_recognised_by_extension_whatever_its_case ... ok
[INFO] [stdout] test degree::tests::growth_crosses_files_because_a_call_does ... ok
[INFO] [stdout] test rules::tests::an_import_never_mentioned_again_is_named_at_its_line ... ok
[INFO] [stdout] test rules::tests::a_call_that_cannot_change_between_iterations_is_named_at_its_line ... ok
[INFO] [stdout] test grammar::tests::no_extension_is_claimed_by_two_grammars ... ok
[INFO] [stdout] test rules::tests::a_clean_file_proves_nothing ... ok
[INFO] [stdout] test rules::tests::a_value_built_every_iteration_for_no_reason_is_named ... ok
[INFO] [stdout] test degree::tests::a_routine_written_inside_another_is_not_charged_to_it ... ok
[INFO] [stdout] test degree::tests::a_routine_that_loops_once_grows_by_one ... ok
[INFO] [stdout] test rules::tests::a_definition_nothing_reaches_is_named ... ok
[INFO] [stdout] test rules::tests::the_same_call_twice_in_one_block_is_named_at_the_second ... ok
[INFO] [stdout] test degree::tests::a_name_defined_twice_is_called_by_nobody_rather_than_resolved_by_read_order ... ok
[INFO] [stdout] test degree::tests::a_routine_that_reaches_itself_is_refused_rather_than_given_a_depth ... ok
[INFO] [stdout] test tree::tests::a_definition_carries_the_name_it_defines ... ok
[INFO] [stdout] test degree::tests::a_helper_that_loops_makes_its_caller_quadratic_from_one_visible_loop ... ok
[INFO] [stdout] test tree::tests::a_call_that_runs_its_argument_per_element_is_a_repeat ... ok
[INFO] [stdout] test tree::tests::a_loop_keyword_inside_a_comment_opens_nothing ... ok
[INFO] [stdout] test tree::tests::unbalanced_delimiters_close_at_the_last_line_rather_than_panicking ... ok
[INFO] [stdout] test tree::tests::a_brace_inside_quoted_text_is_not_structure ... ok
[INFO] [stdout] test tree::tests::a_reported_line_is_the_line_in_the_file ... ok
[INFO] [stdout] test degree::tests::the_memoised_degree_is_the_degree_the_program_was_built_to_have ... ok
[INFO] [stdout] test degree::tests::the_order_the_files_arrive_in_does_not_change_any_answer ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/planted.rs (/opt/rustwide/target/release/deps/planted-33915afe5804e2b2)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test every_planted_defect_is_proven_at_the_exact_line_in_every_language ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_laws-aacb267b62f803eb)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test repair::tests::the_choice_matches_the_librarys_own_exhaustive_search ... ok
[INFO] [stdout] test repair::tests::a_cost_resting_on_nothing_is_counted_rather_than_weighed ... ok
[INFO] [stdout] test repair::tests::the_choice_matches_exhaustive_enumeration_under_every_ceiling ... ok
[INFO] [stdout] test repair::tests::the_selection_names_the_regions_that_argued_for_it ... ok
[INFO] [stdout] test repair::tests::findings_that_cost_nothing_are_refused_rather_than_ranked ... ok
[INFO] [stdout] test repair::tests::nothing_is_bought_beyond_the_margin ... ok
[INFO] [stdout] test repair::tests::the_budget_binds ... ok
[INFO] [stdout] test repair::tests::a_shakily_measured_cost_drags_the_trust_of_the_whole_plan_down ... ok
[INFO] [stdout] test repair::tests::a_plan_wider_than_the_terms_is_refused_rather_than_truncated ... ok
[INFO] [stdout] test repair::tests::a_chain_with_no_margin_selects_nothing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_repo-eded79db58368d0b)
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test plan::tests::spending_more_than_there_is_to_gain_stops ... ok
[INFO] [stdout] test dominate::tests::what_the_entry_cannot_reach_is_named ... ok
[INFO] [stdout] test unused::tests::a_commented_out_rule_is_not_a_rule ... ok
[INFO] [stdout] test plan::tests::the_margin_states_what_the_next_change_would_add ... ok
[INFO] [stdout] test dominate::tests::a_shared_component_is_retained_by_neither_route ... ok
[INFO] [stdout] test unused::tests::a_component_stops_at_the_next_component_so_nothing_is_read_twice ... ok
[INFO] [stdout] test dominate::tests::a_sole_route_retains_everything_behind_it ... ok
[INFO] [stdout] test npm::tests::resolution_prefers_the_nearest_install ... ok
[INFO] [stdout] test npm::tests::workspace_patterns_only_admit_directories_holding_a_manifest ... ok
[INFO] [stdout] test plan::tests::the_plan_never_pays_twice_for_nested_savings ... ok
[INFO] [stdout] test dominate::tests::a_cycle_does_not_hang_or_double_count ... ok
[INFO] [stdout] test plan::tests::a_budget_of_none_promises_nothing ... ok
[INFO] [stdout] test plan::tests::a_ranking_by_own_weight_would_have_chosen_worse ... ok
[INFO] [stdout] test plan::tests::one_change_takes_the_branch_that_holds_the_most ... ok
[INFO] [stdout] test npm::tests::development_dependencies_are_not_counted_as_shipped ... ok
[INFO] [stdout] test unused::tests::a_name_that_merely_contains_the_binding_is_not_a_read ... ok
[INFO] [stdout] test unused::tests::a_length_is_not_mistaken_for_a_class ... ok
[INFO] [stdout] test unused::tests::a_literal_read_is_certain ... ok
[INFO] [stdout] test unused::tests::a_rule_reports_the_bytes_deleting_it_removes ... ok
[INFO] [stdout] test unused::tests::a_selector_inside_an_at_rule_is_still_a_selector ... ok
[INFO] [stdout] test npm::tests::a_nested_install_is_not_weighed_into_its_host ... ok
[INFO] [stdout] test unused::tests::a_compound_selector_names_every_class_in_it ... ok
[INFO] [stdout] test unused::tests::the_binding_is_read_from_the_import ... ok
[INFO] [stdout] test unused::tests::a_computed_key_refuses_rather_than_guessing ... ok
[INFO] [stdout] test unused::tests::passing_the_binding_whole_refuses ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_source-372201fa606d2545)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test map::tests::a_generated_line_offers_every_original_line_the_map_leaves_room_for ... ok
[INFO] [stdout] test map::tests::a_source_root_is_prefixed_without_inventing_a_separator ... ok
[INFO] [stdout] test map::tests::a_row_is_searched_in_column_order_even_if_it_arrived_out_of_order ... ok
[INFO] [stdout] test map::tests::a_map_missing_what_it_needs_is_refused_rather_than_half_read ... ok
[INFO] [stdout] test map::tests::no_room_is_claimed_across_a_gap_the_map_says_nothing_about ... ok
[INFO] [stdout] test map::tests::an_index_map_is_flattened_to_one_table ... ok
[INFO] [stdout] test map::tests::no_room_is_claimed_across_a_jump_to_another_source ... ok
[INFO] [stdout] test package::tests::a_file_the_repository_owns_belongs_to_no_dependency ... ok
[INFO] [stdout] test tests::a_qualified_name_is_matched_by_its_last_segment ... ok
[INFO] [stdout] test package::tests::a_marker_with_nothing_under_it_names_nothing ... ok
[INFO] [stdout] test tests::a_map_is_looked_for_under_the_scripts_own_name ... ok
[INFO] [stdout] test package::tests::a_scoped_dependency_keeps_both_of_its_segments ... ok
[INFO] [stdout] test tests::two_lines_that_both_name_it_are_not_chosen_between ... ok
[INFO] [stdout] test tests::a_declaration_joined_to_its_comment_is_still_proved ... ok
[INFO] [stdout] test map::tests::the_shift_selects_which_line_is_first ... ok
[INFO] [stdout] test map::tests::a_position_resolves_to_the_original_line_and_its_text ... ok
[INFO] [stdout] test map::tests::vlq_decodes_the_signed_deltas_the_format_states ... ok
[INFO] [stdout] test package::tests::an_unscoped_dependency_takes_one_segment ... ok
[INFO] [stdout] test package::tests::the_innermost_install_wins ... ok
[INFO] [stdout] test package::tests::fixability_follows_from_whether_a_package_was_named ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/critpath_trace-eb3acd99822811ef)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test bind::tests::concurrent_work_is_never_ordered_by_where_it_sits ... ok
[INFO] [stdout] test bind::tests::nested_work_does_not_become_the_next_task ... ok
[INFO] [stdout] test bind::tests::separate_tracks_are_never_ordered_against_each_other ... ok
[INFO] [stdout] test tests::a_begin_without_an_end_is_censored_at_the_window_rather_than_dropped ... ok
[INFO] [stdout] test tests::an_interval_that_ends_before_it_starts_decides_nothing ... ok
[INFO] [stdout] test tests::an_end_with_nothing_open_is_a_hole ... ok
[INFO] [stdout] test tests::metadata_and_instants_are_understood_rather_than_counted_as_holes ... ok
[INFO] [stdout] test tests::the_wrapping_object_and_the_bare_array_read_alike ... ok
[INFO] [stdout] test tests::an_unknown_phase_is_counted_rather_than_ignored ... ok
[INFO] [stdout] test tests::a_complete_event_becomes_one_activity ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_graph
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_grow
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_laws
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_repo
[INFO] [stderr]    Doc-tests critpath_source
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests critpath_trace
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e", kill_on_drop: false }`
[INFO] [stdout] f2ae2eea6332295ad410049cfa060801d4cafddce8f9032ccb4f763ba1841e2e
