Nov 17 23:44:29.680 INFO testing scram-0.4.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 23:44:29.681 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:44:29.933 INFO [stdout] ab4b8aec81912aacb4362f02a621c70565d6dabdca15f734f3846315d65d5ae3
Nov 17 23:44:29.935 INFO running `"docker" "start" "-a" "ab4b8aec81912aacb4362f02a621c70565d6dabdca15f734f3846315d65d5ae3"`
Nov 17 23:44:30.782 INFO [stderr] usermod: no changes
Nov 17 23:44:30.883 INFO [stderr]    Compiling ring v0.13.5
Nov 17 23:44:39.087 INFO [stderr]    Compiling scram v0.4.0 (/source)
Nov 17 23:44:40.594 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.78s
Nov 17 23:44:40.600 INFO [stderr] su: No module specific data is present
Nov 17 23:44:41.179 INFO running `"docker" "inspect" "ab4b8aec81912aacb4362f02a621c70565d6dabdca15f734f3846315d65d5ae3"`
Nov 17 23:44:41.395 INFO running `"docker" "rm" "-f" "ab4b8aec81912aacb4362f02a621c70565d6dabdca15f734f3846315d65d5ae3"`
Nov 17 23:44:41.604 INFO [stdout] ab4b8aec81912aacb4362f02a621c70565d6dabdca15f734f3846315d65d5ae3
Nov 17 23:44:41.609 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:44:41.967 INFO [stdout] decf44dcb62644ec661b7b0a2d54da1919878f8f58ad9b778b16c5858d876e9e
Nov 17 23:44:41.969 INFO running `"docker" "start" "-a" "decf44dcb62644ec661b7b0a2d54da1919878f8f58ad9b778b16c5858d876e9e"`
Nov 17 23:44:43.532 INFO [stderr] usermod: no changes
Nov 17 23:44:43.651 INFO [stderr]    Compiling scram v0.4.0 (/source)
Nov 17 23:44:46.787 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.23s
Nov 17 23:44:46.796 INFO [stderr] su: No module specific data is present
Nov 17 23:44:47.737 INFO running `"docker" "inspect" "decf44dcb62644ec661b7b0a2d54da1919878f8f58ad9b778b16c5858d876e9e"`
Nov 17 23:44:48.017 INFO running `"docker" "rm" "-f" "decf44dcb62644ec661b7b0a2d54da1919878f8f58ad9b778b16c5858d876e9e"`
Nov 17 23:44:48.142 INFO [stdout] decf44dcb62644ec661b7b0a2d54da1919878f8f58ad9b778b16c5858d876e9e
Nov 17 23:44:48.148 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:44:48.414 INFO [stdout] 68f2b71e36df775ef4bfdc58066262589df4f28c5f2a7718b7818b8d01b3f387
Nov 17 23:44:48.414 INFO running `"docker" "start" "-a" "68f2b71e36df775ef4bfdc58066262589df4f28c5f2a7718b7818b8d01b3f387"`
Nov 17 23:44:48.781 INFO [stderr] usermod: no changes
Nov 17 23:44:48.859 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Nov 17 23:44:48.859 INFO [stderr]      Running /target/debug/deps/scram-4817b52f413821a0
Nov 17 23:44:48.860 INFO [stderr]      Running /target/debug/deps/client_server-3d8f52ea7f3f4203
Nov 17 23:44:48.860 INFO [stdout] 
Nov 17 23:44:48.860 INFO [stdout] running 5 tests
Nov 17 23:44:48.860 INFO [stdout] test server::tests::test_parse_client_first_invalid_data ... ok
Nov 17 23:44:48.860 INFO [stdout] test server::tests::test_parse_client_first_missing_fields ... ok
Nov 17 23:44:48.860 INFO [stdout] test server::tests::test_parse_client_final_missing_fields ... ok
Nov 17 23:44:48.860 INFO [stdout] test server::tests::test_parse_client_first_success ... ok
Nov 17 23:44:48.860 INFO [stdout] test server::tests::test_parse_client_final_success ... ok
Nov 17 23:44:48.860 INFO [stdout] 
Nov 17 23:44:48.861 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:44:48.861 INFO [stdout] 
Nov 17 23:44:48.861 INFO [stdout] 
Nov 17 23:44:48.861 INFO [stdout] running 8 tests
Nov 17 23:44:49.031 INFO [stdout] test test_invalid_user ... ok
Nov 17 23:44:49.045 INFO [stdout] test test_empty_password ... ok
Nov 17 23:44:49.084 INFO [stdout] test test_authorize_different ... ok
Nov 17 23:44:49.084 INFO [stdout] test test_empty_username ... ok
Nov 17 23:44:49.098 INFO [stdout] test test_simple_success ... ok
Nov 17 23:44:49.102 INFO [stdout] test test_authorize_non_existent ... ok
Nov 17 23:44:49.143 INFO [stdout] test test_bad_password ... ok
Nov 17 23:44:49.147 INFO [stdout] test test_authorize_fail ... ok
Nov 17 23:44:49.147 INFO [stdout] 
Nov 17 23:44:49.147 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:44:49.147 INFO [stdout] 
Nov 17 23:44:49.151 INFO [stderr]    Doc-tests scram
Nov 17 23:44:50.244 INFO [stdout] 
Nov 17 23:44:50.244 INFO [stdout] running 2 tests
Nov 17 23:44:51.929 INFO [stdout] test src/lib.rs -  (line 28) ... ok
Nov 17 23:44:52.036 INFO [stdout] test src/lib.rs -  (line 81) ... ok
Nov 17 23:44:52.036 INFO [stdout] 
Nov 17 23:44:52.037 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:44:52.037 INFO [stdout] 
Nov 17 23:44:52.047 INFO [stderr] su: No module specific data is present
Nov 17 23:44:52.399 INFO running `"docker" "inspect" "68f2b71e36df775ef4bfdc58066262589df4f28c5f2a7718b7818b8d01b3f387"`
Nov 17 23:44:52.500 INFO running `"docker" "rm" "-f" "68f2b71e36df775ef4bfdc58066262589df4f28c5f2a7718b7818b8d01b3f387"`
Nov 17 23:44:52.625 INFO [stdout] 68f2b71e36df775ef4bfdc58066262589df4f28c5f2a7718b7818b8d01b3f387