Oct 16 15:04:54.366 INFO testing chttp-0.2.2 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 16 15:04:54.368 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 16 15:04:54.928 INFO blam! 79f5b4202d43191cf3e4a6a2103c532c5d686d88c0a4f629213a1d8f2ce5328c
Oct 16 15:04:54.931 INFO running `"docker" "start" "-a" "79f5b4202d43191cf3e4a6a2103c532c5d686d88c0a4f629213a1d8f2ce5328c"`
Oct 16 15:04:56.527 INFO kablam! usermod: no changes
Oct 16 15:04:56.839 INFO kablam!    Compiling libnghttp2-sys v0.1.0
Oct 16 15:04:56.840 INFO kablam!    Compiling curl-sys v0.4.13
Oct 16 15:04:56.853 INFO kablam!    Compiling withers_derive v0.2.0
Oct 16 15:05:18.095 INFO kablam!    Compiling curl v0.4.18
Oct 16 15:05:20.411 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 16 15:05:26.059 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 29.51s
Oct 16 15:05:26.070 INFO kablam! su: No module specific data is present
Oct 16 15:05:26.460 INFO running `"docker" "rm" "-f" "79f5b4202d43191cf3e4a6a2103c532c5d686d88c0a4f629213a1d8f2ce5328c"`
Oct 16 15:05:26.545 INFO blam! 79f5b4202d43191cf3e4a6a2103c532c5d686d88c0a4f629213a1d8f2ce5328c
Oct 16 15:05:26.550 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 16 15:05:26.838 INFO blam! ca646006c8e624872b73754892be156e85c7a0a369e08a6138f41bbbb81562a4
Oct 16 15:05:26.840 INFO running `"docker" "start" "-a" "ca646006c8e624872b73754892be156e85c7a0a369e08a6138f41bbbb81562a4"`
Oct 16 15:05:27.313 INFO kablam! usermod: no changes
Oct 16 15:05:27.579 INFO kablam!    Compiling futures-io-preview v0.2.2
Oct 16 15:05:27.588 INFO kablam!    Compiling curl-sys v0.4.13
Oct 16 15:05:27.588 INFO kablam!    Compiling crossbeam-channel v0.2.6
Oct 16 15:05:28.379 INFO kablam!    Compiling curl v0.4.18
Oct 16 15:05:28.556 INFO kablam!    Compiling futures-util-preview v0.2.2
Oct 16 15:05:32.931 INFO kablam!    Compiling futures-executor-preview v0.2.2
Oct 16 15:05:35.021 INFO kablam!    Compiling futures-stable-preview v0.2.3
Oct 16 15:05:35.152 INFO kablam!    Compiling futures-async-runtime-preview v0.2.3
Oct 16 15:05:35.307 INFO kablam!    Compiling futures-preview v0.2.2
Oct 16 15:05:36.326 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 16 15:06:18.851 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 51.51s
Oct 16 15:06:18.875 INFO kablam! su: No module specific data is present
Oct 16 15:06:19.575 INFO running `"docker" "rm" "-f" "ca646006c8e624872b73754892be156e85c7a0a369e08a6138f41bbbb81562a4"`
Oct 16 15:06:19.801 INFO blam! ca646006c8e624872b73754892be156e85c7a0a369e08a6138f41bbbb81562a4
Oct 16 15:06:19.811 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 16 15:06:20.255 INFO blam! 10e42198ecad8defc4c4e1974bf4dc247b7b75409cf73659895746c19358dd81
Oct 16 15:06:20.257 INFO running `"docker" "start" "-a" "10e42198ecad8defc4c4e1974bf4dc247b7b75409cf73659895746c19358dd81"`
Oct 16 15:06:20.971 INFO kablam! usermod: no changes
Oct 16 15:06:21.247 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Oct 16 15:06:21.267 INFO kablam!      Running /target/debug/deps/chttp-35ec8890d4f73941
Oct 16 15:06:21.275 INFO kablam!      Running /target/debug/deps/redirects-343bee3d7d629447
Oct 16 15:06:21.275 INFO blam! 
Oct 16 15:06:21.275 INFO blam! running 0 tests
Oct 16 15:06:21.275 INFO blam! 
Oct 16 15:06:21.275 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:21.275 INFO blam! 
Oct 16 15:06:21.283 INFO blam! 
Oct 16 15:06:21.283 INFO blam! running 3 tests
Oct 16 15:06:21.395 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: agent ready
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8d0c04e000, handler: 0x7f8d0c04e000 }))
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8d0daa4000, handler: 0x7f8d0daa4000 }))
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44555 (#0)
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:44555\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [serially]
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8d0d851000, handler: 0x7f8d0d851000 }))
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [serially]
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 43409 (#2)
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:43409\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.395 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.419 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /1\r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.423 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/1")
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45629/1'
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.423 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.436 INFO blam! test response_301_no_follow ... ok
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /b\r\n
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:21.436 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: request with token 0 completed
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.436 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /2\r\n
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.437 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/2")
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45629/2'
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.437 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /b\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.438 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/b")
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:43409/b'
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 43409 (#2)
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:43409\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.438 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /3\r\n
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.439 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/3")
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45629/3'
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.439 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.440 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.440 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.440 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.440 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /4\r\n
Oct 16 15:06:21.440 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.440 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/4")
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45629/4'
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.441 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.471 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.471 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.471 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.471 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /5\r\n
Oct 16 15:06:21.471 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.472 INFO blam! test response_301_auto_follow ... ok
Oct 16 15:06:21.472 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/5")
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.472 INFO blam! test redirect_limit_is_respected ... ok
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45629/5'
Oct 16 15:06:21.472 INFO blam! 
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8d0c26c030 [can pipeline]
Oct 16 15:06:21.472 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:21.472 INFO blam! 
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45629 (#1)
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:45629\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 2\r\n
Oct 16 15:06:21.472 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << ok
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received 2 bytes of data
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:21.473 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: request with token 2 completed
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 30 bytes
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Location: /6\r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:21.473 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::request: preparing for redirect to Some("/6")
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Maximum (5) redirects followed
Oct 16 15:06:21.473 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Oct 16 15:06:21.473 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:21.479 INFO kablam!      Running /target/debug/deps/response-a53d368bd5d4f55f
Oct 16 15:06:21.483 INFO blam! 
Oct 16 15:06:21.483 INFO blam! running 2 tests
Oct 16 15:06:21.500 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: agent ready
Oct 16 15:06:21.500 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8aac0a4000, handler: 0x7f8aac0a4000 }))
Oct 16 15:06:21.500 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f8aa9a4e000, handler: 0x7f8aa9a4e000 }))
Oct 16 15:06:21.500 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.500 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 46505 (#0)
Oct 16 15:06:21.500 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:46505\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f8aaa26c030 [serially]
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45307 (#1)
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:45307\r\nAccept: */*\r\n\r\n
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.501 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.539 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:21.539 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 13000\r\n
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:21.544 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.555 INFO kablam! TRACE 2018-10-16T15:06:21Z: 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
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received 8192 bytes of data
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 8192 bytes, consuming 32 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 8160 bytes, consuming 32 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 64 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 8128 bytes, consuming 64 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 128 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 8064 bytes, consuming 128 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 256 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 7936 bytes, consuming 256 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 512 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 7680 bytes, consuming 512 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 1024 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 7168 bytes, consuming 1024 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 2048 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 6144 bytes, consuming 2048 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 4096 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 4096 bytes, consuming 4096 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 8192 bytes
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: 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 
Oct 16 15:06:21.559 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received 4808 bytes of data
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 8192 bytes
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 3384 bytes
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:21.563 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: request with token 0 completed
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 3384 bytes
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:21.563 INFO kablam!  WARN 2018-10-16T15:06:21Z: chttp::internal::agent: received unpause request for unknown request token: 0
Oct 16 15:06:21.563 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.567 INFO blam! test large_response_body ... ok
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:21 GMT\r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << Content-Length: 11\r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << \r\n
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: << hello world
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received 11 bytes of data
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:21.571 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: request with token 1 completed
Oct 16 15:06:21.571 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:21.581 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:21.581 INFO blam! test simple_response_body ... ok
Oct 16 15:06:21.581 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Oct 16 15:06:21.581 INFO blam! 
Oct 16 15:06:21.581 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: received read request for 21 bytes
Oct 16 15:06:21.581 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:21.581 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:21.581 INFO blam! 
Oct 16 15:06:21.595 INFO kablam!      Running /target/debug/deps/timeouts-e5dce5e46228738e
Oct 16 15:06:21.603 INFO blam! 
Oct 16 15:06:21.603 INFO blam! running 1 test
Oct 16 15:06:21.610 INFO kablam! DEBUG 2018-10-16T15:06:21Z: chttp::internal::agent: agent ready
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f642f6a4000, handler: 0x7f642f6a4000 }))
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 40351 (#0)
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:40351\r\nAccept: */*\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:21.610 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:21.811 INFO kablam! TRACE 2018-10-16T15:06:21Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:22.011 INFO kablam! TRACE 2018-10-16T15:06:22Z: chttp::internal::request: Done waiting for 100-continue
Oct 16 15:06:22.011 INFO kablam! TRACE 2018-10-16T15:06:22Z: chttp::internal::agent: polling with timeout of 1s
Oct 16 15:06:22.011 INFO kablam! TRACE 2018-10-16T15:06:22Z: chttp::internal::request: >> hello world
Oct 16 15:06:22.011 INFO kablam! TRACE 2018-10-16T15:06:22Z: chttp::internal::request: We are completely uploaded and fine
Oct 16 15:06:22.011 INFO kablam! TRACE 2018-10-16T15:06:22Z: chttp::internal::agent: polling with timeout of 1s
Oct 16 15:06:23.011 INFO kablam! TRACE 2018-10-16T15:06:23Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Oct 16 15:06:23.011 INFO kablam! TRACE 2018-10-16T15:06:23Z: chttp::internal::request: Closing connection 0
Oct 16 15:06:23.011 INFO kablam! DEBUG 2018-10-16T15:06:23Z: chttp::internal::agent: curl error: [28] Timeout was reached
Oct 16 15:06:23.011 INFO kablam! TRACE 2018-10-16T15:06:23Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:23.011 INFO blam! test request_errors_if_read_timeout_is_reached ... ok
Oct 16 15:06:23.011 INFO blam! 
Oct 16 15:06:23.011 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:23.011 INFO blam! 
Oct 16 15:06:23.012 INFO kablam!    Doc-tests chttp
Oct 16 15:06:29.713 INFO blam! 
Oct 16 15:06:29.713 INFO blam! running 6 tests
Oct 16 15:06:55.827 INFO blam! test src/client.rs - client::ClientBuilder (line 17) ... ok
Oct 16 15:06:56.927 INFO blam! test src/lib.rs -  (line 53) ... ok
Oct 16 15:06:57.239 INFO blam! test src/lib.rs -  (line 74) ... ok
Oct 16 15:06:58.227 INFO blam! test src/lib.rs -  (line 26) ... ok
Oct 16 15:06:58.471 INFO blam! test src/lib.rs -  (line 11) ... ok
Oct 16 15:06:59.051 INFO blam! test src/lib.rs -  (line 37) ... ok
Oct 16 15:06:59.051 INFO blam! 
Oct 16 15:06:59.051 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:59.051 INFO blam! 
Oct 16 15:06:59.075 INFO kablam! su: No module specific data is present
Oct 16 15:06:59.845 INFO running `"docker" "rm" "-f" "10e42198ecad8defc4c4e1974bf4dc247b7b75409cf73659895746c19358dd81"`
Oct 16 15:07:00.153 INFO blam! 10e42198ecad8defc4c4e1974bf4dc247b7b75409cf73659895746c19358dd81