[INFO] fetching crate snmp_usm 0.2.0...
[INFO] testing snmp_usm-0.2.0 against try#ce59db7a1b1b012fb2793c4641c1bdecad7a128b for pr-85530
[INFO] extracting crate snmp_usm 0.2.0 into /workspace/builds/worker-4/source
[INFO] validating manifest of crates.io crate snmp_usm 0.2.0 on toolchain ce59db7a1b1b012fb2793c4641c1bdecad7a128b
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate snmp_usm 0.2.0
[INFO] finished tweaking crates.io crate snmp_usm 0.2.0
[INFO] tweaked toml for crates.io crate snmp_usm 0.2.0 written to /workspace/builds/worker-4/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "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" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded yasna v0.3.2
[INFO] [stderr]   Downloaded cfb-mode v0.4.0
[INFO] [stderr]   Downloaded block-modes v0.5.0
[INFO] [stderr]   Downloaded des v0.4.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 51704dac324d8b59630acdfdfff8a77cb727670697b5799e020525a1df1cd01f
[INFO] running `Command { std: "docker" "start" "-a" "51704dac324d8b59630acdfdfff8a77cb727670697b5799e020525a1df1cd01f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "51704dac324d8b59630acdfdfff8a77cb727670697b5799e020525a1df1cd01f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "51704dac324d8b59630acdfdfff8a77cb727670697b5799e020525a1df1cd01f", kill_on_drop: false }`
[INFO] [stdout] 51704dac324d8b59630acdfdfff8a77cb727670697b5799e020525a1df1cd01f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 994e09877bf7a0aa4653ad48d7524c152e894b9bf8cc2b45a3aceec20c03b369
[INFO] running `Command { std: "docker" "start" "-a" "994e09877bf7a0aa4653ad48d7524c152e894b9bf8cc2b45a3aceec20c03b369", kill_on_drop: false }`
[INFO] [stderr]    Compiling typenum v1.13.0
[INFO] [stderr]    Compiling version_check v0.9.3
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling yasna v0.3.2
[INFO] [stderr]    Compiling generic-array v0.14.4
[INFO] [stderr]    Compiling block-cipher v0.7.1
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling stream-cipher v0.4.1
[INFO] [stderr]    Compiling crypto-mac v0.8.0
[INFO] [stderr]    Compiling aes-soft v0.4.0
[INFO] [stderr]    Compiling des v0.4.0
[INFO] [stderr]    Compiling cfb-mode v0.4.0
[INFO] [stderr]    Compiling block-modes v0.5.0
[INFO] [stderr]    Compiling sha-1 v0.9.6
[INFO] [stderr]    Compiling hmac v0.8.1
[INFO] [stderr]    Compiling md-5 v0.9.1
[INFO] [stderr]    Compiling aes v0.4.0
[INFO] [stderr]    Compiling snmp_usm v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.58s
[INFO] running `Command { std: "docker" "inspect" "994e09877bf7a0aa4653ad48d7524c152e894b9bf8cc2b45a3aceec20c03b369", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "994e09877bf7a0aa4653ad48d7524c152e894b9bf8cc2b45a3aceec20c03b369", kill_on_drop: false }`
[INFO] [stdout] 994e09877bf7a0aa4653ad48d7524c152e894b9bf8cc2b45a3aceec20c03b369
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48f9dc5ce6f92008e2089823fac4b4154f9c76d90db54c921ea7b10b10ae3475
[INFO] running `Command { std: "docker" "start" "-a" "48f9dc5ce6f92008e2089823fac4b4154f9c76d90db54c921ea7b10b10ae3475", kill_on_drop: false }`
[INFO] [stderr]    Compiling snmp_usm v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.26s
[INFO] running `Command { std: "docker" "inspect" "48f9dc5ce6f92008e2089823fac4b4154f9c76d90db54c921ea7b10b10ae3475", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48f9dc5ce6f92008e2089823fac4b4154f9c76d90db54c921ea7b10b10ae3475", kill_on_drop: false }`
[INFO] [stdout] 48f9dc5ce6f92008e2089823fac4b4154f9c76d90db54c921ea7b10b10ae3475
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e1b114e8e8ccb5639596369d1875e014cba260a704e0ec0766788a43a4b30376
[INFO] running `Command { std: "docker" "start" "-a" "e1b114e8e8ccb5639596369d1875e014cba260a704e0ec0766788a43a4b30376", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/snmp_usm-2505a08fe524e73a)
[INFO] [stdout] running 17 tests
[INFO] [stdout] test pos_finder::tests::it_returns_invalid_error_for_high_tag_num ... ok
[INFO] [stdout] test pos_finder::tests::it_steps_into_octet_str ... ok
[INFO] [stdout] test pos_finder::tests::it_steps_into_seq ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_error_when_reading_end_of_buf ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_invalid_error_for_invalid_long_len ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_invalid_error_for_invalid_short_len ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_invalid_error_for_indefinite_len ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_error_for_wrong_tag ... ok
[INFO] [stdout] test pos_finder::tests::it_returns_invalid_error_for_len_larger_than_usize_max ... ok
[INFO] [stdout] test priv_key::des_priv_key::tests::it_does_not_add_padding_if_multiple_of_block_size ... ok
[INFO] [stdout] test pos_finder::tests::it_skips_seq ... ok
[INFO] [stdout] test priv_key::des_priv_key::tests::it_adds_padding_if_not_multiple_of_block_size ... ok
[INFO] [stdout] test pos_finder::tests::it_skips_int ... ok
[INFO] [stdout] test pos_finder::tests::it_skips_octet_str ... ok
[INFO] [stdout] test localized_key::tests::it_panics_with_empty_passwd ... ok
[INFO] [stdout] test localized_key::tests::it_constructs_localized_key_with_sha1 ... ok
[INFO] [stdout] test localized_key::tests::it_constructs_localized_key_with_md5 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/aes128_priv_key.rs (/opt/rustwide/target/debug/deps/aes128_priv_key-c1d2123143481df0)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test it_encrypts_scoped_pdu_using_localized_sha1_key ... ok
[INFO] [stdout] test it_returns_error_for_priv_params_with_wrong_len ... ok
[INFO] [stdout] test it_decrypts_scoped_pdu_using_localized_md5_key ... ok
[INFO] [stdout] test it_decrypts_scoped_pdu_using_localized_sha1_key ... ok
[INFO] [stdout] test it_returns_empty_vec_when_decrypting_empty_scoped_pdu ... ok
[INFO] [stdout] test it_encrypts_scoped_pdu_using_localized_md5_key ... ok
[INFO] [stdout] test it_returns_empty_vec_when_encrypting_empty_scoped_pdu ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.48s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/auth_key.rs (/opt/rustwide/target/debug/deps/auth_key-f42cb921ce2d241d)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test it_authenticates_in_msg_using_md5 ... ok
[INFO] [stdout] test it_authenticates_out_msg_using_sha1 ... ok
[INFO] [stdout] test it_returns_malformed_msg_for_malformed_out_msg ... ok
[INFO] [stdout] test it_authenticates_in_msg_using_sha1 ... ok
[INFO] [stdout] test it_returns_not_in_time_window_error_for_invalid_engine_boots_in_authoritative ... ok
[INFO] [stdout] test it_returns_error_for_in_msg_with_malformed_security_params ... ok
[INFO] [stdout] test it_returns_not_in_time_window_error_for_invalid_engine_time_in_non_authoritative ... ok
[INFO] [stdout] test it_returns_malformed_msg_for_empty_out_msg ... ok
[INFO] [stdout] test it_returns_not_in_time_window_error_for_invalid_engine_time_in_authoritative ... ok
[INFO] [stdout] test it_returns_not_in_time_window_for_engine_boots_max ... ok
[INFO] [stdout] test it_returns_not_in_time_window_error_for_invalid_engine_boots_in_non_authoritative ... ok
[INFO] [stdout] test it_authenticates_out_msg_using_md5 ... ok
[INFO] [stdout] test it_returns_error_for_malformed_in_msg ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.81s
[INFO] [stderr]      Running tests/des_priv_key.rs (/opt/rustwide/target/debug/deps/des_priv_key-adb79a07ac6296a0)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test it_returns_error_for_priv_params_with_wrong_len ... ok
[INFO] [stdout] test it_returns_empty_vec_when_encrypting_empty_scoped_pdu ... ok
[INFO] [stdout] test it_decrypts_scoped_pdu_using_localized_md5_key ... ok
[INFO] [stdout] test it_encrypts_scoped_pdu_using_localized_sha1_key ... ok
[INFO] [stdout] test it_returns_empty_vec_when_decrypting_empty_scoped_pdu ... ok
[INFO] [stdout] test it_decrypts_scoped_pdu_using_localized_sha1_key ... ok
[INFO] [stderr]      Running tests/security_params.rs (/opt/rustwide/target/debug/deps/security_params-d6fa34727f2d45f5)
[INFO] [stdout] test it_encrypts_scoped_pdu_using_localized_md5_key ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test it_returns_malformed_security_params_error_for_negative_engine_boots ... ok
[INFO] [stdout] test it_returns_malformed_security_params_error_for_negative_engine_time ... ok
[INFO] [stderr]    Doc-tests snmp_usm
[INFO] [stdout] test it_encodes_empty_security_params ... ok
[INFO] [stdout] test it_creates_security_params_with_username_and_auth_params ... ok
[INFO] [stdout] test it_returns_malformed_security_params_error_for_empty_security_params ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::decode (line 337) ... ok
[INFO] [stdout] test src/priv_key.rs - priv_key::PrivKey::decrypt (line 64) ... ok
[INFO] [stdout] test src/auth_key.rs - auth_key::AuthKey::auth_out_msg (line 250) ... ok
[INFO] [stdout] test src/lib.rs - (line 36) ... ok
[INFO] [stdout] test src/auth_key.rs - auth_key::AuthKey::auth_in_msg (line 194) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::encode (line 306) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::ENGINE_TIME_MAX (line 54) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::engine_boots (line 126) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams (line 17) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::auth_params (line 223) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::ENGINE_BOOTS_MAX (line 44) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::priv_params (line 272) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::username (line 192) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_engine_boots (line 143) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_username (line 206) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_engine_id (line 109) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::for_discovery (line 76) ... ok
[INFO] [stdout] test src/localized_key.rs - localized_key::LocalizedKey::new (line 48) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::engine_time (line 159) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_engine_time (line 176) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::engine_id (line 95) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_auth_params_placeholder (line 254) ... ok
[INFO] [stdout] test src/localized_key.rs - localized_key::WithLocalizedKey::with_localized_key (line 102) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_auth_params (line 237) ... ok
[INFO] [stdout] test src/security_params.rs - security_params::SecurityParams::set_priv_params (line 286) ... ok
[INFO] [stdout] test src/auth_key.rs - auth_key::AuthKey::new (line 32) ... ok
[INFO] [stdout] test src/priv_key.rs - priv_key::PrivKey::encrypt (line 33) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.67s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e1b114e8e8ccb5639596369d1875e014cba260a704e0ec0766788a43a4b30376", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e1b114e8e8ccb5639596369d1875e014cba260a704e0ec0766788a43a4b30376", kill_on_drop: false }`
[INFO] [stdout] e1b114e8e8ccb5639596369d1875e014cba260a704e0ec0766788a43a4b30376