Dec 02 16:19:37.010 INFO testing snow-0.4.0 against 1.30.1 for beta-1.31-5
Dec 02 16:19:37.010 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:19:37.191 INFO [stdout] 1a9ff9276f392f3c762d4b8995596a425de673bb7be8af21bb1e605e9bfb59aa
Dec 02 16:19:37.195 INFO running `"docker" "start" "-a" "1a9ff9276f392f3c762d4b8995596a425de673bb7be8af21bb1e605e9bfb59aa"`
Dec 02 16:19:37.675 INFO [stderr] usermod: no changes
Dec 02 16:19:37.878 INFO [stderr]    Compiling byteorder v1.2.7
Dec 02 16:19:37.883 INFO [stderr]    Compiling subtle v0.7.1
Dec 02 16:19:37.889 INFO [stderr]    Compiling static_slice v0.0.3
Dec 02 16:19:37.894 INFO [stderr]    Compiling clear_on_drop v0.2.3
Dec 02 16:19:37.894 INFO [stderr]    Compiling chacha20-poly1305-aead v0.1.2
Dec 02 16:19:37.894 INFO [stderr]    Compiling blake2-rfc v0.2.18
Dec 02 16:19:37.911 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 02 16:19:37.911 INFO [stderr]    Compiling digest v0.7.6
Dec 02 16:19:41.359 INFO [stderr]    Compiling curve25519-dalek v0.19.1
Dec 02 16:19:46.353 INFO [stderr]    Compiling x25519-dalek v0.3.0
Dec 02 16:19:50.331 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.62s
Dec 02 16:19:50.343 INFO [stderr] su: No module specific data is present
Dec 02 16:19:51.231 INFO running `"docker" "inspect" "1a9ff9276f392f3c762d4b8995596a425de673bb7be8af21bb1e605e9bfb59aa"`
Dec 02 16:19:51.536 INFO running `"docker" "rm" "-f" "1a9ff9276f392f3c762d4b8995596a425de673bb7be8af21bb1e605e9bfb59aa"`
Dec 02 16:19:51.802 INFO [stdout] 1a9ff9276f392f3c762d4b8995596a425de673bb7be8af21bb1e605e9bfb59aa
Dec 02 16:19:51.811 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:19:52.317 INFO [stdout] 4eab72a3c7cd50dedb4bdc6b52e7af9b9988dc03570ffb2589959d3dee79db87
Dec 02 16:19:52.319 INFO running `"docker" "start" "-a" "4eab72a3c7cd50dedb4bdc6b52e7af9b9988dc03570ffb2589959d3dee79db87"`
Dec 02 16:19:53.477 INFO [stderr] usermod: no changes
Dec 02 16:19:53.674 INFO [stderr]    Compiling term v0.5.1
Dec 02 16:19:53.675 INFO [stderr]    Compiling criterion-plot v0.2.5
Dec 02 16:19:53.678 INFO [stderr]    Compiling criterion-stats v0.2.5
Dec 02 16:19:53.678 INFO [stderr]    Compiling csv-core v0.1.4
Dec 02 16:19:53.686 INFO [stderr]    Compiling handlebars v0.32.4
Dec 02 16:19:56.219 INFO [stderr]    Compiling csv v1.0.2
Dec 02 16:19:59.111 INFO [stderr]    Compiling simplelog v0.5.3
Dec 02 16:20:06.896 INFO [stderr]    Compiling criterion v0.2.5
Dec 02 16:20:16.643 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 02 16:20:24.212 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 30.71s
Dec 02 16:20:24.247 INFO [stderr] su: No module specific data is present
Dec 02 16:20:24.977 INFO running `"docker" "inspect" "4eab72a3c7cd50dedb4bdc6b52e7af9b9988dc03570ffb2589959d3dee79db87"`
Dec 02 16:20:25.215 INFO running `"docker" "rm" "-f" "4eab72a3c7cd50dedb4bdc6b52e7af9b9988dc03570ffb2589959d3dee79db87"`
Dec 02 16:20:25.501 INFO [stdout] 4eab72a3c7cd50dedb4bdc6b52e7af9b9988dc03570ffb2589959d3dee79db87
Dec 02 16:20:25.509 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:20:25.995 INFO [stdout] 67c7e89c6ba06cdf6ee682db488853cadf6bef734bfdf36d9b08320c38f478ea
Dec 02 16:20:25.997 INFO running `"docker" "start" "-a" "67c7e89c6ba06cdf6ee682db488853cadf6bef734bfdf36d9b08320c38f478ea"`
Dec 02 16:20:26.858 INFO [stderr] usermod: no changes
Dec 02 16:20:27.196 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.31s
Dec 02 16:20:27.223 INFO [stderr]      Running /target/debug/deps/snow-070ed460dce78769
Dec 02 16:20:27.230 INFO [stdout] 
Dec 02 16:20:27.231 INFO [stdout] running 22 tests
Dec 02 16:20:27.231 INFO [stdout] test builder::tests::test_builder_bad_spec ... ok
Dec 02 16:20:27.231 INFO [stdout] test builder::tests::test_builder_missing_prereqs ... ok
Dec 02 16:20:27.234 INFO [stdout] test builder::tests::test_builder_keygen ... ok
Dec 02 16:20:27.247 INFO [stdout] test params::tests::test_basic ... ok
Dec 02 16:20:27.247 INFO [stdout] test builder::tests::test_builder ... ok
Dec 02 16:20:27.247 INFO [stdout] test params::tests::test_modified_multi_psk_handshake ... ok
Dec 02 16:20:27.247 INFO [stdout] test params::tests::test_fallback_mod ... ok
Dec 02 16:20:27.247 INFO [stdout] test params::tests::test_modified_psk_handshake ... ok
Dec 02 16:20:27.248 INFO [stdout] test params::tests::test_simple_handshake ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_chachapoly_empty ... ok
Dec 02 16:20:27.251 INFO [stdout] test params::tests::test_multi_psk_mod ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_aes256_gcm ... ok
Dec 02 16:20:27.251 INFO [stdout] test params::tests::test_single_psk_mod ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_chachapoly_known_answer ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_chachapoly_nonempty ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_hmac_sha256_sha512 ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_blake2s ... ok
Dec 02 16:20:27.251 INFO [stdout] test resolvers::default::tests::test_poly1305 ... ok
Dec 02 16:20:27.252 INFO [stdout] test params::tests::test_psk_fallback_mod ... ok
Dec 02 16:20:27.252 INFO [stdout] test resolvers::default::tests::test_blake2b ... ok
Dec 02 16:20:27.252 INFO [stdout] test resolvers::default::tests::test_curve25519 ... ok
Dec 02 16:20:27.263 INFO [stdout] test resolvers::default::tests::test_sha256 ... ok
Dec 02 16:20:27.263 INFO [stdout] 
Dec 02 16:20:27.263 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:27.263 INFO [stdout] 
Dec 02 16:20:27.270 INFO [stderr]      Running /target/debug/deps/general-7f3e2f85083c687f
Dec 02 16:20:27.273 INFO [stdout] 
Dec 02 16:20:27.273 INFO [stdout] running 21 tests
Dec 02 16:20:27.276 INFO [stdout] test test_buffer_issues ... ok
Dec 02 16:20:27.335 INFO [stdout] test test_Npsk0_expected_value ... ok
Dec 02 16:20:27.362 INFO [stdout] test test_Xpsk0_expected_value ... ok
Dec 02 16:20:27.362 INFO [stdout] test test_buffer_issues_encrypted_handshake ... ok
Dec 02 16:20:27.364 INFO [stdout] test test_handshake_message_undersized_output_buffer ... ok
Dec 02 16:20:27.384 INFO [stdout] test test_XXpsk0_expected_value ... ok
Dec 02 16:20:27.391 INFO [stdout] test test_NNpsk0_sanity_session ... ok
Dec 02 16:20:27.391 INFO [stdout] test test_checkpointing ... ok
Dec 02 16:20:27.392 INFO [stdout] test test_oneway_initiator_enforcements ... ok
Dec 02 16:20:27.392 INFO [stdout] test test_noise_session_transition_change ... ok
Dec 02 16:20:27.395 INFO [stdout] test test_send_trait ... ok
Dec 02 16:20:27.411 INFO [stdout] test test_protocol_name ... ok
Dec 02 16:20:27.423 INFO [stdout] test test_rekey ... ok
Dec 02 16:20:27.423 INFO [stdout] test test_transport_message_exceeds_max_len ... ok
Dec 02 16:20:27.435 INFO [stdout] test test_transport_message_undersized_output_buffer ... ok
Dec 02 16:20:27.443 INFO [stdout] test test_get_remote_static ... ok
Dec 02 16:20:27.463 INFO [stdout] test test_sanity_session ... ok
Dec 02 16:20:27.463 INFO [stdout] test test_handshake_message_exceeds_max_len ... ok
Dec 02 16:20:27.467 INFO [stdout] test test_oneway_responder_enforcements ... ok
Dec 02 16:20:27.467 INFO [stdout] test test_set_psk ... ok
Dec 02 16:20:27.468 INFO [stdout] test test_XXpsk3_sanity_session ... ok
Dec 02 16:20:27.468 INFO [stdout] 
Dec 02 16:20:27.468 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:27.468 INFO [stdout] 
Dec 02 16:20:27.475 INFO [stderr]      Running /target/debug/deps/vectors-ced591a8ccbf801c
Dec 02 16:20:27.477 INFO [stdout] 
Dec 02 16:20:27.477 INFO [stdout] running 0 tests
Dec 02 16:20:27.477 INFO [stdout] 
Dec 02 16:20:27.477 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:27.477 INFO [stdout] 
Dec 02 16:20:27.482 INFO [stderr]    Doc-tests snow
Dec 02 16:20:30.852 INFO [stdout] 
Dec 02 16:20:30.853 INFO [stdout] running 7 tests
Dec 02 16:20:30.853 INFO [stdout] test src/session.rs - session::Session::into_stateless_transport_mode (line 299) ... ignored
Dec 02 16:20:30.853 INFO [stdout] test src/session.rs - session::Session::into_transport_mode (line 264) ... ignored
Dec 02 16:20:30.853 INFO [stdout] test src/session.rs - session::Session::is_handshake_finished (line 55) ... ignored
Dec 02 16:20:30.853 INFO [stdout] test src/session.rs - session::Session::was_write_payload_encrypted (line 34) ... ignored
Dec 02 16:20:39.043 INFO [stdout] test src/params/mod.rs - params::NoiseParams (line 118) ... ok
Dec 02 16:20:39.872 INFO [stdout] test src/builder.rs - builder::Builder (line 26) ... ok
Dec 02 16:20:40.079 INFO [stdout] test src/lib.rs -  (line 12) ... ok
Dec 02 16:20:40.079 INFO [stdout] 
Dec 02 16:20:40.079 INFO [stdout] test result: ok. 3 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Dec 02 16:20:40.079 INFO [stdout] 
Dec 02 16:20:40.091 INFO [stderr] su: No module specific data is present
Dec 02 16:20:40.660 INFO running `"docker" "inspect" "67c7e89c6ba06cdf6ee682db488853cadf6bef734bfdf36d9b08320c38f478ea"`
Dec 02 16:20:41.083 INFO running `"docker" "rm" "-f" "67c7e89c6ba06cdf6ee682db488853cadf6bef734bfdf36d9b08320c38f478ea"`
Dec 02 16:20:41.479 INFO [stdout] 67c7e89c6ba06cdf6ee682db488853cadf6bef734bfdf36d9b08320c38f478ea
