Dec 14 20:46:51.823 INFO testing scram-0.4.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 20:46:51.823 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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:46:52.068 INFO [stdout] 9fd2590d3a768f17dc25e644788a0510c1e6bc4b6a4000ec78094163e69df573
Dec 14 20:46:52.079 INFO running `"docker" "start" "-a" "9fd2590d3a768f17dc25e644788a0510c1e6bc4b6a4000ec78094163e69df573"`
Dec 14 20:46:53.039 INFO [stderr] usermod: no changes
Dec 14 20:46:53.179 INFO [stderr]    Compiling scram v0.4.0 (/source)
Dec 14 20:46:55.105 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.02s
Dec 14 20:46:55.105 INFO [stderr] su: No module specific data is present
Dec 14 20:46:55.736 INFO running `"docker" "inspect" "9fd2590d3a768f17dc25e644788a0510c1e6bc4b6a4000ec78094163e69df573"`
Dec 14 20:46:56.087 INFO running `"docker" "rm" "-f" "9fd2590d3a768f17dc25e644788a0510c1e6bc4b6a4000ec78094163e69df573"`
Dec 14 20:46:56.359 INFO [stdout] 9fd2590d3a768f17dc25e644788a0510c1e6bc4b6a4000ec78094163e69df573
Dec 14 20:46:56.362 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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:46:56.792 INFO [stdout] 6ba1f7e17710f91804ce3093cac7183477afcc0adcc7f4fea172e7414e1a3e7b
Dec 14 20:46:56.800 INFO running `"docker" "start" "-a" "6ba1f7e17710f91804ce3093cac7183477afcc0adcc7f4fea172e7414e1a3e7b"`
Dec 14 20:46:58.089 INFO [stderr] usermod: no changes
Dec 14 20:46:58.219 INFO [stderr]    Compiling scram v0.4.0 (/source)
Dec 14 20:47:02.323 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.21s
Dec 14 20:47:02.333 INFO [stderr] su: No module specific data is present
Dec 14 20:47:03.451 INFO running `"docker" "inspect" "6ba1f7e17710f91804ce3093cac7183477afcc0adcc7f4fea172e7414e1a3e7b"`
Dec 14 20:47:03.792 INFO running `"docker" "rm" "-f" "6ba1f7e17710f91804ce3093cac7183477afcc0adcc7f4fea172e7414e1a3e7b"`
Dec 14 20:47:04.159 INFO [stdout] 6ba1f7e17710f91804ce3093cac7183477afcc0adcc7f4fea172e7414e1a3e7b
Dec 14 20:47:04.165 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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:47:04.672 INFO [stdout] dbddcaeddc1a1c5771e00095aefd82aaa6cad60a10c740cf9fad53d3f444bad0
Dec 14 20:47:04.676 INFO running `"docker" "start" "-a" "dbddcaeddc1a1c5771e00095aefd82aaa6cad60a10c740cf9fad53d3f444bad0"`
Dec 14 20:47:05.995 INFO [stderr] usermod: no changes
Dec 14 20:47:06.131 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 14 20:47:06.139 INFO [stderr]      Running /target/debug/deps/scram-1adc854efa59cd36
Dec 14 20:47:06.139 INFO [stdout] 
Dec 14 20:47:06.139 INFO [stdout] running 5 tests
Dec 14 20:47:06.139 INFO [stdout] test server::tests::test_parse_client_first_missing_fields ... ok
Dec 14 20:47:06.139 INFO [stdout] test server::tests::test_parse_client_first_invalid_data ... ok
Dec 14 20:47:06.147 INFO [stdout] test server::tests::test_parse_client_final_missing_fields ... ok
Dec 14 20:47:06.147 INFO [stdout] test server::tests::test_parse_client_final_success ... ok
Dec 14 20:47:06.147 INFO [stdout] test server::tests::test_parse_client_first_success ... ok
Dec 14 20:47:06.147 INFO [stdout] 
Dec 14 20:47:06.147 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:47:06.147 INFO [stdout] 
Dec 14 20:47:06.151 INFO [stderr]      Running /target/debug/deps/client_server-89a1460e4e457a75
Dec 14 20:47:06.167 INFO [stdout] 
Dec 14 20:47:06.167 INFO [stdout] running 8 tests
Dec 14 20:47:06.655 INFO [stdout] test test_empty_username ... ok
Dec 14 20:47:06.687 INFO [stdout] test test_invalid_user ... ok
Dec 14 20:47:06.807 INFO [stdout] test test_authorize_different ... ok
Dec 14 20:47:06.866 INFO [stdout] test test_empty_password ... ok
Dec 14 20:47:06.879 INFO [stdout] test test_authorize_fail ... ok
Dec 14 20:47:06.893 INFO [stdout] test test_bad_password ... ok
Dec 14 20:47:06.893 INFO [stdout] test test_simple_success ... ok
Dec 14 20:47:06.928 INFO [stdout] test test_authorize_non_existent ... ok
Dec 14 20:47:06.930 INFO [stdout] 
Dec 14 20:47:06.930 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:47:06.930 INFO [stdout] 
Dec 14 20:47:06.930 INFO [stderr]    Doc-tests scram
Dec 14 20:47:08.551 INFO [stdout] 
Dec 14 20:47:08.551 INFO [stdout] running 2 tests
Dec 14 20:47:10.909 INFO [stdout] test src/lib.rs -  (line 81) ... ok
Dec 14 20:47:10.915 INFO [stdout] test src/lib.rs -  (line 28) ... ok
Dec 14 20:47:10.915 INFO [stdout] 
Dec 14 20:47:10.915 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 20:47:10.915 INFO [stdout] 
Dec 14 20:47:10.934 INFO [stderr] su: No module specific data is present
Dec 14 20:47:11.825 INFO running `"docker" "inspect" "dbddcaeddc1a1c5771e00095aefd82aaa6cad60a10c740cf9fad53d3f444bad0"`
Dec 14 20:47:12.244 INFO running `"docker" "rm" "-f" "dbddcaeddc1a1c5771e00095aefd82aaa6cad60a10c740cf9fad53d3f444bad0"`
Dec 14 20:47:12.479 INFO [stdout] dbddcaeddc1a1c5771e00095aefd82aaa6cad60a10c740cf9fad53d3f444bad0