Nov 17 19:48:30.512 INFO testing tuf-0.3.0-alpha3 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 19:48:30.513 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 17 19:48:31.141 INFO [stdout] 303de4fa06849d63bbae81b7d61c460c9d39c1bf9935205de8140237c4b807d2
Nov 17 19:48:31.145 INFO running `"docker" "start" "-a" "303de4fa06849d63bbae81b7d61c460c9d39c1bf9935205de8140237c4b807d2"`
Nov 17 19:48:32.363 INFO [stderr] usermod: no changes
Nov 17 19:48:32.660 INFO [stderr]    Compiling derp v0.0.4
Nov 17 19:48:32.663 INFO [stderr]    Compiling ring v0.11.0
Nov 17 19:48:43.480 INFO [stderr]    Compiling tuf v0.3.0-alpha3 (/source)
Nov 17 19:48:51.946 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.55s
Nov 17 19:48:51.960 INFO [stderr] su: No module specific data is present
Nov 17 19:48:52.549 INFO running `"docker" "inspect" "303de4fa06849d63bbae81b7d61c460c9d39c1bf9935205de8140237c4b807d2"`
Nov 17 19:48:52.821 INFO running `"docker" "rm" "-f" "303de4fa06849d63bbae81b7d61c460c9d39c1bf9935205de8140237c4b807d2"`
Nov 17 19:48:53.066 INFO [stdout] 303de4fa06849d63bbae81b7d61c460c9d39c1bf9935205de8140237c4b807d2
Nov 17 19:48:53.075 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 17 19:48:53.545 INFO [stdout] 2cade62e6bf9d230b6c50d336809e4aaaa66d6b268e5572dae491ef7c951ca26
Nov 17 19:48:53.547 INFO running `"docker" "start" "-a" "2cade62e6bf9d230b6c50d336809e4aaaa66d6b268e5572dae491ef7c951ca26"`
Nov 17 19:48:54.807 INFO [stderr] usermod: no changes
Nov 17 19:48:55.061 INFO [stderr]    Compiling tuf v0.3.0-alpha3 (/source)
Nov 17 19:49:21.954 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 27.11s
Nov 17 19:49:21.977 INFO [stderr] su: No module specific data is present
Nov 17 19:49:22.447 INFO running `"docker" "inspect" "2cade62e6bf9d230b6c50d336809e4aaaa66d6b268e5572dae491ef7c951ca26"`
Nov 17 19:49:22.607 INFO running `"docker" "rm" "-f" "2cade62e6bf9d230b6c50d336809e4aaaa66d6b268e5572dae491ef7c951ca26"`
Nov 17 19:49:22.971 INFO [stdout] 2cade62e6bf9d230b6c50d336809e4aaaa66d6b268e5572dae491ef7c951ca26
Nov 17 19:49:22.979 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 17 19:49:23.657 INFO [stdout] 1106e1030f7eeebe03b185ae9b2632e7f68acd781738d7872eabe6d6e1fb0c2b
Nov 17 19:49:23.661 INFO running `"docker" "start" "-a" "1106e1030f7eeebe03b185ae9b2632e7f68acd781738d7872eabe6d6e1fb0c2b"`
Nov 17 19:49:24.390 INFO [stderr] usermod: no changes
Nov 17 19:49:24.527 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Nov 17 19:49:24.543 INFO [stderr]      Running /target/debug/deps/tuf-ec22850593e102cb
Nov 17 19:49:24.545 INFO [stdout] 
Nov 17 19:49:24.545 INFO [stdout] running 68 tests
Nov 17 19:49:24.547 INFO [stdout] test crypto::test::extract_pkcs1_from_rsa_2048_pkcs8 ... ok
Nov 17 19:49:24.548 INFO [stdout] test crypto::test::parse_rsa_4096_spki ... ok
Nov 17 19:49:24.548 INFO [stdout] test crypto::test::parse_rsa_2048_spki ... ok
Nov 17 19:49:24.548 INFO [stdout] test crypto::test::ed25519_read_pkcs8_and_sign ... ok
Nov 17 19:49:24.550 INFO [stdout] test crypto::test::extract_pkcs1_from_rsa_4096_pkcs8 ... ok
Nov 17 19:49:24.550 INFO [stdout] test crypto::test::serde_ed25519_public_key ... ok
Nov 17 19:49:24.550 INFO [stdout] test crypto::test::serde_key_id ... ok
Nov 17 19:49:24.551 INFO [stdout] test crypto::test::serde_rsa_public_key ... ok
Nov 17 19:49:24.553 INFO [stdout] test interchange::cjson::test::write_str ... ok
Nov 17 19:49:24.553 INFO [stdout] test interchange::cjson::test::write_obj ... ok
Nov 17 19:49:24.555 INFO [stdout] test crypto::test::serde_signature ... ok
Nov 17 19:49:24.555 INFO [stdout] test metadata::test::deserialize_json_delegation_bad_threshold ... ok
Nov 17 19:49:24.568 INFO [stdout] test metadata::test::deserialize_json_delegation_duplicate_paths ... ok
Nov 17 19:49:24.568 INFO [stdout] test interchange::cjson::test::write_arr ... ok
Nov 17 19:49:24.568 INFO [stdout] test metadata::test::deserialize_json_delegation_duplicate_key_ids ... ok
Nov 17 19:49:24.568 INFO [stdout] test crypto::test::serde_signature_value ... ok
Nov 17 19:49:24.570 INFO [stdout] test metadata::test::deserialize_json_role_definition_duplicate_key_ids ... ok
Nov 17 19:49:24.576 INFO [stdout] test metadata::test::deserialize_json_delegations_no_roles ... ok
Nov 17 19:49:24.576 INFO [stdout] test metadata::test::deserialize_json_delegations_duplicate_keys ... ok
Nov 17 19:49:24.584 INFO [stdout] test metadata::test::deserialize_json_delegations_duplicated_roles ... ok
Nov 17 19:49:24.584 INFO [stdout] test metadata::test::deserialize_json_delegations_no_keys ... ok
Nov 17 19:49:24.585 INFO [stdout] test metadata::test::deserialize_json_role_definition_illegal_threshold ... ok
Nov 17 19:49:24.600 INFO [stdout] test client::test::root_chain_update ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_snapshot_bad_type ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_root_illegal_version ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_root_bad_type ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_targets_bad_type ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_timestamp_bad_type ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_snapshot_illegal_version ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_timestamp_illegal_version ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::deserialize_json_root_duplicate_keys ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::path_matches_chain ... ok
Nov 17 19:49:24.601 INFO [stdout] test crypto::test::rsa_2048_read_pkcs8_and_sign ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_role_definition ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_metadata_path ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_snapshot_metadata ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_targets_with_delegations_metadata ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_target_path ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_root_metadata ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_target_description ... ok
Nov 17 19:49:24.601 INFO [stdout] test repository::test::ephemeral_repo_targets ... ok
Nov 17 19:49:24.601 INFO [stdout] test metadata::test::serde_signed_metadata ... ok
Nov 17 19:49:24.603 INFO [stdout] test metadata::test::serde_targets_metadata ... ok
Nov 17 19:49:24.604 INFO [stdout] test metadata::test::serde_timestamp_metadata ... ok
Nov 17 19:49:24.604 INFO [stdout] test tuf::test::bad_snapshot_update_wrong_version ... ok
Nov 17 19:49:24.605 INFO [stdout] test repository::test::file_system_repo_targets ... ok
Nov 17 19:49:24.605 INFO [stdout] test tuf::test::bad_timestamp_update_wrong_key ... ok
Nov 17 19:49:24.606 INFO [stdout] test tuf::test::bad_snapshot_update_wrong_key ... ok
Nov 17 19:49:24.627 INFO [stdout] test tuf::test::bad_targets_update_wrong_version ... ok
Nov 17 19:49:24.627 INFO [stdout] test tuf::test::good_root_rotation ... ok
Nov 17 19:49:24.627 INFO [stdout] test metadata::test::deserialize_json_targets_illegal_version ... ok
Nov 17 19:49:24.627 INFO [stdout] test tuf::test::good_targets_update ... ok
Nov 17 19:49:24.627 INFO [stdout] test tuf::test::bad_targets_update_wrong_key ... ok
Nov 17 19:49:24.627 INFO [stdout] test util::test::invalid_read_above_max_size ... ok
Nov 17 19:49:24.627 INFO [stdout] test util::test::invalid_read_above_max_size_large_data ... ok
Nov 17 19:49:24.627 INFO [stdout] test util::test::invalid_read_bad_hash ... ok
Nov 17 19:49:24.628 INFO [stdout] test util::test::valid_read ... ok
Nov 17 19:49:24.629 INFO [stdout] test util::test::valid_read_below_max_size ... ok
Nov 17 19:49:24.630 INFO [stdout] test tuf::test::good_timestamp_update ... ok
Nov 17 19:49:24.635 INFO [stdout] test tuf::test::no_cross_sign_root_rotation ... ok
Nov 17 19:49:24.635 INFO [stdout] test tuf::test::root_pinned_failure ... ok
Nov 17 19:49:24.635 INFO [stdout] test util::test::valid_read_good_hash ... ok
Nov 17 19:49:24.635 INFO [stdout] test tuf::test::root_pinned_success ... ok
Nov 17 19:49:24.637 INFO [stdout] test tuf::test::good_snapshot_update ... ok
Nov 17 19:49:24.659 INFO [stdout] test util::test::invalid_read_bad_hash_large_data ... ok
Nov 17 19:49:24.660 INFO [stdout] test util::test::valid_read_large_data ... ok
Nov 17 19:49:24.660 INFO [stdout] test util::test::valid_read_good_hash_large_data ... ok
Nov 17 19:49:24.726 INFO [stdout] test crypto::test::rsa_4096_read_pkcs8_and_sign ... ok
Nov 17 19:49:24.726 INFO [stdout] 
Nov 17 19:49:24.726 INFO [stdout] test result: ok. 68 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:49:24.726 INFO [stdout] 
Nov 17 19:49:24.728 INFO [stderr]      Running /target/debug/deps/integration-4dca44d9c48120eb
Nov 17 19:49:24.730 INFO [stdout] 
Nov 17 19:49:24.730 INFO [stdout] running 2 tests
Nov 17 19:49:24.738 INFO [stdout] test simple_delegation ... ok
Nov 17 19:49:24.746 INFO [stdout] test nested_delegation ... ok
Nov 17 19:49:24.746 INFO [stdout] 
Nov 17 19:49:24.747 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:49:24.747 INFO [stdout] 
Nov 17 19:49:24.747 INFO [stderr]      Running /target/debug/deps/simple_example-fb1c12d44023fd37
Nov 17 19:49:24.753 INFO [stdout] 
Nov 17 19:49:24.753 INFO [stdout] running 2 tests
Nov 17 19:49:24.771 INFO [stdout] test without_translator ... ok
Nov 17 19:49:24.771 INFO [stdout] test with_translator ... ok
Nov 17 19:49:24.771 INFO [stdout] 
Nov 17 19:49:24.771 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:49:24.771 INFO [stdout] 
Nov 17 19:49:24.772 INFO [stderr]    Doc-tests tuf
Nov 17 19:49:28.048 INFO [stdout] 
Nov 17 19:49:28.055 INFO [stdout] running 24 tests
Nov 17 19:49:31.755 INFO [stdout] test src/crypto.rs - crypto::PrivateKey::from_pkcs8 (line 355) ... ok
Nov 17 19:49:53.063 INFO [stdout] test src/client.rs - client (line 5) ... ok
Nov 17 19:49:55.131 INFO [stdout] test src/client.rs - client::Config (line 628) ... ok
Nov 17 19:49:58.613 INFO [stdout] test src/interchange/mod.rs - interchange::Json::deserialize (line 244) ... ok
Nov 17 19:50:00.623 INFO [stdout] test src/interchange/mod.rs - interchange::Json::canonicalize (line 232) ... ok
Nov 17 19:50:02.499 INFO [stdout] test src/client.rs - client::PathTranslator (line 68) ... ok
Nov 17 19:50:03.286 INFO [stdout] test src/interchange/mod.rs - interchange::Json::from_reader (line 319) ... ok
Nov 17 19:50:03.855 INFO [stdout] test src/interchange/mod.rs - interchange::Json::extension (line 224) ... ok
Nov 17 19:50:05.283 INFO [stdout] test src/crypto.rs - crypto::hash_preference (line 37) ... ok
Nov 17 19:50:28.339 INFO [stdout] test src/interchange/mod.rs - interchange::Json::serialize (line 273) ... ok
Nov 17 19:50:29.367 INFO [stdout] test src/interchange/mod.rs - interchange::Json::to_writer (line 302) ... ok
Nov 17 19:50:35.155 INFO [stdout] test src/metadata.rs - metadata::MetadataPath (line 677) ... ok
Nov 17 19:50:38.119 INFO [stdout] test src/metadata.rs - metadata::MetadataPath::from_role (line 710) ... ok
Nov 17 19:50:38.171 INFO [stdout] test src/metadata.rs - metadata::MetadataPath::components (line 728) ... ok
Nov 17 19:50:39.291 INFO [stdout] test src/metadata.rs - metadata::MetadataPath::new (line 692) ... ok
Nov 17 19:50:41.587 INFO [stdout] test src/metadata.rs - metadata::Role::fuzzy_matches_path (line 159) ... ok
Nov 17 19:50:47.047 INFO [stdout] test src/metadata.rs - metadata::SignedMetadata<D, M>::add_signature (line 287) ... ok
Nov 17 19:51:09.191 INFO [stdout] test src/metadata.rs - metadata::SignedMetadata<D, M>::new (line 244) ... ok
Nov 17 19:51:10.647 INFO [stdout] test src/metadata.rs - metadata::SignedMetadata<D, M>::verify (line 375) ... ok
Nov 17 19:51:12.399 INFO [stdout] test src/metadata.rs - metadata::VirtualTargetPath::new (line 1003) ... ok
Nov 17 19:51:13.595 INFO [stdout] test src/metadata.rs - metadata::TargetPath::components (line 1120) ... ok
Nov 17 19:51:14.531 INFO [stdout] test src/metadata.rs - metadata::VirtualTargetPath::is_child (line 1033) ... ok
Nov 17 19:51:14.631 INFO [stdout] test src/metadata.rs - metadata::TargetDescription::from_reader (line 1162) ... ok
Nov 17 19:51:15.015 INFO [stdout] test src/metadata.rs - metadata::VirtualTargetPath::components (line 1022) ... ok
Nov 17 19:51:15.016 INFO [stdout] 
Nov 17 19:51:15.017 INFO [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:51:15.017 INFO [stdout] 
Nov 17 19:51:15.079 INFO [stderr] su: No module specific data is present
Nov 17 19:51:16.749 INFO running `"docker" "inspect" "1106e1030f7eeebe03b185ae9b2632e7f68acd781738d7872eabe6d6e1fb0c2b"`
Nov 17 19:51:17.139 INFO running `"docker" "rm" "-f" "1106e1030f7eeebe03b185ae9b2632e7f68acd781738d7872eabe6d6e1fb0c2b"`
Nov 17 19:51:17.375 INFO [stdout] 1106e1030f7eeebe03b185ae9b2632e7f68acd781738d7872eabe6d6e1fb0c2b