[INFO] fetching crate portage-metadata 0.3.1...
[INFO] testing portage-metadata-0.3.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate portage-metadata 0.3.1 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate portage-metadata 0.3.1
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate portage-metadata 0.3.1
[INFO] tweaked toml for crates.io crate portage-metadata 0.3.1 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate portage-metadata 0.3.1 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate portage-metadata 0.3.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f686508a833efd45b2d68340ce49b7acd0b0ae6757ff84509bd32db8a9020e73
[INFO] running `Command { std: "docker" "start" "-a" "f686508a833efd45b2d68340ce49b7acd0b0ae6757ff84509bd32db8a9020e73", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f686508a833efd45b2d68340ce49b7acd0b0ae6757ff84509bd32db8a9020e73", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f686508a833efd45b2d68340ce49b7acd0b0ae6757ff84509bd32db8a9020e73", kill_on_drop: false }`
[INFO] [stdout] f686508a833efd45b2d68340ce49b7acd0b0ae6757ff84509bd32db8a9020e73
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c506e63ae03074539b515a7dfb707ed50889fa322e89a79da133263d31326bde
[INFO] running `Command { std: "docker" "start" "-a" "c506e63ae03074539b515a7dfb707ed50889fa322e89a79da133263d31326bde", kill_on_drop: false }`
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling winnow v1.0.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling lasso v0.7.3
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling gentoo-interner v0.1.2
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling portage-atom v0.5.1
[INFO] [stderr]    Compiling portage-metadata v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.16s
[INFO] running `Command { std: "docker" "inspect" "c506e63ae03074539b515a7dfb707ed50889fa322e89a79da133263d31326bde", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c506e63ae03074539b515a7dfb707ed50889fa322e89a79da133263d31326bde", kill_on_drop: false }`
[INFO] [stdout] c506e63ae03074539b515a7dfb707ed50889fa322e89a79da133263d31326bde
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 832944c5e9edf89b1591d42c61ec18bf8132abe02286d1f845262ac735cf102b
[INFO] running `Command { std: "docker" "start" "-a" "832944c5e9edf89b1591d42c61ec18bf8132abe02286d1f845262ac735cf102b", kill_on_drop: false }`
[INFO] [stderr]    Compiling portage-metadata v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.55s
[INFO] running `Command { std: "docker" "inspect" "832944c5e9edf89b1591d42c61ec18bf8132abe02286d1f845262ac735cf102b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "832944c5e9edf89b1591d42c61ec18bf8132abe02286d1f845262ac735cf102b", kill_on_drop: false }`
[INFO] [stdout] 832944c5e9edf89b1591d42c61ec18bf8132abe02286d1f845262ac735cf102b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6d5677b1b375db89bf5dde37ffed1b2a6f0c7484e47f45befb34f3d32e7b46f0
[INFO] running `Command { std: "docker" "start" "-a" "6d5677b1b375db89bf5dde37ffed1b2a6f0c7484e47f45befb34f3d32e7b46f0", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/portage_metadata-1b7d7b25aac10c3b)
[INFO] [stdout] 
[INFO] [stdout] running 124 tests
[INFO] [stdout] test cache::tests::defined_phases_dash ... ok
[INFO] [stdout] test cache::tests::missing_description ... ok
[INFO] [stdout] test cache::tests::parse_eclasses_empty ... ok
[INFO] [stdout] test cache::tests::parse_eclasses ... ok
[INFO] [stdout] test cache::tests::missing_slot ... ok
[INFO] [stdout] test cache::tests::parse_eclasses_odd_count ... ok
[INFO] [stdout] test cache::tests::license_parsing ... ok
[INFO] [stdout] test cache::tests::parse_example ... ok
[INFO] [stdout] test cache::tests::parse_minimal ... ok
[INFO] [stdout] test cache::tests::slot_with_subslot ... ok
[INFO] [stdout] test cache::tests::unknown_keys_ignored ... ok
[INFO] [stdout] test eapi::tests::display_round_trip ... ok
[INFO] [stdout] test eapi::tests::parse_all_eapis ... ok
[INFO] [stdout] test iuse::tests::complex_flag_names ... ok
[INFO] [stdout] test cache::tests::serialize_round_trip ... ok
[INFO] [stdout] test cache::tests::empty_lines_ignored ... ok
[INFO] [stdout] test eapi::tests::feature_queries ... ok
[INFO] [stdout] test eapi::tests::invalid_eapi ... ok
[INFO] [stdout] test eapi::tests::ordering ... ok
[INFO] [stdout] test iuse::tests::invalid_bare_plus ... ok
[INFO] [stdout] test iuse::tests::invalid_empty ... ok
[INFO] [stdout] test iuse::tests::invalid_flag_starting_with_at ... ok
[INFO] [stdout] test iuse::tests::invalid_flag_with_exclamation ... ok
[INFO] [stdout] test iuse::tests::parse_disabled_default ... ok
[INFO] [stdout] test iuse::tests::display_round_trip ... ok
[INFO] [stdout] test iuse::tests::invalid_bare_minus ... ok
[INFO] [stdout] test iuse::tests::parse_empty_line ... ok
[INFO] [stdout] test iuse::tests::parse_enabled_default ... ok
[INFO] [stdout] test iuse::tests::test_flag_name_validation ... ok
[INFO] [stdout] test iuse::tests::valid_flag_with_at_character ... ok
[INFO] [stdout] test iuse::tests::valid_flag_with_plus_character ... ok
[INFO] [stdout] test iuse::tests::parse_line ... ok
[INFO] [stdout] test iuse::tests::parse_plain ... ok
[INFO] [stdout] test keyword::tests::invalid_arch_with_invalid_chars ... ok
[INFO] [stdout] test keyword::tests::invalid_bare_dash ... ok
[INFO] [stdout] test keyword::tests::invalid_bare_tilde ... ok
[INFO] [stdout] test keyword::tests::invalid_arch_with_exclamation ... ok
[INFO] [stdout] test keyword::tests::display_round_trip ... ok
[INFO] [stdout] test keyword::tests::invalid_empty ... ok
[INFO] [stdout] test keyword::tests::parse_disabled ... ok
[INFO] [stdout] test keyword::tests::parse_line ... ok
[INFO] [stdout] test keyword::tests::parse_disabled_all ... ok
[INFO] [stdout] test keyword::tests::parse_stable ... ok
[INFO] [stdout] test keyword::tests::parse_empty_line ... ok
[INFO] [stdout] test keyword::tests::parse_testing ... ok
[INFO] [stdout] test keyword::tests::test_arch_name_validation ... ok
[INFO] [stdout] test license::tests::display_any_of ... ok
[INFO] [stdout] test keyword::tests::valid_arch_with_underscore ... ok
[INFO] [stdout] test license::tests::display_single ... ok
[INFO] [stdout] test license::tests::display_round_trip ... ok
[INFO] [stdout] test license::tests::parse_complex ... ok
[INFO] [stdout] test license::tests::parse_multiple_licenses ... ok
[INFO] [stdout] test license::tests::invalid_license_starting_with_hyphen ... ok
[INFO] [stdout] test license::tests::parse_single_license ... ok
[INFO] [stdout] test license::tests::parse_any_of ... ok
[INFO] [stdout] test license::tests::parse_use_conditional ... ok
[INFO] [stdout] test keyword::tests::valid_arch_with_hyphen_not_first ... ok
[INFO] [stdout] test license::tests::invalid_license_starting_with_dot ... ok
[INFO] [stdout] test license::tests::valid_license_with_hyphen_not_first ... ok
[INFO] [stdout] test license::tests::invalid_license_starting_with_plus ... ok
[INFO] [stdout] test license::tests::valid_license_with_underscore ... ok
[INFO] [stdout] test license::tests::parse_empty ... ok
[INFO] [stdout] test phase::tests::parse_full_names ... ok
[INFO] [stdout] test phase::tests::invalid_phase ... ok
[INFO] [stdout] test license::tests::valid_use_conditional_with_at ... ok
[INFO] [stdout] test phase::tests::parse_line ... ok
[INFO] [stdout] test phase::tests::display_round_trip ... ok
[INFO] [stdout] test phase::tests::parse_empty_line ... ok
[INFO] [stdout] test cache::tests::eapi8_idepend ... ok
[INFO] [stdout] test phase::tests::parse_line_dash ... ok
[INFO] [stdout] test phase::tests::parse_short_names ... ok
[INFO] [stdout] test phase::tests::real_world_defined_phases ... ok
[INFO] [stdout] test required_use::tests::display_round_trip_conditional ... ok
[INFO] [stdout] test required_use::tests::display_round_trip_any_of ... ok
[INFO] [stdout] test required_use::tests::display_round_trip_exactly_one ... ok
[INFO] [stdout] test required_use::tests::display_round_trip_at_most_one ... ok
[INFO] [stdout] test required_use::tests::invalid_flag_starting_with_hyphen ... ok
[INFO] [stdout] test required_use::tests::invalid_use_conditional_flag_starting_with_hyphen ... ok
[INFO] [stdout] test required_use::tests::invalid_flag_starting_with_at ... ok
[INFO] [stdout] test required_use::tests::parse_empty ... ok
[INFO] [stdout] test required_use::tests::parse_complex ... ok
[INFO] [stdout] test required_use::tests::parse_single_flag ... ok
[INFO] [stdout] test required_use::tests::parse_use_conditional ... ok
[INFO] [stdout] test required_use::tests::parse_exactly_one ... ok
[INFO] [stdout] test required_use::tests::valid_flag_with_at_character ... ok
[INFO] [stdout] test required_use::tests::parse_negated_flag ... ok
[INFO] [stdout] test required_use::tests::valid_use_conditional_with_at ... ok
[INFO] [stdout] test restrict::tests::display_token ... ok
[INFO] [stdout] test restrict::tests::parse_mixed ... ok
[INFO] [stdout] test restrict::tests::display_round_trip ... ok
[INFO] [stdout] test restrict::tests::flat_tokens ... ok
[INFO] [stdout] test restrict::tests::parse_simple_tokens ... ok
[INFO] [stdout] test restrict::tests::display_conditional ... ok
[INFO] [stdout] test restrict::tests::parse_empty ... ok
[INFO] [stdout] test restrict::tests::parse_use_conditional ... ok
[INFO] [stdout] test src_uri::tests::display_renamed ... ok
[INFO] [stdout] test src_uri::tests::display_restricted_uri ... ok
[INFO] [stdout] test src_uri::tests::display_restricted_renamed_uri ... ok
[INFO] [stdout] test src_uri::tests::bare_filename_no_protocol ... ok
[INFO] [stdout] test src_uri::tests::display_uri ... ok
[INFO] [stdout] test src_uri::tests::mirror_uri_debian ... ok
[INFO] [stdout] test src_uri::tests::nested_use_conditionals_stellarium ... ok
[INFO] [stdout] test src_uri::tests::multiple_rename_targets_with_at_sign ... ok
[INFO] [stdout] test src_uri::tests::mirror_uri_gnu ... ok
[INFO] [stdout] test src_uri::tests::parse_empty ... ok
[INFO] [stdout] test src_uri::tests::parse_fetch_restricted_uri ... ok
[INFO] [stdout] test src_uri::tests::nested_negated_conditional_culmus ... ok
[INFO] [stdout] test src_uri::tests::parse_mirror_restricted_uri ... ok
[INFO] [stdout] test src_uri::tests::parse_multiple_uris ... ok
[INFO] [stdout] test src_uri::tests::parse_negated_conditional ... ok
[INFO] [stdout] test src_uri::tests::parse_renamed_uri ... ok
[INFO] [stdout] test src_uri::tests::parse_mixed ... ok
[INFO] [stdout] test src_uri::tests::parse_restricted_renamed_uri ... ok
[INFO] [stdout] test src_uri::tests::parse_single_uri ... ok
[INFO] [stdout] test src_uri::tests::parse_use_conditional ... ok
[INFO] [stdout] test src_uri::tests::rename_target_with_at_sign ... ok
[INFO] [stdout] test src_uri::tests::rename_target_with_braces ... ok
[INFO] [stdout] test src_uri::tests::real_world_mirror_plus_prefix ... ok
[INFO] [stdout] test src_uri::tests::url_encoded_chars_in_uri ... ok
[INFO] [stdout] test src_uri::tests::real_world_src_uri ... ok
[INFO] [stdout] test required_use::tests::parse_any_of ... ok
[INFO] [stdout] test required_use::tests::parse_at_most_one ... ok
[INFO] [stdout] test src_uri::tests::uri_with_query_string_and_rename ... ok
[INFO] [stdout] test src_uri::tests::uri_with_brackets_in_filename ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 124 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests portage_metadata
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test src/cache.rs - cache::CacheEntry<DefaultInterner>::parse (line 313) ... ok
[INFO] [stdout] test src/keyword.rs - keyword::Keyword<DefaultInterner>::parse_line (line 118) ... ok
[INFO] [stdout] test src/phase.rs - phase::Phase::parse_line (line 54) ... ok
[INFO] [stdout] test src/restrict.rs - restrict::RestrictExpr::parse (line 40) ... ok
[INFO] [stdout] test src/iuse.rs - iuse::IUse<DefaultInterner>::parse_line (line 92) ... ok
[INFO] [stdout] test src/src_uri.rs - src_uri::SrcUriEntry::parse (line 58) ... ok
[INFO] [stdout] test src/required_use.rs - required_use::RequiredUseExpr::parse (line 50) ... ok
[INFO] [stdout] test src/lib.rs - (line 19) ... ok
[INFO] [stdout] test src/license.rs - license::LicenseExpr::parse (line 42) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.10s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6d5677b1b375db89bf5dde37ffed1b2a6f0c7484e47f45befb34f3d32e7b46f0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6d5677b1b375db89bf5dde37ffed1b2a6f0c7484e47f45befb34f3d32e7b46f0", kill_on_drop: false }`
[INFO] [stdout] 6d5677b1b375db89bf5dde37ffed1b2a6f0c7484e47f45befb34f3d32e7b46f0