[INFO] fetching crate fcp-core 0.1.1...
[INFO] testing fcp-core-0.1.1 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate fcp-core 0.1.1 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate fcp-core 0.1.1
[INFO] finished tweaking crates.io crate fcp-core 0.1.1
[INFO] tweaked toml for crates.io crate fcp-core 0.1.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate fcp-core 0.1.1 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate fcp-core 0.1.1 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] be727731825a94da36d610d84bfa14e84b4bb6e2a85a33037a37d05247660e9d
[INFO] running `Command { std: "docker" "start" "-a" "be727731825a94da36d610d84bfa14e84b4bb6e2a85a33037a37d05247660e9d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "be727731825a94da36d610d84bfa14e84b4bb6e2a85a33037a37d05247660e9d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "be727731825a94da36d610d84bfa14e84b4bb6e2a85a33037a37d05247660e9d", kill_on_drop: false }`
[INFO] [stdout] be727731825a94da36d610d84bfa14e84b4bb6e2a85a33037a37d05247660e9d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 510bbf311714d0c0ac42435bd131e2288889dc3e98c28b738f6549e4fa59fed2
[INFO] running `Command { std: "docker" "start" "-a" "510bbf311714d0c0ac42435bd131e2288889dc3e98c28b738f6549e4fa59fed2", kill_on_drop: false }`
[INFO] [stderr]    Compiling fcp-core v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.51s
[INFO] running `Command { std: "docker" "inspect" "510bbf311714d0c0ac42435bd131e2288889dc3e98c28b738f6549e4fa59fed2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "510bbf311714d0c0ac42435bd131e2288889dc3e98c28b738f6549e4fa59fed2", kill_on_drop: false }`
[INFO] [stdout] 510bbf311714d0c0ac42435bd131e2288889dc3e98c28b738f6549e4fa59fed2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9c3a9359d53cc9cc8587696ddacaf6118fe4d8ff5c6553649fb20a61109d0b5c
[INFO] running `Command { std: "docker" "start" "-a" "9c3a9359d53cc9cc8587696ddacaf6118fe4d8ff5c6553649fb20a61109d0b5c", kill_on_drop: false }`
[INFO] [stderr]    Compiling fcp-core v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.24s
[INFO] running `Command { std: "docker" "inspect" "9c3a9359d53cc9cc8587696ddacaf6118fe4d8ff5c6553649fb20a61109d0b5c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9c3a9359d53cc9cc8587696ddacaf6118fe4d8ff5c6553649fb20a61109d0b5c", kill_on_drop: false }`
[INFO] [stdout] 9c3a9359d53cc9cc8587696ddacaf6118fe4d8ff5c6553649fb20a61109d0b5c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bb97b46a57a8c1eb48900f4f1a2747e7a25a81f374bbd4a4c6aeec34268f3206
[INFO] running `Command { std: "docker" "start" "-a" "bb97b46a57a8c1eb48900f4f1a2747e7a25a81f374bbd4a4c6aeec34268f3206", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fcp_core-c160fd0aed28e2b5)
[INFO] [stdout] 
[INFO] [stdout] running 92 tests
[INFO] [stdout] test event_log::tests::test_cursor_advances_on_append ... ok
[INFO] [stdout] test event_log::tests::test_checkpoint_unknown_name ... ok
[INFO] [stdout] test event_log::tests::test_cursor_moves_back_on_undo ... ok
[INFO] [stdout] test event_log::tests::test_can_undo ... ok
[INFO] [stdout] test event_log::tests::test_checkpoint_removed_on_truncation ... ok
[INFO] [stdout] test event_log::tests::test_can_redo ... ok
[INFO] [stdout] test event_log::tests::test_checkpoint_undo_to ... ok
[INFO] [stdout] test event_log::tests::test_append_and_cursor ... ok
[INFO] [stdout] test event_log::tests::test_cursor_starts_at_zero ... ok
[INFO] [stdout] test event_log::tests::test_recent ... ok
[INFO] [stdout] test event_log::tests::test_cursor_moves_forward_on_redo ... ok
[INFO] [stdout] test event_log::tests::test_redo_empty ... ok
[INFO] [stdout] test event_log::tests::test_redo_skips_checkpoints ... ok
[INFO] [stdout] test event_log::tests::test_truncate_on_append ... ok
[INFO] [stdout] test event_log::tests::test_undo_empty ... ok
[INFO] [stdout] test event_log::tests::test_redo ... ok
[INFO] [stdout] test event_log::tests::test_undo_skips_checkpoints ... ok
[INFO] [stdout] test event_log::tests::test_undo_multiple ... ok
[INFO] [stdout] test formatter::tests::test_format_result_prefix_conventions ... ok
[INFO] [stdout] test formatter::tests::test_format_result_error_ignores_prefix ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_arrows_as_positionals ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_empty_input ... ok
[INFO] [stdout] test formatter::tests::test_suggest ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_lowercases_verb ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_mixed_types ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_selectors ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_preserves_raw ... ok
[INFO] [stdout] test formatter::tests::test_format_result_success_without_prefix ... ok
[INFO] [stdout] test formatter::tests::test_suggest_empty_candidates ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_terraform_style ... ok
[INFO] [stdout] test formatter::tests::test_format_result_success_with_prefix ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_key_value_params ... ok
[INFO] [stdout] test session::tests::test_session_close_placeholder ... ok
[INFO] [stdout] test session::tests::test_session_new_clears_file_path ... ok
[INFO] [stdout] test session::tests::test_session_new_resets_log ... ok
[INFO] [stdout] test session::tests::test_session_new_with_title ... ok
[INFO] [stdout] test session::tests::test_session_empty_action ... ok
[INFO] [stdout] test session::tests::test_session_new_default_title ... ok
[INFO] [stdout] test event_log::tests::test_undo_most_recent ... ok
[INFO] [stdout] test formatter::tests::test_format_result_error ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_quoted_positionals ... ok
[INFO] [stdout] test session::tests::test_session_checkpoint ... ok
[INFO] [stdout] test session::tests::test_session_checkpoint_requires_name ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_whitespace_only ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_verb_only ... ok
[INFO] [stdout] test session::tests::test_session_open_resets_log ... ok
[INFO] [stdout] test session::tests::test_session_open ... ok
[INFO] [stdout] test parsed_op::tests::test_parse_op_simple_verb ... ok
[INFO] [stdout] test session::tests::test_session_save_no_model ... ok
[INFO] [stdout] test session::tests::test_session_save_no_path ... ok
[INFO] [stdout] test session::tests::test_session_open_error ... ok
[INFO] [stdout] test session::tests::test_session_save_with_as ... ok
[INFO] [stdout] test session::tests::test_session_open_requires_path ... ok
[INFO] [stdout] test session::tests::test_session_redo ... ok
[INFO] [stdout] test session::tests::test_session_undo_to_checkpoint ... ok
[INFO] [stdout] test session::tests::test_session_undo_no_model ... ok
[INFO] [stdout] test session::tests::test_session_unknown_command ... ok
[INFO] [stdout] test session::tests::test_session_redo_calls_rebuild ... ok
[INFO] [stdout] test session::tests::test_session_status_placeholder ... ok
[INFO] [stdout] test session::tests::test_session_undo_calls_rebuild ... ok
[INFO] [stdout] test session::tests::test_session_redo_no_model ... ok
[INFO] [stdout] test session::tests::test_session_undo ... ok
[INFO] [stdout] test session::tests::test_session_new_with_params ... ok
[INFO] [stdout] test tokenizer::tests::test_parse_key_value ... ok
[INFO] [stdout] test tokenizer::tests::test_parse_key_value_strips_quotes ... ok
[INFO] [stdout] test tokenizer::tests::test_is_selector ... ok
[INFO] [stdout] test tokenizer::tests::test_is_key_value ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_colons_in_value ... ok
[INFO] [stdout] test tokenizer::tests::test_is_arrow ... ok
[INFO] [stdout] test session::tests::test_session_save ... ok
[INFO] [stdout] test tokenizer::tests::test_parse_key_value_with_meta ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_empty_quoted_string ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_escaped_backslash ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_multiple_newlines ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_newline_in_unquoted ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_multiple_spaces ... ok
[INFO] [stdout] test verb_registry::tests::test_lookup_unknown ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_escaped_quotes ... ok
[INFO] [stdout] test verb_registry::tests::test_reference_card ... ok
[INFO] [stdout] test verb_registry::tests::test_reference_card_empty ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_quoted_strings ... ok
[INFO] [stdout] test verb_registry::tests::test_register_many ... ok
[INFO] [stdout] test verb_registry::tests::test_verbs ... ok
[INFO] [stdout] test verb_registry::tests::test_reference_card_extra_sections ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_embedded_quoted_value ... ok
[INFO] [stdout] test verb_registry::tests::test_register_and_lookup ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_simple_tokens ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_single_token ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_unclosed_quote ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_empty_input ... ok
[INFO] [stdout] test tokenizer::tests::test_tokenize_newline_in_quoted ... ok
[INFO] [stderr]    Doc-tests fcp_core
[INFO] [stdout] test tokenizer::tests::test_tokenize_whitespace_only ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 92 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[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" "bb97b46a57a8c1eb48900f4f1a2747e7a25a81f374bbd4a4c6aeec34268f3206", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bb97b46a57a8c1eb48900f4f1a2747e7a25a81f374bbd4a4c6aeec34268f3206", kill_on_drop: false }`
[INFO] [stdout] bb97b46a57a8c1eb48900f4f1a2747e7a25a81f374bbd4a4c6aeec34268f3206