Dec 15 02:31:40.916 INFO testing oxide-auth-0.4.0-preview.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 02:31:40.916 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 02:31:41.512 INFO [stdout] 06ebc2f0b145704501c0094fb42b9624b36c1cc2df3558339ab7862c692e3ff5
Dec 15 02:31:41.514 INFO running `"docker" "start" "-a" "06ebc2f0b145704501c0094fb42b9624b36c1cc2df3558339ab7862c692e3ff5"`
Dec 15 02:31:44.063 INFO [stderr] usermod: no changes
Dec 15 02:31:44.651 INFO [stderr]    Compiling brotli-sys v0.3.2
Dec 15 02:31:44.652 INFO [stderr]    Compiling actix-web v0.6.15
Dec 15 02:31:44.711 INFO [stderr]    Compiling crossbeam-epoch v0.4.3
Dec 15 02:31:44.715 INFO [stderr]    Compiling flate2 v1.0.6
Dec 15 02:31:44.716 INFO [stderr]    Compiling actix_derive v0.2.0
Dec 15 02:31:44.724 INFO [stderr]    Compiling error-chain v0.1.12
Dec 15 02:31:44.727 INFO [stderr]    Compiling cargo_metadata v0.6.3
Dec 15 02:31:44.739 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 15 02:31:51.188 INFO [stderr]    Compiling tokio-threadpool v0.1.9
Dec 15 02:31:51.331 INFO [stderr]    Compiling cookie v0.10.1
Dec 15 02:31:51.531 INFO [stderr]    Compiling crossbeam-channel v0.1.3
Dec 15 02:32:01.472 INFO [stderr]    Compiling tokio-reactor v0.1.7
Dec 15 02:32:01.780 INFO [stderr]    Compiling skeptic v0.13.4
Dec 15 02:32:07.971 INFO [stderr]    Compiling tokio-fs v0.1.4
Dec 15 02:32:12.715 INFO [stderr]    Compiling tokio-uds v0.2.4
Dec 15 02:32:12.715 INFO [stderr]    Compiling tokio-udp v0.1.3
Dec 15 02:32:12.715 INFO [stderr]    Compiling tokio-tcp v0.1.2
Dec 15 02:32:17.739 INFO [stderr]    Compiling brotli2 v0.3.2
Dec 15 02:32:21.583 INFO [stderr]    Compiling borrow-bag v1.0.0
Dec 15 02:32:21.583 INFO [stderr]    Compiling actix v0.5.8
Dec 15 02:32:23.387 INFO [stderr]    Compiling tokio v0.1.13
Dec 15 02:32:30.999 INFO [stderr]    Compiling tokio-core v0.1.17
Dec 15 02:32:36.948 INFO [stderr]    Compiling trust-dns-proto v0.3.3
Dec 15 02:32:36.952 INFO [stderr]    Compiling tokio-signal v0.1.5
Dec 15 02:32:36.954 INFO [stderr]    Compiling tokio-proto v0.1.1
Dec 15 02:32:51.221 INFO [stderr]    Compiling hyper v0.11.27
Dec 15 02:32:58.900 INFO [stderr]    Compiling trust-dns-resolver v0.8.2
Dec 15 02:33:19.700 INFO [stderr]    Compiling gotham v0.2.1
Dec 15 02:34:03.790 INFO [stderr]    Compiling oxide-auth v0.4.0-preview.0 (/source)
Dec 15 02:34:19.839 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 35s
Dec 15 02:34:19.862 INFO [stderr] su: No module specific data is present
Dec 15 02:34:20.891 INFO running `"docker" "inspect" "06ebc2f0b145704501c0094fb42b9624b36c1cc2df3558339ab7862c692e3ff5"`
Dec 15 02:34:21.194 INFO running `"docker" "rm" "-f" "06ebc2f0b145704501c0094fb42b9624b36c1cc2df3558339ab7862c692e3ff5"`
Dec 15 02:34:21.624 INFO [stdout] 06ebc2f0b145704501c0094fb42b9624b36c1cc2df3558339ab7862c692e3ff5
Dec 15 02:34:21.639 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 02:34:22.187 INFO [stdout] 99f223efb70746167611af278786d361795c31d0ae7c465222bb905e172dc9c9
Dec 15 02:34:22.195 INFO running `"docker" "start" "-a" "99f223efb70746167611af278786d361795c31d0ae7c465222bb905e172dc9c9"`
Dec 15 02:34:23.203 INFO [stderr] usermod: no changes
Dec 15 02:34:23.843 INFO [stderr]    Compiling tokio-tls v0.1.4
Dec 15 02:34:27.831 INFO [stderr]    Compiling hyper-tls v0.1.4
Dec 15 02:34:32.803 INFO [stderr]    Compiling reqwest v0.8.8
Dec 15 02:34:42.811 INFO [stderr]    Compiling oxide-auth v0.4.0-preview.0 (/source)
Dec 15 02:35:21.007 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 57.76s
Dec 15 02:35:21.051 INFO [stderr] su: No module specific data is present
Dec 15 02:35:21.644 INFO running `"docker" "inspect" "99f223efb70746167611af278786d361795c31d0ae7c465222bb905e172dc9c9"`
Dec 15 02:35:21.933 INFO running `"docker" "rm" "-f" "99f223efb70746167611af278786d361795c31d0ae7c465222bb905e172dc9c9"`
Dec 15 02:35:22.199 INFO [stdout] 99f223efb70746167611af278786d361795c31d0ae7c465222bb905e172dc9c9
Dec 15 02:35:22.205 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 02:35:22.719 INFO [stdout] 9fc5c0eda598ad14f5b2a16b677056ec14440722e0075091dc0451b0c971c75b
Dec 15 02:35:22.723 INFO running `"docker" "start" "-a" "9fc5c0eda598ad14f5b2a16b677056ec14440722e0075091dc0451b0c971c75b"`
Dec 15 02:35:24.107 INFO [stderr] usermod: no changes
Dec 15 02:35:24.722 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.59s
Dec 15 02:35:24.759 INFO [stderr]      Running /target/debug/deps/oxide_auth-8f13b60b5ae03a71
Dec 15 02:35:24.791 INFO [stdout] 
Dec 15 02:35:24.791 INFO [stdout] running 35 tests
Dec 15 02:35:24.817 INFO [stdout] test code_grant::tests::access_token::access_request_public_missing_client ... ok
Dec 15 02:35:24.855 INFO [stdout] test code_grant::tests::access_token::access_request_public_authorization ... ok
Dec 15 02:35:32.983 INFO [stdout] test code_grant::tests::access_token::access_request_unknown_client ... ok
Dec 15 02:35:34.623 INFO [stdout] test code_grant::tests::access_token::access_request_invalid_basic ... ok
Dec 15 02:35:34.747 INFO [stdout] test code_grant::tests::access_token::access_request_wrong_authentication ... ok
Dec 15 02:35:35.247 INFO [stdout] test code_grant::tests::access_token::access_request_multiple_client_indications ... ok
Dec 15 02:35:41.807 INFO [stdout] test code_grant::tests::access_token::access_request_no_code ... ok
Dec 15 02:35:42.211 INFO [stdout] test code_grant::tests::access_token::access_request_invalid_redirection ... ok
Dec 15 02:35:44.429 INFO [stdout] test code_grant::tests::authorization_code::auth_request_error_denied ... ok
Dec 15 02:35:44.432 INFO [stdout] test code_grant::tests::access_token::access_request_empty_password ... ok
Dec 15 02:35:46.209 INFO [stdout] test code_grant::tests::access_token::access_request_multiple_codes ... ok
Dec 15 02:35:48.171 INFO [stdout] test code_grant::tests::access_token::access_request_wrong_grant_type ... ok
Dec 15 02:35:50.343 INFO [stdout] test code_grant::tests::authorization_code::auth_request_error_malformed_scope ... ok
Dec 15 02:35:50.343 INFO [stdout] test code_grant::tests::pkce::pkce_correct_verifier ... ok
Dec 15 02:35:50.343 INFO [stdout] test code_grant::tests::pkce::pkce_failed_verifier ... ok
Dec 15 02:35:50.346 INFO [stdout] test code_grant::tests::resource_guard::resource_invalid_token ... ok
Dec 15 02:35:50.367 INFO [stdout] test code_grant::tests::resource_guard::resource_no_authorization ... ok
Dec 15 02:35:50.367 INFO [stdout] test code_grant::tests::resource_guard::resource_scope_too_small ... ok
Dec 15 02:35:50.367 INFO [stdout] test code_grant::tests::resource_guard::resource_wrong_method ... ok
Dec 15 02:35:50.371 INFO [stdout] test code_grant::tests::resource_guard::resource_wrong_scope ... ok
Dec 15 02:35:50.387 INFO [stdout] test primitives::authorizer::tests::test_storage ... ok
Dec 15 02:35:51.052 INFO [stdout] test code_grant::tests::authorization_code::auth_request_silent_mismatching_redirect ... ok
Dec 15 02:35:51.063 INFO [stdout] test primitives::issuer::tests::token_map_test_suite ... ok
Dec 15 02:35:51.115 INFO [stdout] test code_grant::tests::access_token::access_request_wrong_password ... ok
Dec 15 02:35:51.115 INFO [stdout] test primitives::registrar::tests::confidential_client ... ok
Dec 15 02:35:51.135 INFO [stdout] test primitives::registrar::tests::public_client ... ok
Dec 15 02:35:51.135 INFO [stdout] test primitives::scope::tests::test_compare ... ok
Dec 15 02:35:51.144 INFO [stdout] test primitives::scope::tests::test_parsing ... ok
Dec 15 02:35:51.179 INFO [stdout] test code_grant::tests::authorization_code::auth_request_silent_invalid_redirect ... ok
Dec 15 02:35:51.259 INFO [stdout] test code_grant::tests::authorization_code::auth_request_error_unsupported_method ... ok
Dec 15 02:35:51.606 INFO [stdout] test code_grant::tests::access_token::access_request_wrong_redirection ... ok
Dec 15 02:35:52.607 INFO [stdout] test code_grant::tests::authorization_code::auth_request_silent_unknown_client ... ok
Dec 15 02:35:52.947 INFO [stdout] test code_grant::tests::authorization_code::auth_request_silent_missing_client ... ok
Dec 15 02:35:53.906 INFO [stdout] test primitives::issuer::tests::signer_test_suite ... ok
Dec 15 02:35:56.391 INFO [stdout] test primitives::registrar::tests::client_map ... ok
Dec 15 02:35:56.391 INFO [stderr]    Doc-tests oxide-auth
Dec 15 02:35:56.391 INFO [stdout] 
Dec 15 02:35:56.391 INFO [stdout] test result: ok. 35 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:35:56.391 INFO [stdout] 
Dec 15 02:36:14.347 INFO [stdout] 
Dec 15 02:36:14.347 INFO [stdout] running 9 tests
Dec 15 02:36:19.383 INFO [stdout] test src/frontends/mod.rs - frontends (line 18) ... ok
Dec 15 02:37:02.587 INFO [stdout] test src/frontends/actix/message.rs - frontends::actix::message::Guard (line 84) ... ok
Dec 15 02:37:03.743 INFO [stdout] test src/code_grant/frontend.rs - code_grant::frontend (line 25) ... ok
Dec 15 02:37:11.223 INFO [stdout] test src/frontends/actix/message.rs - frontends::actix::message::AccessToken (line 55) ... ok
Dec 15 02:37:11.514 INFO [stdout] test src/frontends/actix/message.rs - frontends::actix::message::AuthorizationCode (line 22) ... ok
Dec 15 02:37:11.779 INFO [stdout] test src/frontends/actix/endpoint.rs - frontends::actix::endpoint::CodeGrantEndpoint (line 22) ... ok
Dec 15 02:37:14.355 INFO [stdout] test src/frontends/gotham.rs - frontends::gotham (line 5) ... test src/frontends/gotham.rs - frontends::gotham (line 5) has been running for over 60 seconds
Dec 15 02:37:14.355 INFO [stdout] test src/frontends/mod.rs - frontends (line 52) ... test src/frontends/mod.rs - frontends (line 52) has been running for over 60 seconds
Dec 15 02:37:17.327 INFO [stdout] test src/frontends/mod.rs - frontends (line 52) ... ok
Dec 15 02:37:19.383 INFO [stdout] test src/primitives/scope.rs - primitives::scope::Scope (line 17) ... test src/primitives/scope.rs - primitives::scope::Scope (line 17) has been running for over 60 seconds
Dec 15 02:37:23.711 INFO [stdout] test src/primitives/scope.rs - primitives::scope::Scope (line 17) ... ok
Dec 15 02:37:25.058 INFO [stdout] test src/frontends/gotham.rs - frontends::gotham (line 5) ... ok
Dec 15 02:37:25.059 INFO [stdout] 
Dec 15 02:37:25.059 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:37:25.059 INFO [stdout] 
Dec 15 02:37:25.111 INFO [stderr] su: No module specific data is present
Dec 15 02:37:25.588 INFO running `"docker" "inspect" "9fc5c0eda598ad14f5b2a16b677056ec14440722e0075091dc0451b0c971c75b"`
Dec 15 02:37:25.701 INFO running `"docker" "rm" "-f" "9fc5c0eda598ad14f5b2a16b677056ec14440722e0075091dc0451b0c971c75b"`
Dec 15 02:37:25.895 INFO [stdout] 9fc5c0eda598ad14f5b2a16b677056ec14440722e0075091dc0451b0c971c75b