Dec 14 20:40:18.644 INFO testing secure-session-0.3.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 20:40:18.644 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:19.172 INFO [stdout] a55044b82ad231e66760fd971ecb2a81ca67d54eb399393b414f537757e715e0
Dec 14 20:40:19.176 INFO running `"docker" "start" "-a" "a55044b82ad231e66760fd971ecb2a81ca67d54eb399393b414f537757e715e0"`
Dec 14 20:40:20.302 INFO [stderr] usermod: no changes
Dec 14 20:40:20.583 INFO [stderr]    Compiling bincode v0.9.2
Dec 14 20:40:20.583 INFO [stderr]    Compiling cookie v0.10.1
Dec 14 20:40:22.887 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 14 20:40:26.063 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.71s
Dec 14 20:40:26.067 INFO [stderr] su: No module specific data is present
Dec 14 20:40:26.776 INFO running `"docker" "inspect" "a55044b82ad231e66760fd971ecb2a81ca67d54eb399393b414f537757e715e0"`
Dec 14 20:40:26.992 INFO running `"docker" "rm" "-f" "a55044b82ad231e66760fd971ecb2a81ca67d54eb399393b414f537757e715e0"`
Dec 14 20:40:27.305 INFO [stdout] a55044b82ad231e66760fd971ecb2a81ca67d54eb399393b414f537757e715e0
Dec 14 20:40:27.308 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.751 INFO [stdout] 59e71de483a00dab12be145ce65496eea14889d8e629bcf5203f43ef126a7e83
Dec 14 20:40:27.759 INFO running `"docker" "start" "-a" "59e71de483a00dab12be145ce65496eea14889d8e629bcf5203f43ef126a7e83"`
Dec 14 20:40:28.810 INFO [stderr] usermod: no changes
Dec 14 20:40:28.927 INFO [stderr]    Compiling uuid v0.5.1
Dec 14 20:40:29.824 INFO [stderr]    Compiling iron-test v0.6.0
Dec 14 20:40:32.146 INFO [stderr]    Compiling secure-session v0.3.1 (/source)
Dec 14 20:40:39.121 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.29s
Dec 14 20:40:39.131 INFO [stderr] su: No module specific data is present
Dec 14 20:40:39.371 INFO running `"docker" "inspect" "59e71de483a00dab12be145ce65496eea14889d8e629bcf5203f43ef126a7e83"`
Dec 14 20:40:39.491 INFO running `"docker" "rm" "-f" "59e71de483a00dab12be145ce65496eea14889d8e629bcf5203f43ef126a7e83"`
Dec 14 20:40:39.579 INFO [stdout] 59e71de483a00dab12be145ce65496eea14889d8e629bcf5203f43ef126a7e83
Dec 14 20:40:39.581 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.759 INFO [stdout] 5f353db70c4a215e4ab16b223602d9d41a1911971db698163d97a067ecd7d5ff
Dec 14 20:40:39.761 INFO running `"docker" "start" "-a" "5f353db70c4a215e4ab16b223602d9d41a1911971db698163d97a067ecd7d5ff"`
Dec 14 20:40:40.246 INFO [stderr] usermod: no changes
Dec 14 20:40:40.395 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 14 20:40:40.407 INFO [stderr]      Running /target/debug/deps/secure_session-c6b33eef349d6614
Dec 14 20:40:40.407 INFO [stdout] 
Dec 14 20:40:40.407 INFO [stdout] running 19 tests
Dec 14 20:40:40.408 INFO [stdout] test session::tests::aesgcm::serde_bad_data_start ... ok
Dec 14 20:40:40.415 INFO [stdout] test session::tests::aesgcm::serde_happy_path ... ok
Dec 14 20:40:40.415 INFO [stdout] test session::tests::aesgcm::serde_bad_data_end ... ok
Dec 14 20:40:40.415 INFO [stdout] test middleware::tests::multisession_and_rotation ... ok
Dec 14 20:40:40.415 INFO [stdout] test middleware::tests::chacha20poly1305::sessions_expire ... ok
Dec 14 20:40:40.415 INFO [stdout] test middleware::tests::chacha20poly1305::no_expiry ... ok
Dec 14 20:40:40.415 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_end ... ok
Dec 14 20:40:40.415 INFO [stdout] test session::tests::chacha20poly1305::serde_bad_data_start ... ok
Dec 14 20:40:40.415 INFO [stdout] test session::tests::chacha20poly1305::serde_happy_path ... ok
Dec 14 20:40:40.417 INFO [stdout] test middleware::tests::aesgcm::sessions_expire ... ok
Dec 14 20:40:40.417 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::no_previous ... ok
Dec 14 20:40:40.417 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::no_previous ... ok
Dec 14 20:40:40.417 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::chacha20poly1305_then_aesgcm ... ok
Dec 14 20:40:40.417 INFO [stdout] test session::tests::multi::chacha20poly1305_then_aesgcm::no_previous ... ok
Dec 14 20:40:40.419 INFO [stdout] test session::tests::multi::aesgcm_then_chacha20poly1305::aesgcm_then_chacha20poly1305 ... ok
Dec 14 20:40:40.421 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::chacha20poly1305_then_chacha20poly1305 ... ok
Dec 14 20:40:40.421 INFO [stdout] test session::tests::multi::aesgcm_then_aesgcm::aesgcm_then_aesgcm ... ok
Dec 14 20:40:40.421 INFO [stdout] test session::tests::multi::chacha20poly1305_then_chacha20poly1305::no_previous ... ok
Dec 14 20:40:40.422 INFO [stdout] test middleware::tests::aesgcm::no_expiry ... ok
Dec 14 20:40:40.422 INFO [stdout] 
Dec 14 20:40:40.422 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:40:40.422 INFO [stdout] 
Dec 14 20:40:40.424 INFO [stderr]    Doc-tests secure_session
Dec 14 20:40:44.810 INFO [stdout] 
Dec 14 20:40:44.810 INFO [stdout] running 1 test
Dec 14 20:40:52.103 INFO [stdout] test src/lib.rs -  (line 8) ... ok
Dec 14 20:40:52.103 INFO [stdout] 
Dec 14 20:40:52.103 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:40:52.103 INFO [stdout] 
Dec 14 20:40:52.116 INFO [stderr] su: No module specific data is present
Dec 14 20:40:52.341 INFO running `"docker" "inspect" "5f353db70c4a215e4ab16b223602d9d41a1911971db698163d97a067ecd7d5ff"`
Dec 14 20:40:52.422 INFO running `"docker" "rm" "-f" "5f353db70c4a215e4ab16b223602d9d41a1911971db698163d97a067ecd7d5ff"`
Dec 14 20:40:52.539 INFO [stdout] 5f353db70c4a215e4ab16b223602d9d41a1911971db698163d97a067ecd7d5ff