Nov 16 22:32:24.070 INFO testing kevinastone/httpbox against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 22:32:24.070 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16 22:32:24.680 INFO [stdout] 8cf83a120b6e49de83ef992ef90a9cd5f9363e43f788fbf307cc8db2f4286e14
Nov 16 22:32:24.683 INFO running `"docker" "start" "-a" "8cf83a120b6e49de83ef992ef90a9cd5f9363e43f788fbf307cc8db2f4286e14"`
Nov 16 22:32:26.882 INFO [stderr] usermod: no changes
Nov 16 22:32:27.248 INFO [stderr]    Compiling horrorshow v0.6.4
Nov 16 22:32:27.268 INFO [stderr]    Compiling term_size v0.2.3
Nov 16 22:32:27.277 INFO [stderr]    Compiling futures-timer v0.1.1
Nov 16 22:32:27.283 INFO [stderr]    Compiling walkdir v2.2.0
Nov 16 22:32:27.310 INFO [stderr]    Compiling rand v0.5.4
Nov 16 22:32:27.315 INFO [stderr]    Compiling tempdir v0.3.7
Nov 16 22:32:27.315 INFO [stderr]    Compiling cookie v0.10.1
Nov 16 22:32:27.315 INFO [stderr]    Compiling mio v0.6.15
Nov 16 22:32:29.458 INFO [stderr]    Compiling tokio-io v0.1.7
Nov 16 22:32:31.761 INFO [stderr]    Compiling clap v2.16.4
Nov 16 22:32:37.710 INFO [stderr]    Compiling bincode v1.0.1
Nov 16 22:32:37.822 INFO [stderr]    Compiling gotham_derive v0.2.1
Nov 16 22:32:37.861 INFO [stderr]    Compiling regex v0.2.11
Nov 16 22:32:37.996 INFO [stderr]    Compiling error-chain v0.11.0
Nov 16 22:32:41.547 INFO [stderr]    Compiling tokio-threadpool v0.1.5
Nov 16 22:32:47.325 INFO [stderr]    Compiling tokio-codec v0.1.0
Nov 16 22:32:47.427 INFO [stderr]    Compiling cargo_metadata v0.5.8
Nov 16 22:32:47.624 INFO [stderr]    Compiling tokio-reactor v0.1.2
Nov 16 22:32:59.367 INFO [stderr]    Compiling tokio-udp v0.1.1
Nov 16 22:32:59.451 INFO [stderr]    Compiling tokio-tcp v0.1.0
Nov 16 22:32:59.980 INFO [stderr]    Compiling tokio-fs v0.1.2
Nov 16 22:33:15.838 INFO [stderr]    Compiling tokio v0.1.7
Nov 16 22:33:17.133 INFO [stderr]    Compiling skeptic v0.13.3
Nov 16 22:33:31.888 INFO [stderr]    Compiling tokio-core v0.1.17
Nov 16 22:33:33.125 INFO [stderr]    Compiling borrow-bag v1.0.0
Nov 16 22:33:37.331 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 16 22:33:40.319 INFO [stderr]    Compiling hyper v0.11.27
Nov 16 22:33:52.515 INFO [stderr]    Compiling gotham v0.2.1
Nov 16 22:34:00.979 INFO [stderr]    Compiling httpbox v0.2.0 (/source)
Nov 16 22:34:12.695 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 45s
Nov 16 22:34:12.711 INFO [stderr] su: No module specific data is present
Nov 16 22:34:13.381 INFO running `"docker" "inspect" "8cf83a120b6e49de83ef992ef90a9cd5f9363e43f788fbf307cc8db2f4286e14"`
Nov 16 22:34:13.646 INFO running `"docker" "rm" "-f" "8cf83a120b6e49de83ef992ef90a9cd5f9363e43f788fbf307cc8db2f4286e14"`
Nov 16 22:34:13.919 INFO [stdout] 8cf83a120b6e49de83ef992ef90a9cd5f9363e43f788fbf307cc8db2f4286e14
Nov 16 22:34:13.922 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16 22:34:14.312 INFO [stdout] a916d69bc798a2b4731297c139a2141a9f28c05e6f62166dcabea511a9e3d8d7
Nov 16 22:34:14.316 INFO running `"docker" "start" "-a" "a916d69bc798a2b4731297c139a2141a9f28c05e6f62166dcabea511a9e3d8d7"`
Nov 16 22:34:15.350 INFO [stderr] usermod: no changes
Nov 16 22:34:15.731 INFO [stderr]    Compiling httpbox v0.2.0 (/source)
Nov 16 22:34:30.091 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.72s
Nov 16 22:34:30.110 INFO [stderr] su: No module specific data is present
Nov 16 22:34:31.615 INFO running `"docker" "inspect" "a916d69bc798a2b4731297c139a2141a9f28c05e6f62166dcabea511a9e3d8d7"`
Nov 16 22:34:32.009 INFO running `"docker" "rm" "-f" "a916d69bc798a2b4731297c139a2141a9f28c05e6f62166dcabea511a9e3d8d7"`
Nov 16 22:34:32.447 INFO [stdout] a916d69bc798a2b4731297c139a2141a9f28c05e6f62166dcabea511a9e3d8d7
Nov 16 22:34:32.452 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16 22:34:32.915 INFO [stdout] 78b7b4fec745b7d92f25a6b721a9a1c947e48a626c6c65f8d66fab35a66107d3
Nov 16 22:34:32.919 INFO running `"docker" "start" "-a" "78b7b4fec745b7d92f25a6b721a9a1c947e48a626c6c65f8d66fab35a66107d3"`
Nov 16 22:34:34.434 INFO [stderr] usermod: no changes
Nov 16 22:34:34.719 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.25s
Nov 16 22:34:34.759 INFO [stderr]      Running /target/debug/deps/httpbox-7d23ff94294a88da
Nov 16 22:34:34.779 INFO [stdout] 
Nov 16 22:34:34.779 INFO [stdout] running 52 tests
Nov 16 22:34:34.783 INFO [stdout] test app::auth::header::test::test_parse_www_authenticate ... ok
Nov 16 22:34:34.783 INFO [stdout] test app::auth::header::test::test_parse_err_www_authenticate ... ok
Nov 16 22:34:34.783 INFO [stdout] test app::auth::header::test::test_parse_err_basic_realm ... ok
Nov 16 22:34:34.795 INFO [stdout] test app::auth::header::test::test_parse_basic_realm ... ok
Nov 16 22:34:34.819 INFO [stdout] test app::auth::header::test::test_format_basic_realm ... ok
Nov 16 22:34:34.819 INFO [stdout] test app::auth::test::test_basic_authorized ... ok
Nov 16 22:34:34.822 INFO [stdout] test app::auth::test::test_basic_unauthorized ... ok
Nov 16 22:34:34.825 INFO [stdout] test app::auth::test::test_basic_no_authorization ... ok
Nov 16 22:34:34.842 INFO [stdout] test app::auth::test::test_bearer_no_authorization ... ok
Nov 16 22:34:34.851 INFO [stdout] test app::auth::test::test_bearer_authorized ... ok
Nov 16 22:34:34.883 INFO [stdout] test app::cache::test::test_set_cache ... ok
Nov 16 22:34:34.883 INFO [stdout] test app::cache::test::test_cache_no_headers ... ok
Nov 16 22:34:34.883 INFO [stdout] test app::auth::test::test_bearer_unauthorized ... ok
Nov 16 22:34:34.887 INFO [stdout] test app::bytes::test::test_bytes ... ok
Nov 16 22:34:34.903 INFO [stdout] test app::bytes::test::test_stream_bytes ... ok
Nov 16 22:34:34.903 INFO [stdout] test app::bytes::test::test_stream_bytes_with_chunk_size ... ok
Nov 16 22:34:34.905 INFO [stdout] test app::cookies::test::test_no_cookies ... ok
Nov 16 22:34:34.905 INFO [stdout] test app::cache::test::test_cache_if_modified_since ... ok
Nov 16 22:34:34.923 INFO [stdout] test app::cookies::test::test_set_cookies ... ok
Nov 16 22:34:34.943 INFO [stdout] test app::cache::test::test_cache_if_none_match ... ok
Nov 16 22:34:34.966 INFO [stdout] test app::delay::test::test_sleep ... ok
Nov 16 22:34:34.966 INFO [stdout] test app::cookies::test::test_cookies ... ok
Nov 16 22:34:34.999 INFO [stdout] test app::method::test::test_delete ... ok
Nov 16 22:34:34.999 INFO [stdout] test app::headers::test::test_headers ... ok
Nov 16 22:34:35.011 INFO [stdout] test app::delay::test::test_sleep_too_long ... ok
Nov 16 22:34:35.031 INFO [stdout] test app::method::test::test_multi_get ... ok
Nov 16 22:34:35.031 INFO [stdout] test app::headers::test::test_response_headers ... ok
Nov 16 22:34:35.031 INFO [stdout] test app::ip::test::test_ip_from_header ... ok
Nov 16 22:34:35.031 INFO [stdout] test app::method::test::test_get ... ok
Nov 16 22:34:35.059 INFO [stdout] test app::ip::test::test_ip ... ok
Nov 16 22:34:35.074 INFO [stdout] test app::method::test::test_multi_put ... ok
Nov 16 22:34:35.109 INFO [stdout] test app::random::tests::rng_seed_consistent ... ok
Nov 16 22:34:35.109 INFO [stdout] test app::random::tests::rng_no_seed ... ok
Nov 16 22:34:35.109 INFO [stdout] test app::redirect::test::test_absolute_redirect ... ok
Nov 16 22:34:35.120 INFO [stdout] test app::method::test::test_multi_post ... ok
Nov 16 22:34:35.120 INFO [stdout] test app::method::test::test_multi_patch ... ok
Nov 16 22:34:35.129 INFO [stdout] test app::method::test::test_patch ... ok
Nov 16 22:34:35.129 INFO [stdout] test app::method::test::test_post ... ok
Nov 16 22:34:35.129 INFO [stdout] test app::method::test::test_put ... ok
Nov 16 22:34:35.131 INFO [stdout] test app::redirect::uri::test::test_absolute_uri_with_port ... ok
Nov 16 22:34:35.132 INFO [stdout] test app::redirect::uri::test::test_absolute_uri ... ok
Nov 16 22:34:35.132 INFO [stdout] test app::redirect::uri::test::test_join_url ... ok
Nov 16 22:34:35.151 INFO [stdout] test app::redirect::test::test_redirect ... ok
Nov 16 22:34:35.176 INFO [stdout] test app::redirect::test::test_relative_redirect ... ok
Nov 16 22:34:35.176 INFO [stdout] test app::redirect::test::test_redirect_to ... ok
Nov 16 22:34:35.195 INFO [stdout] test app::redirect::test::test_relative_redirect_last ... ok
Nov 16 22:34:35.197 INFO [stdout] test app::status_code::test::test_bad_status_code ... ok
Nov 16 22:34:35.198 INFO [stdout] test app::status_code::test::test_status_code ... ok
Nov 16 22:34:35.208 INFO [stdout] test app::user_agent::test::test_user_agent ... ok
Nov 16 22:34:35.212 INFO [stdout] test app::redirect::test::test_redirect_last ... ok
Nov 16 22:34:35.223 INFO [stdout] test app::redirect::test::test_absolute_redirect_last ... ok
Nov 16 22:34:35.231 INFO [stdout] test app::user_agent::test::test_user_agent_custom ... ok
Nov 16 22:34:35.231 INFO [stdout] 
Nov 16 22:34:35.231 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 22:34:35.231 INFO [stdout] 
Nov 16 22:34:35.239 INFO [stderr] su: No module specific data is present
Nov 16 22:34:36.604 INFO running `"docker" "inspect" "78b7b4fec745b7d92f25a6b721a9a1c947e48a626c6c65f8d66fab35a66107d3"`
Nov 16 22:34:36.965 INFO running `"docker" "rm" "-f" "78b7b4fec745b7d92f25a6b721a9a1c947e48a626c6c65f8d66fab35a66107d3"`
Nov 16 22:34:37.255 INFO [stdout] 78b7b4fec745b7d92f25a6b721a9a1c947e48a626c6c65f8d66fab35a66107d3