Nov 17 22:28:25.878 INFO testing snow-0.4.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 22:28:25.878 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 22:28:26.291 INFO [stdout] e3b9fe5a0a0706e14cd7859ff70b75310491b8adf843bb6fe0d340a8de5b4d95
Nov 17 22:28:26.297 INFO running `"docker" "start" "-a" "e3b9fe5a0a0706e14cd7859ff70b75310491b8adf843bb6fe0d340a8de5b4d95"`
Nov 17 22:28:27.233 INFO [stderr] usermod: no changes
Nov 17 22:28:27.495 INFO [stderr]    Compiling byteorder v1.2.7
Nov 17 22:28:27.495 INFO [stderr]    Compiling subtle v0.7.1
Nov 17 22:28:27.505 INFO [stderr]    Compiling static_slice v0.0.3
Nov 17 22:28:27.505 INFO [stderr]    Compiling clear_on_drop v0.2.3
Nov 17 22:28:27.511 INFO [stderr]    Compiling chacha20-poly1305-aead v0.1.2
Nov 17 22:28:27.514 INFO [stderr]    Compiling snow v0.4.0 (/source)
Nov 17 22:28:29.673 INFO [stderr]    Compiling curve25519-dalek v0.19.1
Nov 17 22:28:33.542 INFO [stderr]    Compiling x25519-dalek v0.3.0
Nov 17 22:28:37.236 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.98s
Nov 17 22:28:37.246 INFO [stderr] su: No module specific data is present
Nov 17 22:28:37.524 INFO running `"docker" "inspect" "e3b9fe5a0a0706e14cd7859ff70b75310491b8adf843bb6fe0d340a8de5b4d95"`
Nov 17 22:28:37.617 INFO running `"docker" "rm" "-f" "e3b9fe5a0a0706e14cd7859ff70b75310491b8adf843bb6fe0d340a8de5b4d95"`
Nov 17 22:28:37.747 INFO [stdout] e3b9fe5a0a0706e14cd7859ff70b75310491b8adf843bb6fe0d340a8de5b4d95
Nov 17 22:28:37.749 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 22:28:37.929 INFO [stdout] f48d07f2bc2601bc118b6ed7f46d1cf82941edc32e15305b96a3ea1d5793eabe
Nov 17 22:28:37.932 INFO running `"docker" "start" "-a" "f48d07f2bc2601bc118b6ed7f46d1cf82941edc32e15305b96a3ea1d5793eabe"`
Nov 17 22:28:39.683 INFO [stderr] usermod: no changes
Nov 17 22:28:39.931 INFO [stderr]    Compiling term v0.5.1
Nov 17 22:28:39.935 INFO [stderr]    Compiling criterion-plot v0.2.5
Nov 17 22:28:43.303 INFO [stderr]    Compiling simplelog v0.5.3
Nov 17 22:28:45.035 INFO [stderr]    Compiling criterion v0.2.5
Nov 17 22:28:55.731 INFO [stderr]    Compiling snow v0.4.0 (/source)
Nov 17 22:29:01.454 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.74s
Nov 17 22:29:01.475 INFO [stderr] su: No module specific data is present
Nov 17 22:29:01.822 INFO running `"docker" "inspect" "f48d07f2bc2601bc118b6ed7f46d1cf82941edc32e15305b96a3ea1d5793eabe"`
Nov 17 22:29:01.949 INFO running `"docker" "rm" "-f" "f48d07f2bc2601bc118b6ed7f46d1cf82941edc32e15305b96a3ea1d5793eabe"`
Nov 17 22:29:02.154 INFO [stdout] f48d07f2bc2601bc118b6ed7f46d1cf82941edc32e15305b96a3ea1d5793eabe
Nov 17 22:29:02.157 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 22:29:02.499 INFO [stdout] 2e0bbe1f9a25d16b897c8dbc1a8c7669b38e71dfb2bd193f577b535a2f8666f2
Nov 17 22:29:02.501 INFO running `"docker" "start" "-a" "2e0bbe1f9a25d16b897c8dbc1a8c7669b38e71dfb2bd193f577b535a2f8666f2"`
Nov 17 22:29:03.460 INFO [stderr] usermod: no changes
Nov 17 22:29:03.687 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Nov 17 22:29:03.709 INFO [stderr]      Running /target/debug/deps/snow-aaa3a0cb2234be4e
Nov 17 22:29:03.717 INFO [stdout] 
Nov 17 22:29:03.717 INFO [stdout] running 22 tests
Nov 17 22:29:03.723 INFO [stdout] test params::tests::test_fallback_mod ... ok
Nov 17 22:29:03.729 INFO [stdout] test params::tests::test_basic ... ok
Nov 17 22:29:03.729 INFO [stdout] test builder::tests::test_builder_bad_spec ... ok
Nov 17 22:29:03.729 INFO [stdout] test builder::tests::test_builder ... ok
Nov 17 22:29:03.731 INFO [stdout] test builder::tests::test_builder_missing_prereqs ... ok
Nov 17 22:29:03.732 INFO [stdout] test params::tests::test_psk_fallback_mod ... ok
Nov 17 22:29:03.732 INFO [stdout] test params::tests::test_modified_multi_psk_handshake ... ok
Nov 17 22:29:03.732 INFO [stdout] test builder::tests::test_builder_keygen ... ok
Nov 17 22:29:03.752 INFO [stdout] test params::tests::test_multi_psk_mod ... ok
Nov 17 22:29:03.752 INFO [stdout] test params::tests::test_simple_handshake ... ok
Nov 17 22:29:03.753 INFO [stdout] test params::tests::test_single_psk_mod ... ok
Nov 17 22:29:03.753 INFO [stdout] test params::tests::test_modified_psk_handshake ... ok
Nov 17 22:29:03.754 INFO [stdout] test resolvers::default::tests::test_aes256_gcm ... ok
Nov 17 22:29:03.754 INFO [stdout] test resolvers::default::tests::test_blake2s ... ok
Nov 17 22:29:03.754 INFO [stdout] test resolvers::default::tests::test_blake2b ... ok
Nov 17 22:29:03.754 INFO [stdout] test resolvers::default::tests::test_hmac_sha256_sha512 ... ok
Nov 17 22:29:03.755 INFO [stdout] test resolvers::default::tests::test_poly1305 ... ok
Nov 17 22:29:03.755 INFO [stdout] test resolvers::default::tests::test_chachapoly_known_answer ... ok
Nov 17 22:29:03.755 INFO [stdout] test resolvers::default::tests::test_chachapoly_nonempty ... ok
Nov 17 22:29:03.756 INFO [stdout] test resolvers::default::tests::test_sha256 ... ok
Nov 17 22:29:03.756 INFO [stdout] test resolvers::default::tests::test_curve25519 ... ok
Nov 17 22:29:03.763 INFO [stdout] test resolvers::default::tests::test_chachapoly_empty ... ok
Nov 17 22:29:03.763 INFO [stderr]      Running /target/debug/deps/general-9016dcd6d2e4369e
Nov 17 22:29:03.764 INFO [stdout] 
Nov 17 22:29:03.764 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 22:29:03.764 INFO [stdout] 
Nov 17 22:29:03.771 INFO [stdout] 
Nov 17 22:29:03.771 INFO [stdout] running 21 tests
Nov 17 22:29:03.787 INFO [stdout] test test_Npsk0_expected_value ... ok
Nov 17 22:29:03.839 INFO [stdout] test test_NNpsk0_sanity_session ... ok
Nov 17 22:29:03.839 INFO [stdout] test test_buffer_issues ... ok
Nov 17 22:29:03.859 INFO [stdout] test test_Xpsk0_expected_value ... ok
Nov 17 22:29:03.881 INFO [stdout] test test_handshake_message_undersized_output_buffer ... ok
Nov 17 22:29:03.885 INFO [stdout] test test_checkpointing ... ok
Nov 17 22:29:03.907 INFO [stdout] test test_oneway_initiator_enforcements ... ok
Nov 17 22:29:03.921 INFO [stdout] test test_noise_session_transition_change ... ok
Nov 17 22:29:03.935 INFO [stdout] test test_protocol_name ... ok
Nov 17 22:29:03.954 INFO [stdout] test test_handshake_message_exceeds_max_len ... ok
Nov 17 22:29:03.956 INFO [stdout] test test_send_trait ... ok
Nov 17 22:29:03.956 INFO [stdout] test test_oneway_responder_enforcements ... ok
Nov 17 22:29:03.975 INFO [stdout] test test_rekey ... ok
Nov 17 22:29:03.976 INFO [stdout] test test_buffer_issues_encrypted_handshake ... ok
Nov 17 22:29:03.976 INFO [stdout] test test_XXpsk0_expected_value ... ok
Nov 17 22:29:03.976 INFO [stdout] test test_XXpsk3_sanity_session ... ok
Nov 17 22:29:03.976 INFO [stdout] test test_transport_message_exceeds_max_len ... ok
Nov 17 22:29:03.977 INFO [stdout] test test_get_remote_static ... ok
Nov 17 22:29:03.977 INFO [stdout] test test_sanity_session ... ok
Nov 17 22:29:03.983 INFO [stdout] test test_transport_message_undersized_output_buffer ... ok
Nov 17 22:29:03.997 INFO [stdout] test test_set_psk ... ok
Nov 17 22:29:03.998 INFO [stdout] 
Nov 17 22:29:03.998 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 22:29:03.998 INFO [stdout] 
Nov 17 22:29:04.003 INFO [stderr]      Running /target/debug/deps/vectors-5da91324947824ef
Nov 17 22:29:04.009 INFO [stdout] 
Nov 17 22:29:04.009 INFO [stdout] running 0 tests
Nov 17 22:29:04.009 INFO [stdout] 
Nov 17 22:29:04.009 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 22:29:04.009 INFO [stdout] 
Nov 17 22:29:04.010 INFO [stderr]    Doc-tests snow
Nov 17 22:29:06.757 INFO [stdout] 
Nov 17 22:29:06.757 INFO [stdout] running 7 tests
Nov 17 22:29:06.757 INFO [stdout] test src/session.rs - session::Session::into_stateless_transport_mode (line 299) ... ignored
Nov 17 22:29:06.757 INFO [stdout] test src/session.rs - session::Session::into_transport_mode (line 264) ... ignored
Nov 17 22:29:06.757 INFO [stdout] test src/session.rs - session::Session::is_handshake_finished (line 55) ... ignored
Nov 17 22:29:06.757 INFO [stdout] test src/session.rs - session::Session::was_write_payload_encrypted (line 34) ... ignored
Nov 17 22:29:13.159 INFO [stdout] test src/builder.rs - builder::Builder (line 26) ... ok
Nov 17 22:29:13.401 INFO [stdout] test src/lib.rs -  (line 12) ... ok
Nov 17 22:29:13.785 INFO [stdout] test src/params/mod.rs - params::NoiseParams (line 118) ... ok
Nov 17 22:29:13.785 INFO [stdout] 
Nov 17 22:29:13.785 INFO [stdout] test result: ok. 3 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Nov 17 22:29:13.785 INFO [stdout] 
Nov 17 22:29:13.799 INFO [stderr] su: No module specific data is present
Nov 17 22:29:14.147 INFO running `"docker" "inspect" "2e0bbe1f9a25d16b897c8dbc1a8c7669b38e71dfb2bd193f577b535a2f8666f2"`
Nov 17 22:29:14.354 INFO running `"docker" "rm" "-f" "2e0bbe1f9a25d16b897c8dbc1a8c7669b38e71dfb2bd193f577b535a2f8666f2"`
Nov 17 22:29:14.537 INFO [stdout] 2e0bbe1f9a25d16b897c8dbc1a8c7669b38e71dfb2bd193f577b535a2f8666f2