Dec 06 23:38:38.605 INFO testing secure-session-0.3.1 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 23:38:38.606 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 23:38:39.036 INFO [stdout] 9d1bfbeb4671552fac54a2ab39e04fb7faa1f5f6677ec72ee2cf59157f9cee30
Dec 06 23:38:39.038 INFO running `"docker" "start" "-a" "9d1bfbeb4671552fac54a2ab39e04fb7faa1f5f6677ec72ee2cf59157f9cee30"`
Dec 06 23:38:40.324 INFO [stderr] usermod: no changes
Dec 06 23:38:40.564 INFO [stderr]    Compiling bincode v0.9.2
Dec 06 23:38:40.564 INFO [stderr]    Compiling cookie v0.10.1
Dec 06 23:38:40.576 INFO [stderr]    Compiling iron v0.6.0
Dec 06 23:38:45.116 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 06 23:38:48.400 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.05s
Dec 06 23:38:48.409 INFO [stderr] su: No module specific data is present
Dec 06 23:38:49.252 INFO running `"docker" "inspect" "9d1bfbeb4671552fac54a2ab39e04fb7faa1f5f6677ec72ee2cf59157f9cee30"`
Dec 06 23:38:49.526 INFO running `"docker" "rm" "-f" "9d1bfbeb4671552fac54a2ab39e04fb7faa1f5f6677ec72ee2cf59157f9cee30"`
Dec 06 23:38:49.764 INFO [stdout] 9d1bfbeb4671552fac54a2ab39e04fb7faa1f5f6677ec72ee2cf59157f9cee30
Dec 06 23:38:49.766 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 23:38:50.188 INFO [stdout] 7ec7870118a875e6a58dede355da81886f7d826c678fd84303201638a7939d7a
Dec 06 23:38:50.194 INFO running `"docker" "start" "-a" "7ec7870118a875e6a58dede355da81886f7d826c678fd84303201638a7939d7a"`
Dec 06 23:38:51.396 INFO [stderr] usermod: no changes
Dec 06 23:38:51.616 INFO [stderr]    Compiling iron-test v0.6.0
Dec 06 23:38:54.193 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 06 23:39:01.783 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.36s
Dec 06 23:39:01.792 INFO [stderr] su: No module specific data is present
Dec 06 23:39:02.130 INFO running `"docker" "inspect" "7ec7870118a875e6a58dede355da81886f7d826c678fd84303201638a7939d7a"`
Dec 06 23:39:02.216 INFO running `"docker" "rm" "-f" "7ec7870118a875e6a58dede355da81886f7d826c678fd84303201638a7939d7a"`
Dec 06 23:39:02.379 INFO [stdout] 7ec7870118a875e6a58dede355da81886f7d826c678fd84303201638a7939d7a
Dec 06 23:39:02.383 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 23:39:02.584 INFO [stdout] d85c11ad06436863750a0b7ca2a76722e63d1e4ad92731c878567e1fc8faf7df
Dec 06 23:39:02.586 INFO running `"docker" "start" "-a" "d85c11ad06436863750a0b7ca2a76722e63d1e4ad92731c878567e1fc8faf7df"`
Dec 06 23:39:02.960 INFO [stderr] usermod: no changes
Dec 06 23:39:03.097 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 06 23:39:03.104 INFO [stdout] 
Dec 06 23:39:03.104 INFO [stdout] running 19 tests
Dec 06 23:39:03.104 INFO [stderr]      Running /target/debug/deps/secure_session-4f0e8db91e9681ab
Dec 06 23:39:03.108 INFO [stdout] test session::tests::aesgcm::serde_happy_path ... ok
Dec 06 23:39:03.108 INFO [stdout] test session::tests::aesgcm::serde_bad_data_end ... ok
Dec 06 23:39:03.108 INFO [stdout] test session::tests::aesgcm::serde_bad_data_start ... ok
Dec 06 23:39:03.108 INFO [stdout] test middleware::tests::aesgcm::no_expiry ... ok
Dec 06 23:39:03.108 INFO [stdout] test middleware::tests::aesgcm::sessions_expire ... ok
Dec 06 23:39:03.108 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_start ... ok
Dec 06 23:39:03.112 INFO [stdout] test middleware::tests::chacha20poly1305::sessions_expire ... ok
Dec 06 23:39:03.124 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_end ... ok
Dec 06 23:39:03.125 INFO [stdout] test middleware::tests::multisession_and_rotation ... ok
Dec 06 23:39:03.125 INFO [stdout] test session::tests::chacha20poly1305::serde_happy_path ... ok
Dec 06 23:39:03.125 INFO [stdout] test middleware::tests::chacha20poly1305::no_expiry ... ok
Dec 06 23:39:03.128 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::chacha20poly1305_then_chacha20poly1305 ... ok
Dec 06 23:39:03.128 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::no_previous ... ok
Dec 06 23:39:03.129 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::no_previous ... ok
Dec 06 23:39:03.129 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::aesgcm_then_aesgcm ... ok
Dec 06 23:39:03.130 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::no_previous ... ok
Dec 06 23:39:03.130 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::chacha20poly1305_then_aesgcm ... ok
Dec 06 23:39:03.130 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::aesgcm_then_chacha20poly1305 ... ok
Dec 06 23:39:03.130 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::no_previous ... ok
Dec 06 23:39:03.130 INFO [stdout] 
Dec 06 23:39:03.130 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 23:39:03.130 INFO [stdout] 
Dec 06 23:39:03.131 INFO [stderr]    Doc-tests secure_session
Dec 06 23:39:07.334 INFO [stdout] 
Dec 06 23:39:07.334 INFO [stdout] running 1 test
Dec 06 23:39:14.404 INFO [stdout] test src/lib.rs -  (line 8) ... ok
Dec 06 23:39:14.404 INFO [stdout] 
Dec 06 23:39:14.404 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 23:39:14.404 INFO [stdout] 
Dec 06 23:39:14.423 INFO [stderr] su: No module specific data is present
Dec 06 23:39:14.738 INFO running `"docker" "inspect" "d85c11ad06436863750a0b7ca2a76722e63d1e4ad92731c878567e1fc8faf7df"`
Dec 06 23:39:14.898 INFO running `"docker" "rm" "-f" "d85c11ad06436863750a0b7ca2a76722e63d1e4ad92731c878567e1fc8faf7df"`
Dec 06 23:39:15.039 INFO [stdout] d85c11ad06436863750a0b7ca2a76722e63d1e4ad92731c878567e1fc8faf7df