Dec 03 16:09:28.353 INFO testing chttp-0.2.4 against 1.30.1 for beta-1.31-5
Dec 03 16:09:28.354 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 16:09:28.683 INFO [stdout] 67b503f6cd6976d9368733aecbb086f53a1fdab820e8b9cc6915e01562cab92e
Dec 03 16:09:28.691 INFO running `"docker" "start" "-a" "67b503f6cd6976d9368733aecbb086f53a1fdab820e8b9cc6915e01562cab92e"`
Dec 03 16:09:29.991 INFO [stderr] usermod: no changes
Dec 03 16:09:30.196 INFO [stderr]    Compiling libnghttp2-sys v0.1.1
Dec 03 16:09:30.205 INFO [stderr]    Compiling curl-sys v0.4.15
Dec 03 16:09:30.205 INFO [stderr]    Compiling withers_derive v0.2.0
Dec 03 16:09:53.903 INFO [stderr]    Compiling curl v0.4.19
Dec 03 16:09:57.463 INFO [stderr]    Compiling chttp v0.2.4 (/source)
Dec 03 16:10:05.632 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.61s
Dec 03 16:10:05.651 INFO [stderr] su: No module specific data is present
Dec 03 16:10:06.603 INFO running `"docker" "inspect" "67b503f6cd6976d9368733aecbb086f53a1fdab820e8b9cc6915e01562cab92e"`
Dec 03 16:10:06.913 INFO running `"docker" "rm" "-f" "67b503f6cd6976d9368733aecbb086f53a1fdab820e8b9cc6915e01562cab92e"`
Dec 03 16:10:07.214 INFO [stdout] 67b503f6cd6976d9368733aecbb086f53a1fdab820e8b9cc6915e01562cab92e
Dec 03 16:10:07.216 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 16:10:07.660 INFO [stdout] 30423e1283621091c81acec028a1b2203f68f5e990815e3bbf3dd0f84a8fb01d
Dec 03 16:10:07.663 INFO running `"docker" "start" "-a" "30423e1283621091c81acec028a1b2203f68f5e990815e3bbf3dd0f84a8fb01d"`
Dec 03 16:10:09.306 INFO [stderr] usermod: no changes
Dec 03 16:10:09.756 INFO [stderr]    Compiling libnghttp2-sys v0.1.1
Dec 03 16:10:09.764 INFO [stderr]    Compiling futures-io-preview v0.2.2
Dec 03 16:10:09.772 INFO [stderr]    Compiling buf_redux v0.6.3
Dec 03 16:10:09.780 INFO [stderr]    Compiling gzip-header v0.2.0
Dec 03 16:10:09.783 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 03 16:10:14.099 INFO [stderr]    Compiling deflate v0.7.19
Dec 03 16:10:14.191 INFO [stderr]    Compiling futures-util-preview v0.2.2
Dec 03 16:10:14.263 INFO [stderr]    Compiling curl-sys v0.4.15
Dec 03 16:10:15.243 INFO [stderr]    Compiling multipart v0.13.6
Dec 03 16:10:17.739 INFO [stderr]    Compiling curl v0.4.19
Dec 03 16:10:25.891 INFO [stderr]    Compiling rouille v2.2.0
Dec 03 16:10:27.083 INFO [stderr]    Compiling futures-executor-preview v0.2.2
Dec 03 16:10:31.619 INFO [stderr]    Compiling futures-stable-preview v0.2.3
Dec 03 16:10:31.753 INFO [stderr]    Compiling futures-async-runtime-preview v0.2.3
Dec 03 16:10:31.906 INFO [stderr]    Compiling futures-preview v0.2.2
Dec 03 16:10:33.731 INFO [stderr]    Compiling chttp v0.2.4 (/source)
Dec 03 16:11:26.292 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 16s
Dec 03 16:11:26.319 INFO [stderr] su: No module specific data is present
Dec 03 16:11:27.207 INFO running `"docker" "inspect" "30423e1283621091c81acec028a1b2203f68f5e990815e3bbf3dd0f84a8fb01d"`
Dec 03 16:11:27.627 INFO running `"docker" "rm" "-f" "30423e1283621091c81acec028a1b2203f68f5e990815e3bbf3dd0f84a8fb01d"`
Dec 03 16:11:27.877 INFO [stdout] 30423e1283621091c81acec028a1b2203f68f5e990815e3bbf3dd0f84a8fb01d
Dec 03 16:11:27.883 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 16:11:28.296 INFO [stdout] 97044493e27773b40c91fd4bbe0ad9ddb894a0ce5e508f838d86e48e445fcbf1
Dec 03 16:11:28.304 INFO running `"docker" "start" "-a" "97044493e27773b40c91fd4bbe0ad9ddb894a0ce5e508f838d86e48e445fcbf1"`
Dec 03 16:11:29.446 INFO [stderr] usermod: no changes
Dec 03 16:11:30.007 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.50s
Dec 03 16:11:30.047 INFO [stderr]      Running /target/debug/deps/chttp-24e27a09bcc44484
Dec 03 16:11:30.060 INFO [stdout] 
Dec 03 16:11:30.060 INFO [stdout] running 0 tests
Dec 03 16:11:30.061 INFO [stdout] 
Dec 03 16:11:30.061 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 16:11:30.061 INFO [stdout] 
Dec 03 16:11:30.067 INFO [stderr]      Running /target/debug/deps/redirects-3d958194c09aad16
Dec 03 16:11:30.073 INFO [stdout] 
Dec 03 16:11:30.074 INFO [stdout] running 3 tests
Dec 03 16:11:30.124 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: agent ready
Dec 03 16:11:30.124 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f80868a4000, handler: 0x7f80868a4000 }))
Dec 03 16:11:30.127 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f808464e000, handler: 0x7f808464e000 }))
Dec 03 16:11:30.127 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.128 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.128 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.128 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [serially]
Dec 03 16:11:30.129 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 03 16:11:30.129 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.129 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44953 (#1)
Dec 03 16:11:30.129 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:44953\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.130 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.135 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: woke up from notify fd
Dec 03 16:11:30.135 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8082e51000, handler: 0x7f8082e51000 }))
Dec 03 16:11:30.135 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [serially]
Dec 03 16:11:30.135 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 03 16:11:30.136 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.136 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34659 (#2)
Dec 03 16:11:30.136 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:34659\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.136 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.175 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.186 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.200 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.203 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /b\r\n
Dec 03 16:11:30.203 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.204 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/b")
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:34659/b'
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34659 (#2)
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:34659\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /1\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.219 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/1")
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44559/1'
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.219 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 03 16:11:30.235 INFO [stdout] test response_301_auto_follow ... ok
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 2\r\n
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: buffer is empty, read is pending
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << ok
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received 2 bytes of data
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 30 bytes
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: buffer is empty, read is pending
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Dec 03 16:11:30.235 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: request with token 2 completed
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 30 bytes
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: woke up from notify fd
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(2)
Dec 03 16:11:30.235 INFO [stderr]  WARN 2018-12-03T16:11:30Z: chttp::internal::agent: received unpause request for unknown request token: 2
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(2)
Dec 03 16:11:30.235 INFO [stderr]  WARN 2018-12-03T16:11:30Z: chttp::internal::agent: received unpause request for unknown request token: 2
Dec 03 16:11:30.235 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /2\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.247 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/2")
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44559/2'
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /b\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Dec 03 16:11:30.247 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: request with token 1 completed
Dec 03 16:11:30.247 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /3\r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.275 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/3")
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44559/3'
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.275 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.279 INFO [stdout] test response_301_no_follow ... ok
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /4\r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.299 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/4")
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44559/4'
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.299 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /5\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.300 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/5")
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44559/5'
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f808306c030 [can pipeline]
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Could pipeline, but not asked to!
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44559 (#0)
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:44559\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Location: /6\r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 0\r\n
Dec 03 16:11:30.300 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::request: preparing for redirect to Some("/6")
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Maximum (5) redirects followed
Dec 03 16:11:30.300 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Dec 03 16:11:30.300 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 100ms
Dec 03 16:11:30.331 INFO [stdout] test redirect_limit_is_respected ... ok
Dec 03 16:11:30.331 INFO [stdout] 
Dec 03 16:11:30.331 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 16:11:30.331 INFO [stdout] 
Dec 03 16:11:30.335 INFO [stderr]      Running /target/debug/deps/response-1c6c5893eaf255a2
Dec 03 16:11:30.339 INFO [stdout] 
Dec 03 16:11:30.339 INFO [stdout] running 2 tests
Dec 03 16:11:30.371 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: agent ready
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f7816e4e000, handler: 0x7f7816e4e000 }))
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f7817aa4000, handler: 0x7f7817aa4000 }))
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 40633 (#0)
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:40633\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f781206c030 [serially]
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34079 (#1)
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:34079\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\n\r\n
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: woke up from notify fd
Dec 03 16:11:30.371 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.382 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 03 16:11:30.382 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.420 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.420 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 03 16:11:30.420 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 13000\r\n
Dec 03 16:11:30.420 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.420 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.423 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.423 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: buffer is empty, read is pending
Dec 03 16:11:30.428 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: woke up from notify fd
Dec 03 16:11:30.428 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Dec 03 16:11:30.429 INFO [stderr] TRACE 2018-12-03T16:11:30Z: 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 03 16:11:30.429 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received 8192 bytes of data
Dec 03 16:11:30.429 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.429 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 8192 bytes, consuming 32 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: 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 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 8160 bytes, consuming 32 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 64 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 8128 bytes, consuming 64 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 128 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 8064 bytes, consuming 128 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 256 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 7936 bytes, consuming 256 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 512 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 7680 bytes, consuming 512 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 1024 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 7168 bytes, consuming 1024 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 2048 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 6144 bytes, consuming 2048 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 4096 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 4096 bytes, consuming 4096 bytes
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received 4808 bytes of data
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Dec 03 16:11:30.430 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: request with token 1 completed
Dec 03 16:11:30.430 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.431 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 8192 bytes
Dec 03 16:11:30.431 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Dec 03 16:11:30.431 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 3384 bytes
Dec 03 16:11:30.431 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 03 16:11:30.442 INFO [stdout] test large_response_body ... ok
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Date: Mon,  3 Dec 2018 16:11:30 GMT\r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << Content-Length: 11\r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << \r\n
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: << hello world
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received 11 bytes of data
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Dec 03 16:11:30.454 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: request with token 0 completed
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 100ms
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 32 bytes
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: received read request for 21 bytes
Dec 03 16:11:30.454 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Dec 03 16:11:30.459 INFO [stdout] test simple_response_body ... ok
Dec 03 16:11:30.459 INFO [stdout] 
Dec 03 16:11:30.459 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 16:11:30.459 INFO [stdout] 
Dec 03 16:11:30.479 INFO [stderr]      Running /target/debug/deps/timeouts-0843d557c673ecdb
Dec 03 16:11:30.483 INFO [stdout] 
Dec 03 16:11:30.483 INFO [stdout] running 1 test
Dec 03 16:11:30.500 INFO [stderr] DEBUG 2018-12-03T16:11:30Z: chttp::internal::agent: agent ready
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f85d9ca4000, handler: 0x7f85d9ca4000 }))
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request:   Trying 127.0.0.1...
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34035 (#0)
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:34035\r\nAccept: */*\r\nAccept-Encoding: deflate, gzip\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: woke up from notify fd
Dec 03 16:11:30.500 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.701 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:30.907 INFO [stderr] TRACE 2018-12-03T16:11:30Z: chttp::internal::agent: polling with timeout of 200ms
Dec 03 16:11:31.107 INFO [stderr] TRACE 2018-12-03T16:11:31Z: chttp::internal::request: Done waiting for 100-continue
Dec 03 16:11:31.107 INFO [stderr] TRACE 2018-12-03T16:11:31Z: chttp::internal::agent: polling with timeout of 1s
Dec 03 16:11:31.107 INFO [stderr] TRACE 2018-12-03T16:11:31Z: chttp::internal::request: >> hello world
Dec 03 16:11:31.107 INFO [stderr] TRACE 2018-12-03T16:11:31Z: chttp::internal::request: We are completely uploaded and fine
Dec 03 16:11:31.107 INFO [stderr] TRACE 2018-12-03T16:11:31Z: chttp::internal::agent: polling with timeout of 1s
Dec 03 16:11:32.103 INFO [stderr] TRACE 2018-12-03T16:11:32Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Dec 03 16:11:32.103 INFO [stderr] TRACE 2018-12-03T16:11:32Z: chttp::internal::request: Closing connection 0
Dec 03 16:11:32.103 INFO [stderr] DEBUG 2018-12-03T16:11:32Z: chttp::internal::agent: curl error: [28] Timeout was reached
Dec 03 16:11:32.103 INFO [stderr] TRACE 2018-12-03T16:11:32Z: chttp::internal::agent: polling with timeout of 100ms
Dec 03 16:11:32.103 INFO [stdout] test request_errors_if_read_timeout_is_reached ... ok
Dec 03 16:11:32.103 INFO [stdout] 
Dec 03 16:11:32.103 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 16:11:32.103 INFO [stdout] 
Dec 03 16:11:32.107 INFO [stderr]    Doc-tests chttp
Dec 03 16:11:41.399 INFO [stdout] 
Dec 03 16:11:41.399 INFO [stdout] running 6 tests
Dec 03 16:12:13.587 INFO [stdout] test src/lib.rs -  (line 37) ... ok
Dec 03 16:12:18.513 INFO [stdout] test src/lib.rs -  (line 53) ... ok
Dec 03 16:12:21.843 INFO [stdout] test src/lib.rs -  (line 74) ... ok
Dec 03 16:12:21.862 INFO [stdout] test src/lib.rs -  (line 11) ... ok
Dec 03 16:12:22.890 INFO [stdout] test src/client.rs - client::ClientBuilder (line 17) ... ok
Dec 03 16:12:22.929 INFO [stdout] test src/lib.rs -  (line 26) ... ok
Dec 03 16:12:22.929 INFO [stdout] 
Dec 03 16:12:22.929 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 16:12:22.929 INFO [stdout] 
Dec 03 16:12:22.969 INFO [stderr] su: No module specific data is present
Dec 03 16:12:23.783 INFO running `"docker" "inspect" "97044493e27773b40c91fd4bbe0ad9ddb894a0ce5e508f838d86e48e445fcbf1"`
Dec 03 16:12:24.064 INFO running `"docker" "rm" "-f" "97044493e27773b40c91fd4bbe0ad9ddb894a0ce5e508f838d86e48e445fcbf1"`
Dec 03 16:12:24.479 INFO [stdout] 97044493e27773b40c91fd4bbe0ad9ddb894a0ce5e508f838d86e48e445fcbf1
