Nov 16 22:32:22.973 INFO testing kevinastone/httpbox against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 22:32:22.973 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 22:32:23.423 INFO [stdout] 7131a801c833ee19981d70a64a089f4a8239dfff70ce6f3d747b51b5a88a5483
Nov 16 22:32:23.431 INFO running `"docker" "start" "-a" "7131a801c833ee19981d70a64a089f4a8239dfff70ce6f3d747b51b5a88a5483"`
Nov 16 22:32:25.255 INFO [stderr] usermod: no changes
Nov 16 22:32:25.635 INFO [stderr]    Compiling term_size v0.2.3
Nov 16 22:32:25.643 INFO [stderr]    Compiling futures-timer v0.1.1
Nov 16 22:32:25.643 INFO [stderr]    Compiling rand v0.5.4
Nov 16 22:32:25.643 INFO [stderr]    Compiling tempdir v0.3.7
Nov 16 22:32:25.643 INFO [stderr]    Compiling cookie v0.10.1
Nov 16 22:32:25.643 INFO [stderr]    Compiling want v0.0.4
Nov 16 22:32:25.643 INFO [stderr]    Compiling syn v0.14.7
Nov 16 22:32:25.643 INFO [stderr]    Compiling bincode v1.0.1
Nov 16 22:32:27.195 INFO [stderr]    Compiling gotham_derive v0.2.1
Nov 16 22:32:29.969 INFO [stderr]    Compiling tokio-reactor v0.1.2
Nov 16 22:32:30.024 INFO [stderr]    Compiling clap v2.16.4
Nov 16 22:32:33.736 INFO [stderr]    Compiling regex v0.2.11
Nov 16 22:32:36.936 INFO [stderr]    Compiling error-chain v0.11.0
Nov 16 22:32:37.735 INFO [stderr]    Compiling tokio-threadpool v0.1.5
Nov 16 22:32:43.899 INFO [stderr]    Compiling tokio-tcp v0.1.0
Nov 16 22:32:43.900 INFO [stderr]    Compiling tokio-udp v0.1.1
Nov 16 22:32:54.973 INFO [stderr]    Compiling tokio-fs v0.1.2
Nov 16 22:33:09.124 INFO [stderr]    Compiling tokio v0.1.7
Nov 16 22:33:18.365 INFO [stderr]    Compiling serde_derive v1.0.70
Nov 16 22:33:28.504 INFO [stderr]    Compiling tokio-core v0.1.17
Nov 16 22:33:35.058 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 16 22:33:40.345 INFO [stderr]    Compiling hyper v0.11.27
Nov 16 22:33:47.136 INFO [stderr]    Compiling cargo_metadata v0.5.8
Nov 16 22:33:52.174 INFO [stderr]    Compiling skeptic v0.13.3
Nov 16 22:33:59.959 INFO [stderr]    Compiling borrow-bag v1.0.0
Nov 16 22:34:02.679 INFO [stderr]    Compiling gotham v0.2.1
Nov 16 22:34:10.699 INFO [stderr]    Compiling httpbox v0.2.0 (/source)
Nov 16 22:34:21.704 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 56s
Nov 16 22:34:21.726 INFO [stderr] su: No module specific data is present
Nov 16 22:34:22.501 INFO running `"docker" "inspect" "7131a801c833ee19981d70a64a089f4a8239dfff70ce6f3d747b51b5a88a5483"`
Nov 16 22:34:22.769 INFO running `"docker" "rm" "-f" "7131a801c833ee19981d70a64a089f4a8239dfff70ce6f3d747b51b5a88a5483"`
Nov 16 22:34:23.107 INFO [stdout] 7131a801c833ee19981d70a64a089f4a8239dfff70ce6f3d747b51b5a88a5483
Nov 16 22:34:23.113 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 22:34:23.619 INFO [stdout] 204e5cfb025ed15a7b52906d4567e568a06edfec9f30ad6ef0aa107cb5c21302
Nov 16 22:34:23.619 INFO running `"docker" "start" "-a" "204e5cfb025ed15a7b52906d4567e568a06edfec9f30ad6ef0aa107cb5c21302"`
Nov 16 22:34:24.851 INFO [stderr] usermod: no changes
Nov 16 22:34:25.227 INFO [stderr]    Compiling httpbox v0.2.0 (/source)
Nov 16 22:34:40.391 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.50s
Nov 16 22:34:40.411 INFO [stderr] su: No module specific data is present
Nov 16 22:34:41.377 INFO running `"docker" "inspect" "204e5cfb025ed15a7b52906d4567e568a06edfec9f30ad6ef0aa107cb5c21302"`
Nov 16 22:34:41.629 INFO running `"docker" "rm" "-f" "204e5cfb025ed15a7b52906d4567e568a06edfec9f30ad6ef0aa107cb5c21302"`
Nov 16 22:34:41.886 INFO [stdout] 204e5cfb025ed15a7b52906d4567e568a06edfec9f30ad6ef0aa107cb5c21302
Nov 16 22:34:41.897 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 22:34:42.415 INFO [stdout] 803625972ff2d1e03ad492312958fcefdf07570fbc1cad34f7ae2d383854fd17
Nov 16 22:34:42.416 INFO running `"docker" "start" "-a" "803625972ff2d1e03ad492312958fcefdf07570fbc1cad34f7ae2d383854fd17"`
Nov 16 22:34:43.657 INFO [stderr] usermod: no changes
Nov 16 22:34:43.907 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Nov 16 22:34:43.931 INFO [stderr]      Running /target/debug/deps/httpbox-c08adbeff4b85359
Nov 16 22:34:43.935 INFO [stdout] 
Nov 16 22:34:43.935 INFO [stdout] running 52 tests
Nov 16 22:34:43.938 INFO [stdout] test app::auth::header::test::test_parse_err_www_authenticate ... ok
Nov 16 22:34:43.939 INFO [stdout] test app::auth::header::test::test_parse_www_authenticate ... ok
Nov 16 22:34:43.950 INFO [stdout] test app::auth::header::test::test_parse_basic_realm ... ok
Nov 16 22:34:43.950 INFO [stdout] test app::auth::header::test::test_format_basic_realm ... ok
Nov 16 22:34:43.964 INFO [stdout] test app::auth::header::test::test_parse_err_basic_realm ... ok
Nov 16 22:34:43.966 INFO [stdout] test app::auth::test::test_basic_authorized ... ok
Nov 16 22:34:43.975 INFO [stdout] test app::auth::test::test_basic_no_authorization ... ok
Nov 16 22:34:43.979 INFO [stdout] test app::auth::test::test_bearer_unauthorized ... ok
Nov 16 22:34:44.003 INFO [stdout] test app::auth::test::test_bearer_authorized ... ok
Nov 16 22:34:44.003 INFO [stdout] test app::bytes::test::test_bytes ... ok
Nov 16 22:34:44.011 INFO [stdout] test app::auth::test::test_bearer_no_authorization ... ok
Nov 16 22:34:44.011 INFO [stdout] test app::auth::test::test_basic_unauthorized ... ok
Nov 16 22:34:44.019 INFO [stdout] test app::bytes::test::test_stream_bytes ... ok
Nov 16 22:34:44.063 INFO [stdout] test app::bytes::test::test_stream_bytes_with_chunk_size ... ok
Nov 16 22:34:44.101 INFO [stdout] test app::cache::test::test_cache_no_headers ... ok
Nov 16 22:34:44.123 INFO [stdout] test app::cache::test::test_set_cache ... ok
Nov 16 22:34:44.142 INFO [stdout] test app::cache::test::test_cache_if_modified_since ... ok
Nov 16 22:34:44.152 INFO [stdout] test app::cookies::test::test_cookies ... ok
Nov 16 22:34:44.152 INFO [stdout] test app::cookies::test::test_no_cookies ... ok
Nov 16 22:34:44.179 INFO [stdout] test app::cache::test::test_cache_if_none_match ... ok
Nov 16 22:34:44.179 INFO [stdout] test app::cookies::test::test_set_cookies ... ok
Nov 16 22:34:44.179 INFO [stdout] test app::headers::test::test_headers ... ok
Nov 16 22:34:44.211 INFO [stdout] test app::delay::test::test_sleep ... ok
Nov 16 22:34:44.215 INFO [stdout] test app::delay::test::test_sleep_too_long ... ok
Nov 16 22:34:44.227 INFO [stdout] test app::method::test::test_delete ... ok
Nov 16 22:34:44.240 INFO [stdout] test app::method::test::test_get ... ok
Nov 16 22:34:44.240 INFO [stdout] test app::method::test::test_multi_get ... ok
Nov 16 22:34:44.240 INFO [stdout] test app::headers::test::test_response_headers ... ok
Nov 16 22:34:44.242 INFO [stdout] test app::ip::test::test_ip ... ok
Nov 16 22:34:44.251 INFO [stdout] test app::method::test::test_multi_patch ... ok
Nov 16 22:34:44.263 INFO [stdout] test app::random::tests::rng_no_seed ... ok
Nov 16 22:34:44.263 INFO [stdout] test app::ip::test::test_ip_from_header ... ok
Nov 16 22:34:44.267 INFO [stdout] test app::random::tests::rng_seed_consistent ... ok
Nov 16 22:34:44.267 INFO [stdout] test app::method::test::test_multi_post ... ok
Nov 16 22:34:44.291 INFO [stdout] test app::method::test::test_multi_put ... ok
Nov 16 22:34:44.291 INFO [stdout] test app::method::test::test_put ... ok
Nov 16 22:34:44.299 INFO [stdout] test app::method::test::test_post ... ok
Nov 16 22:34:44.311 INFO [stdout] test app::redirect::test::test_redirect ... ok
Nov 16 22:34:44.311 INFO [stdout] test app::method::test::test_patch ... ok
Nov 16 22:34:44.315 INFO [stdout] test app::redirect::uri::test::test_absolute_uri_with_port ... ok
Nov 16 22:34:44.315 INFO [stdout] test app::redirect::uri::test::test_join_url ... ok
Nov 16 22:34:44.315 INFO [stdout] test app::redirect::uri::test::test_absolute_uri ... ok
Nov 16 22:34:44.335 INFO [stdout] test app::redirect::test::test_relative_redirect ... ok
Nov 16 22:34:44.340 INFO [stdout] test app::redirect::test::test_absolute_redirect_last ... ok
Nov 16 22:34:44.347 INFO [stdout] test app::redirect::test::test_absolute_redirect ... ok
Nov 16 22:34:44.347 INFO [stdout] test app::redirect::test::test_redirect_last ... ok
Nov 16 22:34:44.347 INFO [stdout] test app::status_code::test::test_bad_status_code ... ok
Nov 16 22:34:44.363 INFO [stdout] test app::redirect::test::test_redirect_to ... ok
Nov 16 22:34:44.363 INFO [stdout] test app::status_code::test::test_status_code ... ok
Nov 16 22:34:44.371 INFO [stdout] test app::redirect::test::test_relative_redirect_last ... ok
Nov 16 22:34:44.383 INFO [stdout] test app::user_agent::test::test_user_agent ... ok
Nov 16 22:34:44.395 INFO [stdout] test app::user_agent::test::test_user_agent_custom ... ok
Nov 16 22:34:44.395 INFO [stdout] 
Nov 16 22:34:44.395 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 22:34:44.395 INFO [stdout] 
Nov 16 22:34:44.399 INFO [stderr] su: No module specific data is present
Nov 16 22:34:45.271 INFO running `"docker" "inspect" "803625972ff2d1e03ad492312958fcefdf07570fbc1cad34f7ae2d383854fd17"`
Nov 16 22:34:45.617 INFO running `"docker" "rm" "-f" "803625972ff2d1e03ad492312958fcefdf07570fbc1cad34f7ae2d383854fd17"`
Nov 16 22:34:45.944 INFO [stdout] 803625972ff2d1e03ad492312958fcefdf07570fbc1cad34f7ae2d383854fd17