Nov 03 00:14:41.841 INFO testing chttp-0.2.2 against beta-2018-10-30 for beta-1.31-1
Nov 03 00:14:41.842 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 03 00:14:42.340 INFO blam! 151b60b69144f8d2ddb38a9aed8e1108abc6dba6e2de75f3b2adf587d62a4f84
Nov 03 00:14:42.343 INFO running `"docker" "start" "-a" "151b60b69144f8d2ddb38a9aed8e1108abc6dba6e2de75f3b2adf587d62a4f84"`
Nov 03 00:14:43.459 INFO kablam! usermod: no changes
Nov 03 00:14:43.874 INFO kablam!    Compiling libnghttp2-sys v0.1.0
Nov 03 00:14:43.874 INFO kablam!    Compiling curl-sys v0.4.13
Nov 03 00:14:43.877 INFO kablam!    Compiling withers_derive v0.2.0
Nov 03 00:15:07.815 INFO kablam!    Compiling curl v0.4.18
Nov 03 00:15:11.171 INFO kablam!    Compiling chttp v0.2.2 (/source)
Nov 03 00:15:18.521 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 35.02s
Nov 03 00:15:18.534 INFO kablam! su: No module specific data is present
Nov 03 00:15:19.384 INFO running `"docker" "rm" "-f" "151b60b69144f8d2ddb38a9aed8e1108abc6dba6e2de75f3b2adf587d62a4f84"`
Nov 03 00:15:19.639 INFO blam! 151b60b69144f8d2ddb38a9aed8e1108abc6dba6e2de75f3b2adf587d62a4f84
Nov 03 00:15:19.675 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 03 00:15:20.111 INFO blam! b4a6a7308326ad2759b5dbb1eb4761d4c4a9f52e8d1a2e829e49f2678a0d1215
Nov 03 00:15:20.115 INFO running `"docker" "start" "-a" "b4a6a7308326ad2759b5dbb1eb4761d4c4a9f52e8d1a2e829e49f2678a0d1215"`
Nov 03 00:15:21.282 INFO kablam! usermod: no changes
Nov 03 00:15:21.714 INFO kablam!    Compiling chttp v0.2.2 (/source)
Nov 03 00:16:07.368 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 46.05s
Nov 03 00:16:07.407 INFO kablam! su: No module specific data is present
Nov 03 00:16:07.845 INFO running `"docker" "rm" "-f" "b4a6a7308326ad2759b5dbb1eb4761d4c4a9f52e8d1a2e829e49f2678a0d1215"`
Nov 03 00:16:08.031 INFO blam! b4a6a7308326ad2759b5dbb1eb4761d4c4a9f52e8d1a2e829e49f2678a0d1215
Nov 03 00:16:08.038 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 03 00:16:08.451 INFO blam! e6b99e64fa8647568717450a45ff6fad205eb10532a0e3bda2dd3d584bbc4b56
Nov 03 00:16:08.452 INFO running `"docker" "start" "-a" "e6b99e64fa8647568717450a45ff6fad205eb10532a0e3bda2dd3d584bbc4b56"`
Nov 03 00:16:09.743 INFO kablam! usermod: no changes
Nov 03 00:16:10.115 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.32s
Nov 03 00:16:10.147 INFO kablam!      Running /target/debug/deps/chttp-cef1d968e5015c04
Nov 03 00:16:10.151 INFO blam! 
Nov 03 00:16:10.151 INFO blam! running 0 tests
Nov 03 00:16:10.151 INFO blam! 
Nov 03 00:16:10.151 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:10.151 INFO blam! 
Nov 03 00:16:10.155 INFO kablam!      Running /target/debug/deps/redirects-f91b06bc100ba549
Nov 03 00:16:10.167 INFO blam! 
Nov 03 00:16:10.167 INFO blam! running 3 tests
Nov 03 00:16:10.251 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: agent ready
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fced22a4000, handler: 0x7fced22a4000 }))
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34113 (#0)
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:34113\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.251 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fced2051000, handler: 0x7fced2051000 }))
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fced1e51000, handler: 0x7fced1e51000 }))
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [serially]
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [serially]
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44735 (#2)
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:44735\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.263 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.264 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.266 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.266 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /b\r\n
Nov 03 00:16:10.266 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.270 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.270 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Nov 03 00:16:10.271 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: request with token 0 completed
Nov 03 00:16:10.271 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /1\r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.283 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/1")
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:35469/1'
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.283 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /b\r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.291 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/b")
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:44735/b'
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44735 (#2)
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:44735\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.291 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.291 INFO blam! test response_301_no_follow ... ok
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /2\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.299 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/2")
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:35469/2'
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 2\r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << ok
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received 2 bytes of data
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:10.299 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: request with token 2 completed
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 30 bytes
Nov 03 00:16:10.299 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:10.300 INFO blam! test response_301_auto_follow ... ok
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /3\r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.343 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/3")
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:35469/3'
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.343 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /4\r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.367 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/4")
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:35469/4'
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.367 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /5\r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.375 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/5")
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:35469/5'
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fced006c030 [can pipeline]
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Re-using existing connection! (#1) with host 127.0.0.1
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35469 (#1)
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:35469\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.375 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Location: /6\r\n
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:10.387 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::request: preparing for redirect to Some("/6")
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Maximum (5) redirects followed
Nov 03 00:16:10.387 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Nov 03 00:16:10.387 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:10.387 INFO blam! test redirect_limit_is_respected ... ok
Nov 03 00:16:10.387 INFO blam! 
Nov 03 00:16:10.387 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:10.387 INFO blam! 
Nov 03 00:16:10.393 INFO kablam!      Running /target/debug/deps/response-2053e25da19ba9c9
Nov 03 00:16:10.399 INFO blam! 
Nov 03 00:16:10.399 INFO blam! running 2 tests
Nov 03 00:16:10.407 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: agent ready
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f4ea1aa4000, handler: 0x7f4ea1aa4000 }))
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 34557 (#0)
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:34557\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.407 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f4e9dc4e000, handler: 0x7f4e9dc4e000 }))
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f4ea0c6c030 [serially]
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44739 (#1)
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:44739\r\nAccept: */*\r\n\r\n
Nov 03 00:16:10.423 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.440 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 11\r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << hello world
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received 11 bytes of data
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Nov 03 00:16:10.441 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: request with token 0 completed
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 21 bytes
Nov 03 00:16:10.441 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:10.447 INFO blam! test simple_response_body ... ok
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:10 GMT\r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << Content-Length: 13000\r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: << \r\n
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.470 INFO kablam! TRACE 2018-11-03T00:16:10Z: 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
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received 8192 bytes of data
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 8192 bytes, consuming 32 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 8160 bytes, consuming 32 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 64 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 8128 bytes, consuming 64 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 128 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 8064 bytes, consuming 128 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 256 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 7936 bytes, consuming 256 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 512 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 7680 bytes, consuming 512 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 1024 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 7168 bytes, consuming 1024 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 2048 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 6144 bytes, consuming 2048 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 4096 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 4096 bytes, consuming 4096 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 8192 bytes
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Nov 03 00:16:10.475 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: 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 
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received 4808 bytes of data
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:10.476 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: request with token 1 completed
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 8192 bytes
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: received read request for 3384 bytes
Nov 03 00:16:10.476 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:10.477 INFO blam! test large_response_body ... ok
Nov 03 00:16:10.477 INFO blam! 
Nov 03 00:16:10.477 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:10.477 INFO blam! 
Nov 03 00:16:10.487 INFO kablam!      Running /target/debug/deps/timeouts-a8057aceb2278950
Nov 03 00:16:10.487 INFO blam! 
Nov 03 00:16:10.487 INFO blam! running 1 test
Nov 03 00:16:10.503 INFO kablam! DEBUG 2018-11-03T00:16:10Z: chttp::internal::agent: agent ready
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f9c864a4000, handler: 0x7f9c864a4000 }))
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 40289 (#0)
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:40289\r\nAccept: */*\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:10.503 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.711 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:10.911 INFO kablam! TRACE 2018-11-03T00:16:10Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:11.110 INFO kablam! TRACE 2018-11-03T00:16:11Z: chttp::internal::request: Done waiting for 100-continue
Nov 03 00:16:11.110 INFO kablam! TRACE 2018-11-03T00:16:11Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:11.110 INFO kablam! TRACE 2018-11-03T00:16:11Z: chttp::internal::request: >> hello world
Nov 03 00:16:11.110 INFO kablam! TRACE 2018-11-03T00:16:11Z: chttp::internal::request: We are completely uploaded and fine
Nov 03 00:16:11.110 INFO kablam! TRACE 2018-11-03T00:16:11Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:12.110 INFO kablam! TRACE 2018-11-03T00:16:12Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Nov 03 00:16:12.113 INFO kablam! TRACE 2018-11-03T00:16:12Z: chttp::internal::request: Closing connection 0
Nov 03 00:16:12.113 INFO kablam! DEBUG 2018-11-03T00:16:12Z: chttp::internal::agent: curl error: [28] Timeout was reached
Nov 03 00:16:12.113 INFO kablam! TRACE 2018-11-03T00:16:12Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:12.116 INFO blam! test request_errors_if_read_timeout_is_reached ... ok
Nov 03 00:16:12.116 INFO blam! 
Nov 03 00:16:12.116 INFO kablam!    Doc-tests chttp
Nov 03 00:16:12.116 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:12.116 INFO blam! 
Nov 03 00:16:19.187 INFO blam! 
Nov 03 00:16:19.187 INFO blam! running 6 tests
Nov 03 00:16:54.051 INFO blam! test src/lib.rs -  (line 26) ... ok
Nov 03 00:16:57.266 INFO blam! test src/lib.rs -  (line 11) ... ok
Nov 03 00:16:57.689 INFO blam! test src/lib.rs -  (line 37) ... ok
Nov 03 00:16:57.835 INFO blam! test src/lib.rs -  (line 53) ... ok
Nov 03 00:16:58.659 INFO blam! test src/lib.rs -  (line 74) ... ok
Nov 03 00:16:58.991 INFO blam! test src/client.rs - client::ClientBuilder (line 17) ... ok
Nov 03 00:16:58.991 INFO blam! 
Nov 03 00:16:58.991 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:58.991 INFO blam! 
Nov 03 00:16:59.023 INFO kablam! su: No module specific data is present
Nov 03 00:16:59.652 INFO running `"docker" "rm" "-f" "e6b99e64fa8647568717450a45ff6fad205eb10532a0e3bda2dd3d584bbc4b56"`
Nov 03 00:16:59.983 INFO blam! e6b99e64fa8647568717450a45ff6fad205eb10532a0e3bda2dd3d584bbc4b56
