Dec 14 20:40:18.375 INFO testing secure-session-0.3.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 20:40:18.375 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 20:40:18.543 INFO [stdout] 7099f3625051dd5730c199dae56b4c6ebd46212c08f8f58db3b2d34ea2e704b1
Dec 14 20:40:18.554 INFO running `"docker" "start" "-a" "7099f3625051dd5730c199dae56b4c6ebd46212c08f8f58db3b2d34ea2e704b1"`
Dec 14 20:40:19.606 INFO [stderr] usermod: no changes
Dec 14 20:40:20.015 INFO [stderr]    Compiling cookie v0.10.1
Dec 14 20:40:22.155 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 14 20:40:25.559 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.90s
Dec 14 20:40:25.570 INFO [stderr] su: No module specific data is present
Dec 14 20:40:26.455 INFO running `"docker" "inspect" "7099f3625051dd5730c199dae56b4c6ebd46212c08f8f58db3b2d34ea2e704b1"`
Dec 14 20:40:26.752 INFO running `"docker" "rm" "-f" "7099f3625051dd5730c199dae56b4c6ebd46212c08f8f58db3b2d34ea2e704b1"`
Dec 14 20:40:26.983 INFO [stdout] 7099f3625051dd5730c199dae56b4c6ebd46212c08f8f58db3b2d34ea2e704b1
Dec 14 20:40:26.990 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 20:40:27.555 INFO [stdout] cd63fb182f1589fabee3828b5062d3840d6d0258c6c97b95ec655dea0ce2696b
Dec 14 20:40:27.560 INFO running `"docker" "start" "-a" "cd63fb182f1589fabee3828b5062d3840d6d0258c6c97b95ec655dea0ce2696b"`
Dec 14 20:40:28.612 INFO [stderr] usermod: no changes
Dec 14 20:40:28.766 INFO [stderr]    Compiling iron-test v0.6.0
Dec 14 20:40:31.099 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 14 20:40:38.833 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.19s
Dec 14 20:40:38.846 INFO [stderr] su: No module specific data is present
Dec 14 20:40:39.203 INFO running `"docker" "inspect" "cd63fb182f1589fabee3828b5062d3840d6d0258c6c97b95ec655dea0ce2696b"`
Dec 14 20:40:39.286 INFO running `"docker" "rm" "-f" "cd63fb182f1589fabee3828b5062d3840d6d0258c6c97b95ec655dea0ce2696b"`
Dec 14 20:40:39.371 INFO [stdout] cd63fb182f1589fabee3828b5062d3840d6d0258c6c97b95ec655dea0ce2696b
Dec 14 20:40:39.372 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 20:40:39.586 INFO [stdout] 8c2b02a61880eb4bf1573243a294bcc0cff19f32d9b6bb25466c6b0dac96cea4
Dec 14 20:40:39.588 INFO running `"docker" "start" "-a" "8c2b02a61880eb4bf1573243a294bcc0cff19f32d9b6bb25466c6b0dac96cea4"`
Dec 14 20:40:39.961 INFO [stderr] usermod: no changes
Dec 14 20:40:40.089 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 14 20:40:40.105 INFO [stderr]      Running /target/debug/deps/secure_session-99209b78e14525f4
Dec 14 20:40:40.107 INFO [stdout] 
Dec 14 20:40:40.107 INFO [stdout] running 19 tests
Dec 14 20:40:40.109 INFO [stdout] test session::tests::aesgcm::serde_bad_data_end ... ok
Dec 14 20:40:40.110 INFO [stdout] test session::tests::aesgcm::serde_bad_data_start ... ok
Dec 14 20:40:40.111 INFO [stdout] test session::tests::aesgcm::serde_happy_path ... ok
Dec 14 20:40:40.111 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_end ... ok
Dec 14 20:40:40.111 INFO [stdout] test middleware::tests::aesgcm::no_expiry ... ok
Dec 14 20:40:40.111 INFO [stdout] test middleware::tests::chacha20poly1305::sessions_expire ... ok
Dec 14 20:40:40.111 INFO [stdout] test middleware::tests::multisession_and_rotation ... ok
Dec 14 20:40:40.111 INFO [stdout] test middleware::tests::aesgcm::sessions_expire ... ok
Dec 14 20:40:40.111 INFO [stdout] test middleware::tests::chacha20poly1305::no_expiry ... ok
Dec 14 20:40:40.111 INFO [stdout] test session::tests::chacha20poly1305::serde_happy_path ... ok
Dec 14 20:40:40.111 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::chacha20poly1305_then_aesgcm ... ok
Dec 14 20:40:40.111 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::no_previous ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_start ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::no_previous ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::aesgcm_then_aesgcm ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::chacha20poly1305_then_chacha20poly1305 ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::no_previous ... ok
Dec 14 20:40:40.112 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::no_previous ... ok
Dec 14 20:40:40.115 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::aesgcm_then_chacha20poly1305 ... ok
Dec 14 20:40:40.115 INFO [stdout] 
Dec 14 20:40:40.115 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:40:40.115 INFO [stdout] 
Dec 14 20:40:40.116 INFO [stderr]    Doc-tests secure_session
Dec 14 20:40:44.362 INFO [stdout] 
Dec 14 20:40:44.362 INFO [stdout] running 1 test
Dec 14 20:40:51.445 INFO [stdout] test src/lib.rs -  (line 8) ... ok
Dec 14 20:40:51.445 INFO [stdout] 
Dec 14 20:40:51.445 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:40:51.445 INFO [stdout] 
Dec 14 20:40:51.460 INFO [stderr] su: No module specific data is present
Dec 14 20:40:51.819 INFO running `"docker" "inspect" "8c2b02a61880eb4bf1573243a294bcc0cff19f32d9b6bb25466c6b0dac96cea4"`
Dec 14 20:40:51.924 INFO running `"docker" "rm" "-f" "8c2b02a61880eb4bf1573243a294bcc0cff19f32d9b6bb25466c6b0dac96cea4"`
Dec 14 20:40:52.003 INFO [stdout] 8c2b02a61880eb4bf1573243a294bcc0cff19f32d9b6bb25466c6b0dac96cea4