[INFO] fetching crate dprint-core 0.46.1...
[INFO] testing dprint-core-0.46.1 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate dprint-core 0.46.1 into /workspace/builds/worker-12/source
[INFO] validating manifest of crates.io crate dprint-core 0.46.1 on toolchain beta-2021-09-08
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate dprint-core 0.46.1
[INFO] finished tweaking crates.io crate dprint-core 0.46.1
[INFO] tweaked toml for crates.io crate dprint-core 0.46.1 written to /workspace/builds/worker-12/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bedae2e79b5e1438f749818dc7da05f01549547c67ebf409cecea8e4617186f1
[INFO] running `Command { std: "docker" "start" "-a" "bedae2e79b5e1438f749818dc7da05f01549547c67ebf409cecea8e4617186f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bedae2e79b5e1438f749818dc7da05f01549547c67ebf409cecea8e4617186f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bedae2e79b5e1438f749818dc7da05f01549547c67ebf409cecea8e4617186f1", kill_on_drop: false }`
[INFO] [stdout] bedae2e79b5e1438f749818dc7da05f01549547c67ebf409cecea8e4617186f1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] dfda6976d46f26a158ffd1d29cb930ac98e186a773fbbd46615d5f5c90b093db
[INFO] running `Command { std: "docker" "start" "-a" "dfda6976d46f26a158ffd1d29cb930ac98e186a773fbbd46615d5f5c90b093db", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling proc-macro2 v1.0.29
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.76
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling bumpalo v3.7.0
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling dprint-core v0.46.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.35s
[INFO] running `Command { std: "docker" "inspect" "dfda6976d46f26a158ffd1d29cb930ac98e186a773fbbd46615d5f5c90b093db", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dfda6976d46f26a158ffd1d29cb930ac98e186a773fbbd46615d5f5c90b093db", kill_on_drop: false }`
[INFO] [stdout] dfda6976d46f26a158ffd1d29cb930ac98e186a773fbbd46615d5f5c90b093db
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5c115a0a9a293d851137d95baf38d167b6a101100c4203ed51002278a652510f
[INFO] running `Command { std: "docker" "start" "-a" "5c115a0a9a293d851137d95baf38d167b6a101100c4203ed51002278a652510f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling dprint-core v0.46.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.69s
[INFO] running `Command { std: "docker" "inspect" "5c115a0a9a293d851137d95baf38d167b6a101100c4203ed51002278a652510f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c115a0a9a293d851137d95baf38d167b6a101100c4203ed51002278a652510f", kill_on_drop: false }`
[INFO] [stdout] 5c115a0a9a293d851137d95baf38d167b6a101100c4203ed51002278a652510f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a434a4c7056920c48cbaca2bf06a85be11ed2f5bb84048c635bf26f8a32e5198
[INFO] running `Command { std: "docker" "start" "-a" "a434a4c7056920c48cbaca2bf06a85be11ed2f5bb84048c635bf26f8a32e5198", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.55s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/dprint_core-c75a1bdc422aaf5e)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test configuration::test::add_diagnostic_for_renamed_property ... ok
[INFO] [stdout] test configuration::test::add_diagnostic_for_renamed_property_when_already_exists ... ok
[INFO] [stdout] test configuration::test::get_default_config_when_empty ... ok
[INFO] [stdout] test configuration::test::get_diagnostic_for_excess_property ... ok
[INFO] [stdout] test configuration::test::get_diagnostic_for_invalid_enum_config ... ok
[INFO] [stdout] test configuration::test::get_diagnostic_for_invalid_primitive ... ok
[INFO] [stdout] test configuration::test::get_values_when_filled ... ok
[INFO] [stdout] test configuration::test::no_diagnostic_for_excess_property_when_check_false ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_display_when_there_are_three_lines ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_column_for_first_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_column_for_second_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_column_for_start_of_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_column_for_start_of_text ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_last_line_when_above_length ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_line_number_of_single_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_line_when_at_first_pos_on_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_line_when_at_last_pos_on_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_range_highlight_for_full_text_multi_lines ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_range_highlight_for_full_text_one_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_range_highlight_on_multi_lines_within ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_range_highlight_on_one_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_get_range_highlight_on_second_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_handle_multi_byte_characters_on_the_first_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_handle_multi_byte_characters_on_the_second_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_ignore_when_there_are_more_than_three_lines ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_show_only_ten_chars_of_last_line ... ok
[INFO] [stdout] test formatting::utils::string_utils::tests::should_show_only_twenty_chars_of_first_line ... ok
[INFO] [stdout] test formatting::writer::test::markexpectnewline_writesnewline ... ok
[INFO] [stdout] test formatting::writer::test::write_indented_writes ... ok
[INFO] [stdout] test formatting::writer::test::write_multiple_lines_writes ... ok
[INFO] [stdout] test formatting::writer::test::write_singleword_writes ... ok
[INFO] [stdout] test formatting::writer::test::write_singleindent_writes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/sample_example_test.rs (/opt/rustwide/target/debug/deps/sample_example_test-1164c8dcc9629812)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test it_formats_as_multi_line_when_first_item_on_different_line_than_expr ... ok
[INFO] [stdout] test it_formats_as_multi_line_when_items_exceed_print_width ... ok
[INFO] [stdout] test it_formats_as_single_line_when_exceeding_print_width_with_only_one_item ... ok
[INFO] [stdout] test it_formats_when_does_not_exceed_line ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tracing_test.rs (/opt/rustwide/target/debug/deps/tracing_test-b4f4b149910d1474)
[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 dprint-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] running `Command { std: "docker" "inspect" "a434a4c7056920c48cbaca2bf06a85be11ed2f5bb84048c635bf26f8a32e5198", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a434a4c7056920c48cbaca2bf06a85be11ed2f5bb84048c635bf26f8a32e5198", kill_on_drop: false }`
[INFO] [stdout] a434a4c7056920c48cbaca2bf06a85be11ed2f5bb84048c635bf26f8a32e5198