[INFO] fetching crate getopt2 0.1.0...
[INFO] testing getopt2-0.1.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate getopt2 0.1.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate getopt2 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate getopt2 0.1.0
[INFO] tweaked toml for crates.io crate getopt2 0.1.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate getopt2 0.1.0 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 getopt2 0.1.0 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-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 578f065aa0e69a8166a09ae06bfb385f84a669a379faf529f37178a8353a689a
[INFO] running `Command { std: "docker" "start" "-a" "578f065aa0e69a8166a09ae06bfb385f84a669a379faf529f37178a8353a689a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "578f065aa0e69a8166a09ae06bfb385f84a669a379faf529f37178a8353a689a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "578f065aa0e69a8166a09ae06bfb385f84a669a379faf529f37178a8353a689a", kill_on_drop: false }`
[INFO] [stdout] 578f065aa0e69a8166a09ae06bfb385f84a669a379faf529f37178a8353a689a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] da927a48dcbdab94bd28986b07723e5c92fcada5f60e76855b67d0513aaadde0
[INFO] running `Command { std: "docker" "start" "-a" "da927a48dcbdab94bd28986b07723e5c92fcada5f60e76855b67d0513aaadde0", kill_on_drop: false }`
[INFO] [stderr]    Compiling getopt2 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.47s
[INFO] running `Command { std: "docker" "inspect" "da927a48dcbdab94bd28986b07723e5c92fcada5f60e76855b67d0513aaadde0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "da927a48dcbdab94bd28986b07723e5c92fcada5f60e76855b67d0513aaadde0", kill_on_drop: false }`
[INFO] [stdout] da927a48dcbdab94bd28986b07723e5c92fcada5f60e76855b67d0513aaadde0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] fc511b95df16eec1e16d8514263c58736e8e105557825170b823e28412a0086e
[INFO] running `Command { std: "docker" "start" "-a" "fc511b95df16eec1e16d8514263c58736e8e105557825170b823e28412a0086e", kill_on_drop: false }`
[INFO] [stderr]    Compiling getopt2 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.46s
[INFO] running `Command { std: "docker" "inspect" "fc511b95df16eec1e16d8514263c58736e8e105557825170b823e28412a0086e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fc511b95df16eec1e16d8514263c58736e8e105557825170b823e28412a0086e", kill_on_drop: false }`
[INFO] [stdout] fc511b95df16eec1e16d8514263c58736e8e105557825170b823e28412a0086e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 6fac926bab1271304d48f63136cfbac1cc3f04cf134fe0f7dbf2ba742f21f581
[INFO] running `Command { std: "docker" "start" "-a" "6fac926bab1271304d48f63136cfbac1cc3f04cf134fe0f7dbf2ba742f21f581", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/getopt2.rs (/opt/rustwide/target/debug/deps/getopt2-1a7883661484d060)
[INFO] [stdout] 
[INFO] [stdout] running 100 tests
[INFO] [stdout] test doubledash::gnu_dash_following_option_with_argument_is_not_a_separator ... ok
[INFO] [stdout] test doubledash::gnu_dash_following_option_with_optional_argument_is_a_separator ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_at_end ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_at_start ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_following_option_with_argument_at_end ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_following_option_with_argument ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_following_option_without_argument_at_end ... ok
[INFO] [stdout] test doubledash::gnu_option_notparsed_after_dash_at_start ... ok
[INFO] [stdout] test helpers::is_option_double_dash ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_in_middle ... ok
[INFO] [stdout] test doubledash::gnu_option_notparsed_after_option_without_argument_and_dash_in_middle ... ok
[INFO] [stdout] test helpers::is_option_ok ... ok
[INFO] [stdout] test helpers::is_option_not_in_map ... ok
[INFO] [stdout] test helpers::optionsmap_arg_in_middle ... ok
[INFO] [stdout] test helpers::optionsmap_optional_arg_at_end ... ok
[INFO] [stdout] test helpers::optionsmap_optional_arg_in_middle ... ok
[INFO] [stdout] test helpers::optionsmap_plus_semi_start ... ok
[INFO] [stdout] test helpers::optionsmap_plus_start ... ok
[INFO] [stdout] test helpers::optionsmap_semi_start ... ok
[INFO] [stdout] test helpers::optionsmap_with_args ... ok
[INFO] [stdout] test helpers::optionsmap_without_args ... ok
[INFO] [stdout] test helpers::possible_option_bad_char ... ok
[INFO] [stdout] test helpers::possible_option_dash ... ok
[INFO] [stdout] test helpers::possible_option_double_dash ... ok
[INFO] [stdout] test helpers::possible_option_ok ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_char ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_not_alphanum ... ok
[INFO] [stdout] test helpers::optionsmap_arg_tail ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_plus_double_semi_only ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_plus_position ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_posix_plus_with_double_semi_start ... ok
[INFO] [stdout] test doubledash::gnu_eat_dash_following_option_without_argument ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_semi_only ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_empty ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_triple_semi_start ... ok
[INFO] [stdout] test helpers::validate_optstring_ok ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_double_semi_start ... ok
[INFO] [stdout] test doubledash::gnu_option_notparsed_after_option_with_argument_and_dash_in_middle ... ok
[INFO] [stdout] test helpers::validate_optstring_ok_question_mark ... ok
[INFO] [stdout] test helpers::is_option_dash ... ok
[INFO] [stdout] test helpers::validate_optstring_ok_semi ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_triple_semi_mid ... ok
[INFO] [stdout] test helpers::validate_optstring_posix_plus_with_semi_start ... ok
[INFO] [stdout] test helpers::optionsmap_optional_arg_at_start ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_triple_semi_end ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_plus_semi_only ... ok
[INFO] [stdout] test helpers::validate_optstring_posix_plus_start ... ok
[INFO] [stdout] test helpers::validate_optstring_spaces_only ... ok
[INFO] [stdout] test helpers::validate_optstring_spaces_outside ... ok
[INFO] [stdout] test helpers::validate_optstring_invalid_plus_only ... ok
[INFO] [stdout] test helpers::validate_optstring_supported_extension_semi_start ... ok
[INFO] [stdout] test helpers::validate_optstring_valid_double_semi_end ... ok
[INFO] [stdout] test helpers::validate_optstring_valid_double_semi_mid ... ok
[INFO] [stdout] test helpers::validate_question_mark ... ok
[INFO] [stdout] test helpers::validate_question_mark_w_arg ... ok
[INFO] [stdout] test helpers::validate_optstring_spaces_inside ... ok
[INFO] [stdout] test hidebin::first_consumed ... ok
[INFO] [stdout] test hidebin::one_size ... ok
[INFO] [stdout] test index::index_access_to_arguments ... ok
[INFO] [stdout] test it::check_if_into_iter_returns_expected_values ... ok
[INFO] [stdout] test it::check_if_iter_returns_expected_values ... ok
[INFO] [stdout] test it::for_loop_can_consume_getopt_struct ... ok
[INFO] [stdout] test posix_tests::advanced_option_without_optional_arg ... ok
[INFO] [stdout] test posix_tests::bad_input_zero_sized_strings ... ok
[INFO] [stdout] test it::for_loop_wont_consume_immutable_ref ... ok
[INFO] [stdout] test posix_tests::basic_one_option_at_start_with_arg ... ok
[INFO] [stdout] test index::manual_index_access_to_arguments ... ok
[INFO] [stdout] test posix_tests::advanced_dash_without_option ... ok
[INFO] [stdout] test posix_tests::basic_one_option_in_middle_without_arg ... ok
[INFO] [stdout] test posix_tests::basic_two_options_at_start_without_arg ... ok
[INFO] [stdout] test posix_tests::basic_two_options_in_middle_without_arg ... ok
[INFO] [stdout] test posix_tests::basic_no_options ... ok
[INFO] [stdout] test tests::advanced_dash_without_an_option ... ok
[INFO] [stdout] test tests::advanced_semi_start_plus_qmark ... ok
[INFO] [stdout] test tests::bad_input_zero_sized_arg ... ok
[INFO] [stdout] test posix_tests::basic_one_option_at_start_without_arg ... ok
[INFO] [stdout] test tests::bad_input_zero_sized_string ... ok
[INFO] [stdout] test tests::basic_getopt_get ... ok
[INFO] [stdout] test tests::basic_getopt_len ... ok
[INFO] [stdout] test tests::basic_one_option_without_arg ... ok
[INFO] [stdout] test tests::basic_question_mark_option_without_arg ... ok
[INFO] [stdout] test tests::empty_getopt_is_empty ... ok
[INFO] [stdout] test tests::basic_two_option_with_missing_arg ... ok
[INFO] [stdout] test tests::options_only_getopt_is_empty ... ok
[INFO] [stdout] test tests::unknown_option_with_arg ... ok
[INFO] [stdout] test tests::unknown_option_without_arg ... ok
[INFO] [stdout] test validateapi::validate_no_options_on_cmdline_but_options_defined ... ok
[INFO] [stdout] test hidebin::comparing_iterators_works ... ok
[INFO] [stdout] test hidebin::zero_size ... ok
[INFO] [stdout] test posix_tests::basic_one_option_in_middle_with_arg ... ok
[INFO] [stdout] test tests::basic_two_options_without_arg ... ok
[INFO] [stdout] test helpers::validate_opt_question_mark ... ok
[INFO] [stdout] test validateapi::validate_required_argument_missing ... ok
[INFO] [stdout] test validateapi::validate_unknown_option_with_argument ... ok
[INFO] [stdout] test validateapi::validate_unknown_option_without_argument ... ok
[INFO] [stdout] test index::invalid_access_to_arguments - should panic ... ok
[INFO] [stdout] test tests::basic_getopt_has ... ok
[INFO] [stdout] test tests::basic_getopt_is_empty ... ok
[INFO] [stdout] test tests::basic_no_options ... ok
[INFO] [stdout] test tests::basic_one_option_with_arg ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 100 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/Example.rs (/opt/rustwide/target/debug/deps/Example-2fc6ba9a2eed76ec)
[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/Example_tests.rs (/opt/rustwide/target/debug/deps/Example_tests-7299f4d8e5a5d0c6)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test real_invoke_main ... ok
[INFO] [stdout] test real_a_option_without_arg ... ok
[INFO] [stdout] test real_invoked_help ... ok
[INFO] [stdout] test real_should_have_b_option_with_argument ... ok
[INFO] [stdout] test real_should_not_have_unknown_d_option ... ok
[INFO] [stdout] test real_two_arguments ... ok
[INFO] [stdout] test real_should_not_have_c_option ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/Main.rs (/opt/rustwide/target/debug/deps/Main-d77a9a63f6b3cbce)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test main ... 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]    Doc-tests getopt2
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/getopt2.rs - getopt::has (line 206) ... ok
[INFO] [stdout] test src/getopt2.rs - hideBin (line 647) ... ok
[INFO] [stdout] test src/getopt2.rs - (line 44) ... ok
[INFO] [stdout] test src/getopt2.rs - getopt::iter (line 152) ... ok
[INFO] [stdout] test src/getopt2.rs - getopt::is_empty (line 232) ... ok
[INFO] [stdout] test src/getopt2.rs - getopt::len (line 134) ... ok
[INFO] [stdout] test src/getopt2.rs - getopt::get (line 180) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.63s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6fac926bab1271304d48f63136cfbac1cc3f04cf134fe0f7dbf2ba742f21f581", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6fac926bab1271304d48f63136cfbac1cc3f04cf134fe0f7dbf2ba742f21f581", kill_on_drop: false }`
[INFO] [stdout] 6fac926bab1271304d48f63136cfbac1cc3f04cf134fe0f7dbf2ba742f21f581