Dec 07 20:40:10.113 INFO testing chttp-0.2.4 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 20:40:10.114 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 20:40:10.529 INFO [stdout] 819b86ec7ea1f5c819d6e2cccc3f992a4ae008eff8c37195128a35523b369189
Dec 07 20:40:10.531 INFO running `"docker" "start" "-a" "819b86ec7ea1f5c819d6e2cccc3f992a4ae008eff8c37195128a35523b369189"`
Dec 07 20:40:12.337 INFO [stderr] usermod: no changes
Dec 07 20:40:12.670 INFO [stderr]    Compiling libnghttp2-sys v0.1.1
Dec 07 20:40:12.670 INFO [stderr]    Compiling curl-sys v0.4.15
Dec 07 20:40:12.691 INFO [stderr]    Compiling withers_derive v0.2.0
Dec 07 20:40:12.691 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 07 20:40:35.380 INFO [stderr]    Compiling curl v0.4.19
Dec 07 20:40:39.709 INFO [stderr]    Compiling chttp v0.2.4 (/source)
Dec 07 20:40:48.120 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.75s
Dec 07 20:40:48.132 INFO [stderr] su: No module specific data is present
Dec 07 20:40:48.989 INFO running `"docker" "inspect" "819b86ec7ea1f5c819d6e2cccc3f992a4ae008eff8c37195128a35523b369189"`
Dec 07 20:40:49.218 INFO running `"docker" "rm" "-f" "819b86ec7ea1f5c819d6e2cccc3f992a4ae008eff8c37195128a35523b369189"`
Dec 07 20:40:49.486 INFO [stdout] 819b86ec7ea1f5c819d6e2cccc3f992a4ae008eff8c37195128a35523b369189
Dec 07 20:40:49.492 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 20:40:49.852 INFO [stdout] fe703830f692b914b3128014bf519ea4c155ba62c3196ff68555fb869c165239
Dec 07 20:40:49.859 INFO running `"docker" "start" "-a" "fe703830f692b914b3128014bf519ea4c155ba62c3196ff68555fb869c165239"`
Dec 07 20:40:51.189 INFO [stderr] usermod: no changes
Dec 07 20:40:51.520 INFO [stderr]    Compiling chttp v0.2.4 (/source)
Dec 07 20:41:45.428 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 54.21s
Dec 07 20:41:45.465 INFO [stderr] su: No module specific data is present
Dec 07 20:41:48.176 INFO running `"docker" "inspect" "fe703830f692b914b3128014bf519ea4c155ba62c3196ff68555fb869c165239"`
Dec 07 20:41:48.392 INFO running `"docker" "rm" "-f" "fe703830f692b914b3128014bf519ea4c155ba62c3196ff68555fb869c165239"`
Dec 07 20:41:48.695 INFO [stdout] fe703830f692b914b3128014bf519ea4c155ba62c3196ff68555fb869c165239
Dec 07 20:41:48.702 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 20:41:49.101 INFO [stdout] 95893deb3d46008cce40fb5d986203b23c78f95adfb5e77d5d43c9178223944b
Dec 07 20:41:49.103 INFO running `"docker" "start" "-a" "95893deb3d46008cce40fb5d986203b23c78f95adfb5e77d5d43c9178223944b"`
Dec 07 20:41:49.916 INFO [stderr] usermod: no changes
Dec 07 20:41:50.224 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Dec 07 20:41:50.244 INFO [stderr]      Running /target/debug/deps/chttp-f0f9e858acc9d558
Dec 07 20:41:50.260 INFO [stdout] 
Dec 07 20:41:50.260 INFO [stdout] running 0 tests
Dec 07 20:41:50.260 INFO [stdout] 
Dec 07 20:41:50.260 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:41:50.260 INFO [stdout] 
Dec 07 20:41:50.267 INFO [stderr]      Running /target/debug/deps/redirects-e58ece6d2fb030f9
Dec 07 20:41:50.271 INFO [stdout] 
Dec 07 20:41:50.271 INFO [stdout] running 3 tests
Dec 07 20:41:50.404 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: agent ready
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8f4c025f90, handler: 0x7f8f4c025f90 }))
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8f48001e20, handler: 0x7f8f48001e20 }))
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8f44002360, handler: 0x7f8f44002360 }))
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [serially]
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37891 (#1)
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:37891\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [serially]
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37395 (#2)
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:37395\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.404 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.440 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.440 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.440 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.441 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /b\r\n
Dec 07 20:41:50.441 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.441 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/b")
Dec 07 20:41:50.441 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.441 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Dec 07 20:41:50.441 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37395/b'
Dec 07 20:41:50.442 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.442 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.442 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Dec 07 20:41:50.442 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37395 (#2)
Dec 07 20:41:50.442 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:37395\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.443 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.461 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.461 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.461 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.461 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /b\r\n
Dec 07 20:41:50.462 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.462 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.462 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Dec 07 20:41:50.462 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: request with token 1 completed
Dec 07 20:41:50.463 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.484 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.484 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.484 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /1\r\n
Dec 07 20:41:50.492 INFO [stdout] test response_301_no_follow ... ok
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.492 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/1")
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37583/1'
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.492 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.505 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.505 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.505 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.505 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /2\r\n
Dec 07 20:41:50.506 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.506 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/2")
Dec 07 20:41:50.506 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.506 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.506 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37583/2'
Dec 07 20:41:50.507 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.507 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.507 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 07 20:41:50.507 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.508 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.508 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.520 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.521 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.521 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.521 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /3\r\n
Dec 07 20:41:50.521 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.522 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/3")
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37583/3'
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.522 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 07 20:41:50.523 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.523 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.528 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.528 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.529 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.529 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.529 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /4\r\n
Dec 07 20:41:50.529 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.530 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/4")
Dec 07 20:41:50.530 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.536 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.536 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37583/4'
Dec 07 20:41:50.536 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.536 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.536 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 07 20:41:50.537 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.537 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.537 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.546 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 07 20:41:50.546 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.546 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.547 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 07 20:41:50.547 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 2\r\n
Dec 07 20:41:50.547 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.547 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << ok
Dec 07 20:41:50.547 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received 2 bytes of data
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Dec 07 20:41:50.548 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: request with token 2 completed
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 32 bytes
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 30 bytes
Dec 07 20:41:50.548 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 07 20:41:50.556 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.556 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.557 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.557 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /5\r\n
Dec 07 20:41:50.557 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.557 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/5")
Dec 07 20:41:50.557 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37583/5'
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8f3800ff90 [can pipeline]
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37583 (#0)
Dec 07 20:41:50.558 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:37583\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.559 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.559 INFO [stdout] test response_301_auto_follow ... ok
Dec 07 20:41:50.560 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 07 20:41:50.560 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Location: /6\r\n
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 07 20:41:50.561 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::request: preparing for redirect to Some("/6")
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Maximum (5) redirects followed
Dec 07 20:41:50.561 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Dec 07 20:41:50.561 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 100ms
Dec 07 20:41:50.562 INFO [stdout] test redirect_limit_is_respected ... ok
Dec 07 20:41:50.562 INFO [stdout] 
Dec 07 20:41:50.562 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:41:50.562 INFO [stdout] 
Dec 07 20:41:50.573 INFO [stderr]      Running /target/debug/deps/response-622c03083df6c1bc
Dec 07 20:41:50.578 INFO [stdout] 
Dec 07 20:41:50.578 INFO [stdout] running 2 tests
Dec 07 20:41:50.585 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: agent ready
Dec 07 20:41:50.585 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fcebc025a30, handler: 0x7fcebc025a30 }))
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 32911 (#0)
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:32911\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.586 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fce88001e10, handler: 0x7fce88001e10 }))
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcea000ff60 [serially]
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37063 (#1)
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:37063\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 07 20:41:50.600 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 07 20:41:50.626 INFO [stdout] test simple_response_body ... ok
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 11\r\n
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << hello world
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received 11 bytes of data
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 07 20:41:50.626 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: request with token 0 completed
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 32 bytes
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 21 bytes
Dec 07 20:41:50.626 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Date: Fri,  7 Dec 2018 20:41:50 GMT\r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << Content-Length: 13000\r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << \r\n
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wo
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received 8192 bytes of data
Dec 07 20:41:50.632 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.640 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: << w so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large 
Dec 07 20:41:50.640 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received 4808 bytes of data
Dec 07 20:41:50.640 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: response buffer is not empty, pausing transfer
Dec 07 20:41:50.640 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 32 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 8192 bytes, consuming 32 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 32 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 8160 bytes, consuming 32 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 64 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 8128 bytes, consuming 64 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 128 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 8064 bytes, consuming 128 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 256 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 7936 bytes, consuming 256 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 512 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 7680 bytes, consuming 512 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 1024 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 7168 bytes, consuming 1024 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 2048 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 6144 bytes, consuming 2048 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 4096 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 4096 bytes, consuming 4096 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 8192 bytes
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: buffer is empty, read is pending
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.644 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received 4808 bytes of data
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 8192 bytes
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 3384 bytes
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: buffer is empty, read is pending
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Dec 07 20:41:50.645 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: request with token 1 completed
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: received read request for 3384 bytes
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 100ms
Dec 07 20:41:50.645 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.662 INFO [stdout] test large_response_body ... ok
Dec 07 20:41:50.662 INFO [stdout] 
Dec 07 20:41:50.662 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:41:50.662 INFO [stdout] 
Dec 07 20:41:50.676 INFO [stderr]      Running /target/debug/deps/timeouts-a983a7df871f1271
Dec 07 20:41:50.679 INFO [stdout] 
Dec 07 20:41:50.679 INFO [stdout] running 1 test
Dec 07 20:41:50.692 INFO [stderr] DEBUG 2018-12-07T20:41:50Z: chttp::internal::agent: agent ready
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f951c025f50, handler: 0x7f951c025f50 }))
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 38179 (#0)
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:38179\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: woke up from notify fd
Dec 07 20:41:50.692 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:50.884 INFO [stderr] TRACE 2018-12-07T20:41:50Z: chttp::internal::agent: polling with timeout of 200ms
Dec 07 20:41:51.088 INFO [stderr] TRACE 2018-12-07T20:41:51Z: chttp::internal::request: Done waiting for 100-continue
Dec 07 20:41:51.088 INFO [stderr] TRACE 2018-12-07T20:41:51Z: chttp::internal::agent: polling with timeout of 1s
Dec 07 20:41:51.088 INFO [stderr] TRACE 2018-12-07T20:41:51Z: chttp::internal::request: >> hello world
Dec 07 20:41:51.088 INFO [stderr] TRACE 2018-12-07T20:41:51Z: chttp::internal::request: We are completely uploaded and fine
Dec 07 20:41:51.088 INFO [stderr] TRACE 2018-12-07T20:41:51Z: chttp::internal::agent: polling with timeout of 1s
Dec 07 20:41:52.096 INFO [stderr] TRACE 2018-12-07T20:41:52Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Dec 07 20:41:52.096 INFO [stderr] TRACE 2018-12-07T20:41:52Z: chttp::internal::request: Closing connection 0
Dec 07 20:41:52.096 INFO [stderr] DEBUG 2018-12-07T20:41:52Z: chttp::internal::agent: curl error: [28] Timeout was reached
Dec 07 20:41:52.096 INFO [stderr] TRACE 2018-12-07T20:41:52Z: chttp::internal::agent: polling with timeout of 100ms
Dec 07 20:41:52.096 INFO [stderr]    Doc-tests chttp
Dec 07 20:41:52.096 INFO [stdout] test request_errors_if_read_timeout_is_reached ... ok
Dec 07 20:41:52.096 INFO [stdout] 
Dec 07 20:41:52.096 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:41:52.096 INFO [stdout] 
Dec 07 20:41:59.761 INFO [stdout] 
Dec 07 20:41:59.761 INFO [stdout] running 6 tests
Dec 07 20:42:42.177 INFO [stdout] test src/client.rs - client::ClientBuilder (line 17) ... ok
Dec 07 20:42:42.648 INFO [stdout] test src/lib.rs -  (line 74) ... ok
Dec 07 20:42:43.616 INFO [stdout] test src/lib.rs -  (line 37) ... ok
Dec 07 20:42:45.144 INFO [stdout] test src/lib.rs -  (line 11) ... ok
Dec 07 20:42:45.233 INFO [stdout] test src/lib.rs -  (line 53) ... ok
Dec 07 20:42:45.372 INFO [stdout] test src/lib.rs -  (line 26) ... ok
Dec 07 20:42:45.372 INFO [stdout] 
Dec 07 20:42:45.372 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:42:45.372 INFO [stdout] 
Dec 07 20:42:45.400 INFO [stderr] su: No module specific data is present
Dec 07 20:42:46.015 INFO running `"docker" "inspect" "95893deb3d46008cce40fb5d986203b23c78f95adfb5e77d5d43c9178223944b"`
Dec 07 20:42:46.417 INFO running `"docker" "rm" "-f" "95893deb3d46008cce40fb5d986203b23c78f95adfb5e77d5d43c9178223944b"`
Dec 07 20:42:46.754 INFO [stdout] 95893deb3d46008cce40fb5d986203b23c78f95adfb5e77d5d43c9178223944b