[INFO] crate ruler 0.1.0 is already in cache
[INFO] testing ruler-0.1.0 against master#8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e for pr-74409
[INFO] extracting crate ruler 0.1.0 into /workspace/builds/worker-14/source
[INFO] validating manifest of crates.io crate ruler 0.1.0 on toolchain 8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ruler 0.1.0
[INFO] finished tweaking crates.io crate ruler 0.1.0
[INFO] tweaked toml for crates.io crate ruler 0.1.0 written to /workspace/builds/worker-14/source/Cargo.toml
[INFO] crate crates.io crate ruler 0.1.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "fetch" "--locked" "--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-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:d9fbb4e2287ab3795bdefaf705efb7541a8875c13bce71e067d6b01dc5ed759b" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 75a409b6895a965d22b0fbb355ce756076575fcb658983a11268d5fa7c214027
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "75a409b6895a965d22b0fbb355ce756076575fcb658983a11268d5fa7c214027", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "75a409b6895a965d22b0fbb355ce756076575fcb658983a11268d5fa7c214027", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "75a409b6895a965d22b0fbb355ce756076575fcb658983a11268d5fa7c214027", kill_on_drop: false }`
[INFO] [stdout] 75a409b6895a965d22b0fbb355ce756076575fcb658983a11268d5fa7c214027
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:d9fbb4e2287ab3795bdefaf705efb7541a8875c13bce71e067d6b01dc5ed759b" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8c442df567e60f6b4e6bcdd916d841fcdc11558345a1113d1d24efc21698407d
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "8c442df567e60f6b4e6bcdd916d841fcdc11558345a1113d1d24efc21698407d", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v0.1.6
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling serde_derive v1.0.114
[INFO] [stderr]    Compiling serde v1.0.114
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling gcc v0.3.55
[INFO] [stderr]    Compiling byteorder v1.3.2
[INFO] [stderr]    Compiling unicode-width v0.1.6
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling base64 v0.12.3
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling rand v0.4.6
[INFO] [stderr]    Compiling atty v0.2.13
[INFO] [stderr]    Compiling time v0.1.42
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling quote v1.0.2
[INFO] [stderr]    Compiling rand v0.3.23
[INFO] [stderr]    Compiling rust-crypto v0.2.36
[INFO] [stderr]    Compiling lipsum v0.6.0
[INFO] [stderr]    Compiling multimap v0.8.1
[INFO] [stderr]    Compiling bincode v1.3.1
[INFO] [stderr]    Compiling ruler v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 48.99s
[INFO] running `Command { std: "docker" "inspect" "8c442df567e60f6b4e6bcdd916d841fcdc11558345a1113d1d24efc21698407d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8c442df567e60f6b4e6bcdd916d841fcdc11558345a1113d1d24efc21698407d", kill_on_drop: false }`
[INFO] [stdout] 8c442df567e60f6b4e6bcdd916d841fcdc11558345a1113d1d24efc21698407d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:d9fbb4e2287ab3795bdefaf705efb7541a8875c13bce71e067d6b01dc5ed759b" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2cdb4f96ebc2aa9c825dc9d4793b4c1630fde715979728702f2aacf735419663
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "2cdb4f96ebc2aa9c825dc9d4793b4c1630fde715979728702f2aacf735419663", kill_on_drop: false }`
[INFO] [stderr]    Compiling ruler v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 9.78s
[INFO] running `Command { std: "docker" "inspect" "2cdb4f96ebc2aa9c825dc9d4793b4c1630fde715979728702f2aacf735419663", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2cdb4f96ebc2aa9c825dc9d4793b4c1630fde715979728702f2aacf735419663", kill_on_drop: false }`
[INFO] [stdout] 2cdb4f96ebc2aa9c825dc9d4793b4c1630fde715979728702f2aacf735419663
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:d9fbb4e2287ab3795bdefaf705efb7541a8875c13bce71e067d6b01dc5ed759b" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 3cf3ed0ca96279074bdb885165807c8b470fd6f7d391f997af667ca4a4571615
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "3cf3ed0ca96279074bdb885165807c8b470fd6f7d391f997af667ca4a4571615", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/ruler-019ca3a93e0a96d8
[INFO] [stdout] running 118 tests
[INFO] [stdout] test cache::test::back_up_nonexistent_file ... ok
[INFO] [stdout] test memory::test::round_trip_history ... ok
[INFO] [stdout] test memory::test::add_remove_rules ... ok
[INFO] [stdout] test memory::test::round_trip_memory ... ok
[INFO] [stdout] test memory::test::insert_remove_target_history ... ok
[INFO] [stdout] test memory::test::history_of_unknown_file_empty ... ok
[INFO] [stdout] test rule::tests::get_line_endpoints_no_newline_at_end ... ok
[INFO] [stdout] test cache::test::back_up_and_restore ... ok
[INFO] [stdout] test cache::test::restore_nonexistent_file ... ok
[INFO] [stdout] test rule::tests::get_line_endpoints_two ... ok
[INFO] [stdout] test cache::test::restore_file_from_nonexistent_cache ... ok
[INFO] [stdout] test memory::test::round_trip_memory_through_file ... ok
[INFO] [stdout] test memory::test::round_trip_memory_through_file_to_from ... ok
[INFO] [stdout] test rule::tests::get_line_endpoints_empty ... ok
[INFO] [stdout] test rule::tests::parse_empty ... ok
[INFO] [stdout] test rule::tests::parse_two ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_command1 ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_sources2 ... ok
[INFO] [stdout] test rule::tests::parse_one ... ok
[INFO] [stdout] test build::test::build_basic ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_command3 ... ok
[INFO] [stdout] test rule::tests::parse_extra_newline_error1 ... ok
[INFO] [stdout] test rule::tests::parse_extra_newline_error2 ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_sources3 ... ok
[INFO] [stdout] test cache::test::back_up_twice_and_restore ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_targets2 ... ok
[INFO] [stdout] test rule::tests::parse_extra_newline_error3 ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_targets1 ... ok
[INFO] [stdout] test rule::tests::rules_to_frame_buffer_empty_to_empty ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_sources1 ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_targets3 ... ok
[INFO] [stdout] test rule::tests::rules_to_frame_buffer_redundancy_error ... ok
[INFO] [stdout] test rule::tests::rules_to_frame_buffer_two_to_two ... ok
[INFO] [stdout] test rule::tests::parse_unexpected_eof_mid_command2 ... ok
[INFO] [stdout] test rule::tests::split_along_endpoints_one ... ok
[INFO] [stdout] test rule::tests::split_along_endpoints_rule ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_empty_is_empty ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_disconnected_graph ... ok
[INFO] [stdout] test rule::tests::get_line_endpoints_rule ... ok
[INFO] [stdout] test rule::tests::split_along_endpoints_two ... ok
[INFO] [stdout] test rule::tests::split_along_endpoints_empty ... ok
[INFO] [stdout] test rule::tests::split_along_endpoints_two_padding ... ok
[INFO] [stdout] test rule::tests::get_line_endpoints_one ... ok
[INFO] [stdout] test rule::tests::rules_are_rules ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_four_rules_diamond_scrambled ... ok
[INFO] [stdout] test rule::tests::rules_to_frame_buffer_one_to_one ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_poem_scrambled ... ok
[INFO] [stdout] test rule::tests::topological_sort_poem_scrambled ... ok
[INFO] [stdout] test system::fake::test::content_borrows_mut_and_change ... ok
[INFO] [stdout] test rule::tests::topological_sort_four_rules_diamond_already_in_order ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_one_rule ... ok
[INFO] [stdout] test rule::tests::topological_sort_empty_is_error ... ok
[INFO] [stdout] test rule::tests::topological_sort_four_rules_diamond_scrambled ... ok
[INFO] [stdout] test rule::tests::topological_sort_all_two_rules ... ok
[INFO] [stdout] test system::fake::test::content_clone_points_to_same_data ... ok
[INFO] [stdout] test rule::tests::topological_sort_self_reference ... ok
[INFO] [stdout] test rule::tests::topological_sort_circular ... ok
[INFO] [stdout] test system::fake::test::add_remove_file ... ok
[INFO] [stdout] test system::fake::test::add_remove_dir ... ok
[INFO] [stdout] test system::fake::test::dir_is_dir ... ok
[INFO] [stdout] test system::fake::test::content_borrows_with_star ... ok
[INFO] [stdout] test rule::tests::topological_sort_two_rules ... ok
[INFO] [stdout] test system::fake::test::get_components_general ... ok
[INFO] [stdout] test system::fake::test::modified_timestamps ... ok
[INFO] [stdout] test system::fake::test::remove_non_existent_dir_errors ... ok
[INFO] [stdout] test system::fake::test::remove_non_existent_file_errors ... ok
[INFO] [stdout] test system::fake::test::rename_directory ... ok
[INFO] [stdout] test system::fake::test::rename_file ... ok
[INFO] [stdout] test system::fake::test::system_add_remove_dir ... ok
[INFO] [stdout] test system::fake::test::system_add_remove_file ... ok
[INFO] [stdout] test system::fake::test::system_create_file_write_read_round_trip ... ok
[INFO] [stdout] test system::fake::test::system_create_file_write_read_round_trip_read_twice ... ok
[INFO] [stdout] test system::fake::test::system_create_file_write_read_round_trip_with_cloning ... ok
[INFO] [stdout] test system::fake::test::system_rename_file ... ok
[INFO] [stdout] test system::fake::test::executing_mycat2_concatinates_and_dupes ... ok
[INFO] [stdout] test system::fake::test::use_commandline_to_remove ... ok
[INFO] [stdout] test system::fake::test::writing_updates_modified_timestamp ... ok
[INFO] [stdout] test system::fake::test::non_existent_child_is_not_file_or_dir ... ok
[INFO] [stdout] test system::fake::test::get_dir_path_and_name_two ... ok
[INFO] [stdout] test ticket::test::ticket_factory_file ... ok
[INFO] [stdout] test ticket::test::ticket_factory_hashes_bigger_file ... ok
[INFO] [stdout] test system::fake::test::get_dir_path_and_name_one ... ok
[INFO] [stdout] test ticket::test::ticket_factory_string ... ok
[INFO] [stdout] test rule::tests::topological_sort_make_nodes_for_sources ... ok
[INFO] [stdout] test ticket::test::ticket_from_string_from_new ... ok
[INFO] [stdout] test rule::tests::topological_sort_one_rule ... ok
[INFO] [stdout] test ticket::test::ticket_factory_hashes ... ok
[INFO] [stdout] test rule::tests::topological_sort_poem ... ok
[INFO] [stdout] test work::test::do_empty_command ... ok
[INFO] [stdout] test ticket::test::ticket_from_string_more ... ok
[INFO] [stdout] test ticket::test::ticket_serialize_round_trip ... ok
[INFO] [stdout] test system::fake::test::executing_error_gives_error_output ... ok
[INFO] [stdout] test system::fake::test::get_dir_path_and_name_three ... ok
[INFO] [stdout] test work::test::command_fails_to_generate_target ... ok
[INFO] [stdout] test system::fake::test::executing_mycat_concatinates ... ok
[INFO] [stdout] test system::fake::test::get_dir_path_and_name_zero ... ok
[INFO] [stdout] test system::fake::test::new_empty_file_is_file ... ok
[INFO] [stdout] test build::test::build_with_missing_source ... ok
[INFO] [stdout] test work::test::one_dependence_intermediate_already_present ... ok
[INFO] [stdout] test system::fake::test::file_is_file ... ok
[INFO] [stdout] test work::test::file_not_there ... ok
[INFO] [stdout] test work::test::one_target_not_there_error_in_command ... ok
[INFO] [stdout] test work::test::one_dependence_only ... ok
[INFO] [stdout] test work::test::one_target_correct_hash_incorrect_timestamp ... ok
[INFO] [stdout] test work::test::work_get_tickets_from_filesystem ... ok
[INFO] [stdout] test work::test::command_errors ... ok
[INFO] [stdout] test work::test::work_get_tickets_from_history ... ok
[INFO] [stdout] test work::test::target_removed_by_command ... ok
[INFO] [stdout] test work::test::one_dependence_with_error ... ok
[INFO] [stdout] test work::test::poem_already_correct ... ok
[INFO] [stdout] test work::test::two_targets_one_already_present ... ok
[INFO] [stdout] test work::test::poem_contradicts_history ... ok
[INFO] [stdout] test work::test::two_targets_both_not_there ... ok
[INFO] [stdout] test work::test::wait_for_channels ... ok
[INFO] [stdout] test work::test::poem_concatination ... ok
[INFO] [stdout] test work::test::one_target_already_correct_according_to_timestamp ... ok
[INFO] [stdout] test work::test::one_target_already_correct_only ... ok
[INFO] [stdout] test build::test::build_change_build_check_cache ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 118 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "3cf3ed0ca96279074bdb885165807c8b470fd6f7d391f997af667ca4a4571615", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3cf3ed0ca96279074bdb885165807c8b470fd6f7d391f997af667ca4a4571615", kill_on_drop: false }`
[INFO] [stdout] 3cf3ed0ca96279074bdb885165807c8b470fd6f7d391f997af667ca4a4571615