Dec 14 19:20:22.478 INFO testing snow-0.4.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 19:20:22.479 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 19:20:22.765 INFO [stdout] ad35d0218063e41a5c186c5b81af8395df28d1a976ca3a9f83565612af11897f
Dec 14 19:20:22.772 INFO running `"docker" "start" "-a" "ad35d0218063e41a5c186c5b81af8395df28d1a976ca3a9f83565612af11897f"`
Dec 14 19:20:23.746 INFO [stderr] usermod: no changes
Dec 14 19:20:23.996 INFO [stderr]    Compiling byteorder v1.2.7
Dec 14 19:20:23.996 INFO [stderr]    Compiling subtle v0.7.1
Dec 14 19:20:23.996 INFO [stderr]    Compiling static_slice v0.0.3
Dec 14 19:20:23.996 INFO [stderr]    Compiling chacha20-poly1305-aead v0.1.2
Dec 14 19:20:23.996 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 14 19:20:25.919 INFO [stderr]    Compiling curve25519-dalek v0.19.1
Dec 14 19:20:30.995 INFO [stderr]    Compiling x25519-dalek v0.3.0
Dec 14 19:20:35.105 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.31s
Dec 14 19:20:35.127 INFO [stderr] su: No module specific data is present
Dec 14 19:20:36.343 INFO running `"docker" "inspect" "ad35d0218063e41a5c186c5b81af8395df28d1a976ca3a9f83565612af11897f"`
Dec 14 19:20:36.487 INFO running `"docker" "rm" "-f" "ad35d0218063e41a5c186c5b81af8395df28d1a976ca3a9f83565612af11897f"`
Dec 14 19:20:36.576 INFO [stdout] ad35d0218063e41a5c186c5b81af8395df28d1a976ca3a9f83565612af11897f
Dec 14 19:20:36.578 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 19:20:36.852 INFO [stdout] ee8a38fa52c5357a59f12d2b444da61627110e83f1d4a4e182a235ebb6678861
Dec 14 19:20:36.857 INFO running `"docker" "start" "-a" "ee8a38fa52c5357a59f12d2b444da61627110e83f1d4a4e182a235ebb6678861"`
Dec 14 19:20:37.407 INFO [stderr] usermod: no changes
Dec 14 19:20:37.605 INFO [stderr]    Compiling term v0.5.1
Dec 14 19:20:37.607 INFO [stderr]    Compiling criterion-plot v0.2.5
Dec 14 19:20:41.575 INFO [stderr]    Compiling simplelog v0.5.3
Dec 14 19:20:43.267 INFO [stderr]    Compiling criterion v0.2.5
Dec 14 19:20:55.037 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 14 19:21:03.116 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 25.69s
Dec 14 19:21:03.132 INFO [stderr] su: No module specific data is present
Dec 14 19:21:03.687 INFO running `"docker" "inspect" "ee8a38fa52c5357a59f12d2b444da61627110e83f1d4a4e182a235ebb6678861"`
Dec 14 19:21:03.913 INFO running `"docker" "rm" "-f" "ee8a38fa52c5357a59f12d2b444da61627110e83f1d4a4e182a235ebb6678861"`
Dec 14 19:21:04.103 INFO [stdout] ee8a38fa52c5357a59f12d2b444da61627110e83f1d4a4e182a235ebb6678861
Dec 14 19:21:04.104 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 19:21:04.459 INFO [stdout] 457bc4d25e46707e108c29731e081ee9d1feb148b7062dc04a293dd0ac6130fa
Dec 14 19:21:04.470 INFO running `"docker" "start" "-a" "457bc4d25e46707e108c29731e081ee9d1feb148b7062dc04a293dd0ac6130fa"`
Dec 14 19:21:05.059 INFO [stderr] usermod: no changes
Dec 14 19:21:05.221 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 14 19:21:05.239 INFO [stderr]      Running /target/debug/deps/snow-93eadaa38e0c4d05
Dec 14 19:21:05.242 INFO [stdout] 
Dec 14 19:21:05.242 INFO [stdout] running 22 tests
Dec 14 19:21:05.245 INFO [stdout] test builder::tests::test_builder_bad_spec ... ok
Dec 14 19:21:05.245 INFO [stdout] test params::tests::test_basic ... ok
Dec 14 19:21:05.245 INFO [stdout] test builder::tests::test_builder_missing_prereqs ... ok
Dec 14 19:21:05.255 INFO [stdout] test builder::tests::test_builder_keygen ... ok
Dec 14 19:21:05.255 INFO [stdout] test params::tests::test_fallback_mod ... ok
Dec 14 19:21:05.255 INFO [stdout] test builder::tests::test_builder ... ok
Dec 14 19:21:05.255 INFO [stdout] test params::tests::test_psk_fallback_mod ... ok
Dec 14 19:21:05.255 INFO [stdout] test params::tests::test_multi_psk_mod ... ok
Dec 14 19:21:05.257 INFO [stdout] test params::tests::test_modified_multi_psk_handshake ... ok
Dec 14 19:21:05.257 INFO [stdout] test params::tests::test_single_psk_mod ... ok
Dec 14 19:21:05.257 INFO [stdout] test params::tests::test_simple_handshake ... ok
Dec 14 19:21:05.257 INFO [stdout] test params::tests::test_modified_psk_handshake ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_aes256_gcm ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_chachapoly_empty ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_chachapoly_nonempty ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_chachapoly_known_answer ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_hmac_sha256_sha512 ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_blake2b ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_blake2s ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_poly1305 ... ok
Dec 14 19:21:05.257 INFO [stdout] test resolvers::default::tests::test_sha256 ... ok
Dec 14 19:21:05.258 INFO [stdout] test resolvers::default::tests::test_curve25519 ... ok
Dec 14 19:21:05.258 INFO [stdout] 
Dec 14 19:21:05.258 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:21:05.258 INFO [stdout] 
Dec 14 19:21:05.260 INFO [stderr]      Running /target/debug/deps/general-b684fb78665355e5
Dec 14 19:21:05.261 INFO [stdout] 
Dec 14 19:21:05.261 INFO [stdout] running 21 tests
Dec 14 19:21:05.263 INFO [stdout] test test_buffer_issues ... ok
Dec 14 19:21:05.275 INFO [stdout] test test_NNpsk0_sanity_session ... ok
Dec 14 19:21:05.283 INFO [stdout] test test_Xpsk0_expected_value ... ok
Dec 14 19:21:05.283 INFO [stdout] test test_checkpointing ... ok
Dec 14 19:21:05.287 INFO [stdout] test test_buffer_issues_encrypted_handshake ... ok
Dec 14 19:21:05.299 INFO [stdout] test test_handshake_message_undersized_output_buffer ... ok
Dec 14 19:21:05.307 INFO [stdout] test test_noise_session_transition_change ... ok
Dec 14 19:21:05.308 INFO [stdout] test test_handshake_message_exceeds_max_len ... ok
Dec 14 19:21:05.308 INFO [stdout] test test_protocol_name ... ok
Dec 14 19:21:05.311 INFO [stdout] test test_Npsk0_expected_value ... ok
Dec 14 19:21:05.311 INFO [stdout] test test_oneway_responder_enforcements ... ok
Dec 14 19:21:05.313 INFO [stdout] test test_oneway_initiator_enforcements ... ok
Dec 14 19:21:05.319 INFO [stdout] test test_XXpsk0_expected_value ... ok
Dec 14 19:21:05.319 INFO [stdout] test test_sanity_session ... ok
Dec 14 19:21:05.323 INFO [stdout] test test_transport_message_undersized_output_buffer ... ok
Dec 14 19:21:05.325 INFO [stdout] test test_XXpsk3_sanity_session ... ok
Dec 14 19:21:05.326 INFO [stdout] test test_rekey ... ok
Dec 14 19:21:05.327 INFO [stdout] test test_send_trait ... ok
Dec 14 19:21:05.327 INFO [stdout] test test_transport_message_exceeds_max_len ... ok
Dec 14 19:21:05.328 INFO [stdout] test test_get_remote_static ... ok
Dec 14 19:21:05.341 INFO [stderr]      Running /target/debug/deps/vectors-3daf393730c8b499
Dec 14 19:21:05.341 INFO [stdout] test test_set_psk ... ok
Dec 14 19:21:05.341 INFO [stdout] 
Dec 14 19:21:05.341 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:21:05.341 INFO [stdout] 
Dec 14 19:21:05.342 INFO [stdout] 
Dec 14 19:21:05.342 INFO [stdout] running 0 tests
Dec 14 19:21:05.342 INFO [stdout] 
Dec 14 19:21:05.342 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:21:05.342 INFO [stdout] 
Dec 14 19:21:05.343 INFO [stderr]    Doc-tests snow
Dec 14 19:21:08.699 INFO [stdout] 
Dec 14 19:21:08.699 INFO [stdout] running 7 tests
Dec 14 19:21:08.699 INFO [stdout] test src/session.rs - session::Session::into_stateless_transport_mode (line 299) ... ignored
Dec 14 19:21:08.699 INFO [stdout] test src/session.rs - session::Session::into_transport_mode (line 264) ... ignored
Dec 14 19:21:08.699 INFO [stdout] test src/session.rs - session::Session::is_handshake_finished (line 55) ... ignored
Dec 14 19:21:08.699 INFO [stdout] test src/session.rs - session::Session::was_write_payload_encrypted (line 34) ... ignored
Dec 14 19:21:14.969 INFO [stdout] test src/lib.rs -  (line 12) ... ok
Dec 14 19:21:15.287 INFO [stdout] test src/builder.rs - builder::Builder (line 26) ... ok
Dec 14 19:21:15.327 INFO [stdout] test src/params/mod.rs - params::NoiseParams (line 118) ... ok
Dec 14 19:21:15.327 INFO [stdout] 
Dec 14 19:21:15.327 INFO [stdout] test result: ok. 3 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Dec 14 19:21:15.327 INFO [stdout] 
Dec 14 19:21:15.342 INFO [stderr] su: No module specific data is present
Dec 14 19:21:15.612 INFO running `"docker" "inspect" "457bc4d25e46707e108c29731e081ee9d1feb148b7062dc04a293dd0ac6130fa"`
Dec 14 19:21:15.680 INFO running `"docker" "rm" "-f" "457bc4d25e46707e108c29731e081ee9d1feb148b7062dc04a293dd0ac6130fa"`
Dec 14 19:21:15.755 INFO [stdout] 457bc4d25e46707e108c29731e081ee9d1feb148b7062dc04a293dd0ac6130fa