[INFO] fetching crate mahler 0.19.6...
[INFO] testing mahler-0.19.6 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1
[INFO] extracting crate mahler 0.19.6 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate mahler 0.19.6
[INFO] finished tweaking crates.io crate mahler 0.19.6
[INFO] tweaked toml for crates.io crate mahler 0.19.6 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate mahler 0.19.6 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mahler 0.19.6 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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded dedent v0.1.1
[INFO] [stderr]   Downloaded matchit v0.8.6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5fc136b736f9f2c5cd4b50912bd9832f02fc50a8d3b5ccc064121533fba53d78
[INFO] running `Command { std: "docker" "start" "-a" "5fc136b736f9f2c5cd4b50912bd9832f02fc50a8d3b5ccc064121533fba53d78", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5fc136b736f9f2c5cd4b50912bd9832f02fc50a8d3b5ccc064121533fba53d78", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5fc136b736f9f2c5cd4b50912bd9832f02fc50a8d3b5ccc064121533fba53d78", kill_on_drop: false }`
[INFO] [stdout] 5fc136b736f9f2c5cd4b50912bd9832f02fc50a8d3b5ccc064121533fba53d78
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fcae2d312c0d446b81c639ae7642b587b1748b766d406ce3fdf43a826c4e0c5d
[INFO] running `Command { std: "docker" "start" "-a" "fcae2d312c0d446b81c639ae7642b587b1748b766d406ce3fdf43a826c4e0c5d", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.143
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling matchit v0.8.6
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tracing-attributes v0.1.30
[INFO] [stderr]    Compiling thiserror-impl v2.0.16
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling thiserror v2.0.16
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling jsonptr v0.7.1
[INFO] [stderr]    Compiling json-patch v4.0.0
[INFO] [stderr]    Compiling mahler v0.19.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 21.07s
[INFO] running `Command { std: "docker" "inspect" "fcae2d312c0d446b81c639ae7642b587b1748b766d406ce3fdf43a826c4e0c5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fcae2d312c0d446b81c639ae7642b587b1748b766d406ce3fdf43a826c4e0c5d", kill_on_drop: false }`
[INFO] [stdout] fcae2d312c0d446b81c639ae7642b587b1748b766d406ce3fdf43a826c4e0c5d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8ab638291af23a1ae55d75347d79e0d11f95f00d7daaeb18d2f117a29d685f11
[INFO] running `Command { std: "docker" "start" "-a" "8ab638291af23a1ae55d75347d79e0d11f95f00d7daaeb18d2f117a29d685f11", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.6
[INFO] [stderr]    Compiling regex-syntax v0.6.29
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling dedent v0.1.1
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling regex-automata v0.4.10
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling matchers v0.1.0
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling regex v1.11.2
[INFO] [stderr]    Compiling tokio-test v0.4.4
[INFO] [stderr]    Compiling mahler v0.19.6 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stdout] warning: struct `Counters` is never constructed
[INFO] [stdout]    --> src/planner/mod.rs:540:12
[INFO] [stdout]     |
[INFO] [stdout] 540 |     struct Counters(HashMap<String, i32>);
[INFO] [stdout]     |            ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 25.93s
[INFO] running `Command { std: "docker" "inspect" "8ab638291af23a1ae55d75347d79e0d11f95f00d7daaeb18d2f117a29d685f11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ab638291af23a1ae55d75347d79e0d11f95f00d7daaeb18d2f117a29d685f11", kill_on_drop: false }`
[INFO] [stdout] 8ab638291af23a1ae55d75347d79e0d11f95f00d7daaeb18d2f117a29d685f11
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d72e6c19b886839ba0b325ad860b0f1dac9880d6bb8a7e72df341dd0f7e93f6b
[INFO] running `Command { std: "docker" "start" "-a" "d72e6c19b886839ba0b325ad860b0f1dac9880d6bb8a7e72df341dd0f7e93f6b", kill_on_drop: false }`
[INFO] [stderr] warning: struct `Counters` is never constructed
[INFO] [stderr]    --> src/planner/mod.rs:540:12
[INFO] [stderr]     |
[INFO] [stderr] 540 |     struct Counters(HashMap<String, i32>);
[INFO] [stderr]     |            ^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `mahler` (lib test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mahler-c34acad2e5230b84)
[INFO] [stdout] 
[INFO] [stdout] running 142 tests
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_key_error_multiple ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_key_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_index_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_seq ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_string_parse ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_wrong_tuple_length ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_single_value ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_string_string ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_struct_ignoring_additional_fields ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_tuple_ignoring_additional_fields ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_struct ... ok
[INFO] [stdout] test extract::args::tests::deserializes_hashmap_args ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_unsupported_key_type ... ok
[INFO] [stdout] test extract::args::tests::deserializes_tuple_args ... ok
[INFO] [stdout] test extract::view::tests::it_creates_a_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::view::tests::it_allows_changing_a_value_with_a_view ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_a_value_from_the_end_of_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_a_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::args::de::tests::test_unsupported_type_error_nested_data_structure ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_an_existing_value_with_optional_view ... ok
[INFO] [stdout] test path::tests::it_converts_a_str_to_a_path ... ok
[INFO] [stdout] test extract::view::tests::it_extracts_an_existing_value_using_optional_view ... ok
[INFO] [stdout] test extract::view::tests::it_assigns_a_value_to_optional_view_path ... ok
[INFO] [stdout] test planner::domain::tests::it_constructs_a_path_given_arguments ... ok
[INFO] [stdout] test path::tests::it_replaces_existing_key_with_insert ... ok
[INFO] [stdout] test path::tests::it_sorts_path_by_length_and_then_lexicographically ... ok
[INFO] [stdout] test path::tests::test_is_prefix_of ... ok
[INFO] [stdout] test planner::distance::tests::it_calculates_possible_changes_to_target ... ok
[INFO] [stdout] test extract::view::tests::it_initializes_optional_view_with_default ... ok
[INFO] [stdout] test planner::domain::tests::test_escaped_parameters_remain ... ok
[INFO] [stdout] test path::tests::it_converts_a_path_to_string ... ok
[INFO] [stdout] test planner::domain::tests::test_finds_jobs_for_empty_paths ... ignored
[INFO] [stdout] test planner::domain::tests::test_mixed_placeholders ... ok
[INFO] [stdout] test planner::domain::tests::test_wildcard_parameter_replacement ... ok
[INFO] [stdout] test planner::tests::it_calculates_a_linear_workflow ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_map ... ok
[INFO] [stdout] test planner::tests::it_calculates_a_linear_workflow_on_a_complex_state ... ok
[INFO] [stdout] test extract::args::de::tests::test_wrong_number_of_parameters_error ... ok
[INFO] [stdout] test planner::tests::it_calculates_concurrent_workflows_from_non_conflicting_paths ... ok
[INFO] [stdout] test extract::args::tests::deserializes_simple_path_args ... ok
[INFO] [stdout] test planner::tests::it_aborts_search_if_plan_length_grows_too_much ... ok
[INFO] [stdout] test extract::view::tests::it_extracts_an_existing_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test planner::tests::it_ignores_none_jobs ... ok
[INFO] [stdout] test path::tests::it_panics_on_an_invalid_path - should panic ... ok
[INFO] [stdout] test planner::tests::test_array_element_conflicts ... ok
[INFO] [stdout] test extract::view::tests::it_fails_to_initialize_view_if_path_does_not_exist ... ok
[INFO] [stdout] test extract::view::tests::it_fails_if_optional_view_path_is_invalid ... ok
[INFO] [stdout] test planner::domain::tests::test_error_if_unmatched_placeholders_remain ... ok
[INFO] [stdout] test planner::tests::test_longest_common_prefix_empty ... ok
[INFO] [stdout] test planner::tests::test_longest_common_prefix_root_paths ... ok
[INFO] [stdout] test planner::tests::test_longest_common_prefix_single_path ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_no_later_prefix ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_prefix_first ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_root_path ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_with_conflicts ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_proper_path_prefix_vs_string_prefix ... ok
[INFO] [stdout] test planner::tests::test_longest_common_prefix_no_common_prefix ... ok
[INFO] [stdout] test planner::tests::test_longest_common_prefix_common_prefix ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_your_example ... ok
[INFO] [stdout] test task::effect::tests::it_allows_errors_in_sync_executions ... ok
[INFO] [stdout] test planner::domain::tests::it_fails_if_assigning_the_same_job_to_multiple_ops - should panic ... ok
[INFO] [stdout] test task::effect::tests::it_allows_sync_executions ... ok
[INFO] [stdout] test planner::domain::tests::test_no_replacement_if_job_not_found ... ok
[INFO] [stdout] test planner::tests::it_avoids_conflict_in_tasks_returned_from_methods ... ok
[INFO] [stdout] test task::effect::tests::it_allows_wrapping_a_value ... ok
[INFO] [stdout] test task::tests::it_allows_expanding_methods_with_result ... ok
[INFO] [stdout] test planner::tests::it_avoids_conflicts_from_methods ... ok
[INFO] [stdout] test planner::tests::it_calculates_a_linear_workflow_on_a_complex_state_with_compound_tasks ... ok
[INFO] [stdout] test task::tests::it_allows_to_describe_a_task ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_async_actions ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_actions_returning_error ... ok
[INFO] [stdout] test planner::tests::it_calculates_a_linear_workflow_on_a_complex_state_with_deep_compound_tasks ... ok
[INFO] [stdout] test task::tests::it_catches_condition_failure_in_methods_returning_option ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_pure_actions ... ok
[INFO] [stdout] test planner::domain::tests::it_fails_if_assigning_the_same_job_to_multiple_routes - should panic ... ok
[INFO] [stdout] test task::tests::it_catches_input_errors_in_method_expansions ... ok
[INFO] [stdout] test task::tests::it_gets_metadata_from_function ... ok
[INFO] [stdout] test task::tests::it_identifies_task_scoping_based_on_args ... ok
[INFO] [stdout] test task::tests::it_runs_async_actions ... ok
[INFO] [stdout] test task::tests::it_modifies_system_sub_elements ... ok
[INFO] [stdout] test worker::testing::tests::it_allows_testing_compound_tasks ... ok
[INFO] [stdout] test worker::testing::tests::it_allows_searching_for_workflow ... ok
[INFO] [stdout] test worker::testing::tests::it_allows_testing_atomic_tasks ... ok
[INFO] [stdout] test task::effect::tests::it_allows_async_executions ... ok
[INFO] [stdout] test task::effect::tests::it_propagates_errors_in_async_calls ... ok
[INFO] [stdout] test task::tests::it_allows_actions_returning_runtime_errors ... ok
[INFO] [stdout] test workflow::dag::tests::converts_complex_dag_to_string ... ok
[INFO] [stdout] test task::tests::it_allows_extending_actions_with_effect ... ok
[INFO] [stdout] test workflow::dag::tests::converts_numeric_dag_to_string ... ok
[INFO] [stdout] test workflow::dag::tests::converts_branching_dag_to_string ... ok
[INFO] [stdout] test workflow::dag::tests::it_executes_simple_dag ... ok
[INFO] [stdout] test workflow::dag::tests::modifying_a_clone_should_not_affect_the_original ... ok
[INFO] [stdout] test workflow::dag::tests::test_basic_concatenation_of_sequences ... ok
[INFO] [stdout] test workflow::dag::tests::test_basic_prepend ... ok
[INFO] [stdout] test workflow::dag::tests::test_clone_fork ... ok
[INFO] [stdout] test workflow::dag::tests::test_clone_deep_nested_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_clone_sequence ... ok
[INFO] [stdout] test workflow::dag::tests::test_concatenation_with_empty_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_contructing_forking_inverted_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_contructing_linear_inverted_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_construction ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_from_empty_list ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_from_list ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_from_single_branch ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_new_edge_cases ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_new_with_multiple_single_elements ... ok
[INFO] [stdout] test worker::tests::test_follow_stream_closes_on_worker_end ... ok
[INFO] [stdout] test workflow::dag::tests::test_concatenation_with_forked_empty_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_new_with_shared_nodes ... ok
[INFO] [stdout] test workflow::dag::tests::test_dag_seq_edge_cases ... ok
[INFO] [stdout] test workflow::dag::tests::test_empty_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_empty_dag_concatenation_preserves_tail ... ok
[INFO] [stdout] test workflow::dag::tests::test_error_interrupts_execution ... ok
[INFO] [stdout] test workflow::dag::tests::test_iterate_forked_graph ... ok
[INFO] [stdout] test workflow::dag::tests::test_iterate_linear_graph ... ok
[INFO] [stdout] test worker::tests::test_worker_follow_updates ... ok
[INFO] [stdout] test worker::tests::test_worker_complex_state ... ok
[INFO] [stdout] test workflow::dag::tests::test_prepend_with_forked_empty_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_empty_branches ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_empty_dag ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_multiple_sequential_sections ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_nested_forks ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_preserves_execution_semantics ... ok
[INFO] [stdout] test planner::tests::test_select_non_conflicting_prefer_prefixes_basic ... ok
[INFO] [stdout] test workflow::dag::tests::converts_linked_list_to_string ... ok
[INFO] [stdout] test workflow::dag::tests::test_empty_dag_string_representation ... ok
[INFO] [stdout] test planner::tests::it_calculates_a_linear_workflow_with_compound_tasks ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_single_item ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_dag_with_forks ... ok
[INFO] [stdout] test worker::tests::test_worker_interrupt_status ... ok
[INFO] [stdout] test workflow::dag::tests::test_reverse_three_way_fork ... ok
[INFO] [stdout] test planner::tests::it_finds_concurrent_plans_with_nested_forks ... ok
[INFO] [stdout] test planner::tests::test_stacking_problem ... ok
[INFO] [stdout] test planner::tests::it_finds_a_correct_path_through_backtracking ... ok
[INFO] [stdout] test workflow::dag::tests::test_interrupt_during_execution ... ok
[INFO] [stdout] test worker::tests::test_multiple_streams_receive_all_changes ... ok
[INFO] [stdout] test worker::tests::test_worker_bug ... ok
[INFO] [stdout] test workflow::dag::tests::test_concurrent_execution ... ok
[INFO] [stdout] test worker::tests::test_worker_follow_best_effort_loss ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 141 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 1.29s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests mahler
[INFO] [stdout] 
[INFO] [stdout] running 57 tests
[INFO] [stdout] test src/extract/target.rs - extract::target::Target (line 43) - compile ... ok
[INFO] [stdout] test src/extract/system.rs - extract::system::System (line 16) - compile ... ok
[INFO] [stdout] test src/extract/res.rs - extract::res::Res (line 18) - compile ... ok
[INFO] [stdout] test src/extract/mod.rs - extract (line 9) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 113) - compile ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::Target (line 16) - compile ... ok
[INFO] [stdout] test src/extract/mod.rs - extract (line 79) - compile ... ok
[INFO] [stdout] test src/extract/path.rs - extract::path::Path (line 11) - compile ... ok
[INFO] [stdout] test src/extract/args/mod.rs - extract::args::Args (line 20) - compile ... ok
[INFO] [stdout] test src/extract/mod.rs - extract (line 32) ... ok
[INFO] [stdout] test src/extract/res.rs - extract::res::Res (line 51) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 30) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 255) ... ok
[INFO] [stdout] test src/lib.rs - (line 149) ... ok
[INFO] [stdout] test src/extract/view.rs - extract::view::View (line 29) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 230) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::and_then (line 96) ... ok
[INFO] [stdout] test src/lib.rs - (line 90) ... ok
[INFO] [stdout] test src/lib.rs - (line 395) - compile ... ok
[INFO] [stdout] test src/task/io.rs - task::io::with_io (line 215) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::map (line 69) ... ok
[INFO] [stdout] test src/task/handler.rs - task::handler::Handler::with_target (line 47) ... ok
[INFO] [stdout] test src/lib.rs - (line 187) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 166) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 318) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E> (line 173) ... ok
[INFO] [stdout] test src/lib.rs - (line 287) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO (line 35) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 227) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::map_err (line 126) ... ok
[INFO] [stdout] test src/task/mod.rs - task::Task::with_target (line 302) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 243) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,S,I>::resource (line 331) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Ready,I>::follow (line 598) - compile ... ok
[INFO] [stdout] test src/task/mod.rs - task::Task::with_arg (line 316) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized,I>::jobs (line 412) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,S,I>::use_resource (line 357) ... ok
[INFO] [stdout] test src/task/handler.rs - task::handler::Handler::with_arg (line 64) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,S,I>::resource (line 308) ... ok
[INFO] [stdout] test src/task/job.rs - task::job::Job::with_description (line 94) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag<T>::is_empty (line 542) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag<T>::seq (line 510) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,S,I>::use_resource (line 380) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag (line 145) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag (line 194) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag (line 275) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::dag (line 895) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag (line 234) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag<T>::new (line 448) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag (line 111) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag<T> (line 779) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::seq (line 877) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::par (line 913) ... ok
[INFO] [stdout] test src/workflow/interrupt.rs - workflow::interrupt::Interrupt (line 19) ... ok
[INFO] [stdout] test src/workflow/dag.rs - workflow::dag::Dag<T>::shallow_clone (line 634) ... ok
[INFO] [stdout] test src/worker/testing.rs - worker::testing::Worker<O,S,I>::find_workflow (line 46) ... ok
[INFO] [stdout] test src/worker/testing.rs - worker::testing::Worker<O,S,I>::run_task (line 137) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.55s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "d72e6c19b886839ba0b325ad860b0f1dac9880d6bb8a7e72df341dd0f7e93f6b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d72e6c19b886839ba0b325ad860b0f1dac9880d6bb8a7e72df341dd0f7e93f6b", kill_on_drop: false }`
[INFO] [stdout] d72e6c19b886839ba0b325ad860b0f1dac9880d6bb8a7e72df341dd0f7e93f6b