Oct 16 15:04:58.750 INFO testing chttp-0.2.2 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 16 15:04:58.752 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:59.331 INFO blam! e96206d0e0ee16b909e958542712fc267b7c38ea54670f51b06789d5c886d364
Oct 16 15:04:59.331 INFO running `"docker" "start" "-a" "e96206d0e0ee16b909e958542712fc267b7c38ea54670f51b06789d5c886d364"`
Oct 16 15:05:00.427 INFO kablam! usermod: no changes
Oct 16 15:05:00.682 INFO kablam!    Compiling libnghttp2-sys v0.1.0
Oct 16 15:05:00.688 INFO kablam!    Compiling curl-sys v0.4.13
Oct 16 15:05:00.729 INFO kablam!    Compiling withers_derive v0.2.0
Oct 16 15:05:00.729 INFO kablam!    Compiling crossbeam-channel v0.2.6
Oct 16 15:05:21.423 INFO kablam!    Compiling curl v0.4.18
Oct 16 15:05:24.261 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 16 15:05:29.715 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 29.27s
Oct 16 15:05:29.742 INFO kablam! su: No module specific data is present
Oct 16 15:05:30.760 INFO running `"docker" "rm" "-f" "e96206d0e0ee16b909e958542712fc267b7c38ea54670f51b06789d5c886d364"`
Oct 16 15:05:30.975 INFO blam! e96206d0e0ee16b909e958542712fc267b7c38ea54670f51b06789d5c886d364
Oct 16 15:05:30.981 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:31.389 INFO blam! 432593e76002400c745df69f5f55398ea19e28a577e967c3544fbecc8939c646
Oct 16 15:05:31.393 INFO running `"docker" "start" "-a" "432593e76002400c745df69f5f55398ea19e28a577e967c3544fbecc8939c646"`
Oct 16 15:05:32.029 INFO kablam! usermod: no changes
Oct 16 15:05:32.366 INFO kablam!    Compiling futures-io-preview v0.2.2
Oct 16 15:05:32.366 INFO kablam!    Compiling nix v0.11.0
Oct 16 15:05:32.367 INFO kablam!    Compiling curl-sys v0.4.13
Oct 16 15:05:32.374 INFO kablam!    Compiling crossbeam-channel v0.2.6
Oct 16 15:05:33.367 INFO kablam!    Compiling futures-util-preview v0.2.2
Oct 16 15:05:33.595 INFO kablam!    Compiling curl v0.4.18
Oct 16 15:05:40.199 INFO kablam!    Compiling futures-executor-preview v0.2.2
Oct 16 15:05:43.128 INFO kablam!    Compiling futures-stable-preview v0.2.3
Oct 16 15:05:43.371 INFO kablam!    Compiling futures-async-runtime-preview v0.2.3
Oct 16 15:05:43.731 INFO kablam!    Compiling futures-preview v0.2.2
Oct 16 15:05:45.619 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 16 15:06:26.923 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 54.88s
Oct 16 15:06:26.948 INFO kablam! su: No module specific data is present
Oct 16 15:06:27.899 INFO running `"docker" "rm" "-f" "432593e76002400c745df69f5f55398ea19e28a577e967c3544fbecc8939c646"`
Oct 16 15:06:28.119 INFO blam! 432593e76002400c745df69f5f55398ea19e28a577e967c3544fbecc8939c646
Oct 16 15:06:28.132 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:28.543 INFO blam! ccbea0cb6d6c7aabc5c48d4546ead06126a62a8f73f6b616d1f625aa6f6035a4
Oct 16 15:06:28.549 INFO running `"docker" "start" "-a" "ccbea0cb6d6c7aabc5c48d4546ead06126a62a8f73f6b616d1f625aa6f6035a4"`
Oct 16 15:06:29.755 INFO kablam! usermod: no changes
Oct 16 15:06:30.042 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Oct 16 15:06:30.067 INFO kablam!      Running /target/debug/deps/chttp-a0bd14e2ef7485d4
Oct 16 15:06:30.070 INFO blam! 
Oct 16 15:06:30.071 INFO blam! running 0 tests
Oct 16 15:06:30.071 INFO blam! 
Oct 16 15:06:30.071 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:30.072 INFO blam! 
Oct 16 15:06:30.079 INFO blam! 
Oct 16 15:06:30.079 INFO kablam!      Running /target/debug/deps/redirects-e158d455449e77a6
Oct 16 15:06:30.079 INFO blam! running 3 tests
Oct 16 15:06:30.115 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: agent ready
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fcb12e51000, handler: 0x7fcb12e51000 }))
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fcb13ca4000, handler: 0x7fcb13ca4000 }))
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7fcb10651000, handler: 0x7fcb10651000 }))
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [serially]
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 32949 (#1)
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:32949\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [serially]
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 39449 (#2)
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:39449\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:30.115 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.163 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.163 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.167 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /1\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.168 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/1")
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45451/1'
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /2\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.168 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/2")
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45451/2'
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.168 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.184 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.184 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /b\r\n
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.185 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/b")
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:39449/b'
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 39449 (#2)
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:39449\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.185 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /3\r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.196 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/3")
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45451/3'
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.196 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /b\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:30.201 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: request with token 1 completed
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 2\r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << ok
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received 2 bytes of data
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 16 15:06:30.201 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: request with token 2 completed
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 30 bytes
Oct 16 15:06:30.201 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:30.211 INFO blam! test response_301_no_follow ... ok
Oct 16 15:06:30.212 INFO blam! test response_301_auto_follow ... ok
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /4\r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.212 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/4")
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45451/4'
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.212 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.228 INFO blam! test redirect_limit_is_respected ... ok
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.228 INFO blam! 
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.228 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /5\r\n
Oct 16 15:06:30.228 INFO blam! 
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.228 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/5")
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:45451/5'
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7fcb0e86c030 [can pipeline]
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 45451 (#0)
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:45451\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Location: /6\r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 16 15:06:30.228 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::request: preparing for redirect to Some("/6")
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Maximum (5) redirects followed
Oct 16 15:06:30.228 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Oct 16 15:06:30.228 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:30.244 INFO kablam!      Running /target/debug/deps/response-81c5f0490288ca06
Oct 16 15:06:30.244 INFO blam! 
Oct 16 15:06:30.244 INFO blam! running 2 tests
Oct 16 15:06:30.250 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: agent ready
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f1e0aaa4000, handler: 0x7f1e0aaa4000 }))
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f1e09e4e000, handler: 0x7f1e09e4e000 }))
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44431 (#0)
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:44431\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f1e0606c030 [serially]
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 43801 (#1)
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:43801\r\nAccept: */*\r\n\r\n
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:30.255 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.262 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:30.263 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 13000\r\n
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large 
Oct 16 15:06:30.271 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received 8047 bytes of data
Oct 16 15:06:30.282 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 8047 bytes, consuming 32 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 8015 bytes, consuming 32 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 64 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 7983 bytes, consuming 64 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 128 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 7919 bytes, consuming 128 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 256 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 7791 bytes, consuming 256 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 512 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 7535 bytes, consuming 512 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 1024 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 7023 bytes, consuming 1024 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 2048 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 5999 bytes, consuming 2048 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 4096 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 3951 bytes, consuming 3951 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 145 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Date: Tue, 16 Oct 2018 15:06:30 GMT\r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << Content-Length: 11\r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << \r\n
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << hello world
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received 11 bytes of data
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 16 15:06:30.283 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: request with token 1 completed
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: << wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large 
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received 4953 bytes of data
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 32 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 21 bytes
Oct 16 15:06:30.283 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 145 bytes
Oct 16 15:06:30.291 INFO blam! test simple_response_body ... ok
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 4953 bytes, consuming 145 bytes
Oct 16 15:06:30.291 INFO blam! test large_response_body ... ok
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 8192 bytes
Oct 16 15:06:30.291 INFO blam! 
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Oct 16 15:06:30.291 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 3384 bytes
Oct 16 15:06:30.291 INFO blam! 
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: buffer is empty, read is pending
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 16 15:06:30.291 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: request with token 0 completed
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: received read request for 3384 bytes
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:30.291 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 16 15:06:30.291 INFO kablam!  WARN 2018-10-16T15:06:30Z: chttp::internal::agent: received unpause request for unknown request token: 0
Oct 16 15:06:30.291 INFO kablam!      Running /target/debug/deps/timeouts-842f3e97e06d246c
Oct 16 15:06:30.295 INFO blam! 
Oct 16 15:06:30.296 INFO blam! running 1 test
Oct 16 15:06:30.309 INFO kablam! DEBUG 2018-10-16T15:06:30Z: chttp::internal::agent: agent ready
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7ff0328a4000, handler: 0x7ff0328a4000 }))
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 40421 (#0)
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:40421\r\nAccept: */*\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: woke up from notify fd
Oct 16 15:06:30.309 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.515 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.718 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:30.923 INFO kablam! TRACE 2018-10-16T15:06:30Z: chttp::internal::agent: polling with timeout of 200ms
Oct 16 15:06:31.114 INFO kablam! TRACE 2018-10-16T15:06:31Z: chttp::internal::request: Done waiting for 100-continue
Oct 16 15:06:31.115 INFO kablam! TRACE 2018-10-16T15:06:31Z: chttp::internal::agent: polling with timeout of 1s
Oct 16 15:06:31.115 INFO kablam! TRACE 2018-10-16T15:06:31Z: chttp::internal::request: >> hello world
Oct 16 15:06:31.115 INFO kablam! TRACE 2018-10-16T15:06:31Z: chttp::internal::request: We are completely uploaded and fine
Oct 16 15:06:31.115 INFO kablam! TRACE 2018-10-16T15:06:31Z: chttp::internal::agent: polling with timeout of 1s
Oct 16 15:06:32.115 INFO kablam! TRACE 2018-10-16T15:06:32Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Oct 16 15:06:32.115 INFO kablam! TRACE 2018-10-16T15:06:32Z: chttp::internal::request: Closing connection 0
Oct 16 15:06:32.115 INFO kablam! DEBUG 2018-10-16T15:06:32Z: chttp::internal::agent: curl error: [28] Timeout was reached
Oct 16 15:06:32.115 INFO kablam! TRACE 2018-10-16T15:06:32Z: chttp::internal::agent: polling with timeout of 100ms
Oct 16 15:06:32.115 INFO kablam!    Doc-tests chttp
Oct 16 15:06:32.115 INFO blam! test request_errors_if_read_timeout_is_reached ... ok
Oct 16 15:06:32.115 INFO blam! 
Oct 16 15:06:32.115 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:06:32.115 INFO blam! 
Oct 16 15:06:39.091 INFO blam! 
Oct 16 15:06:39.091 INFO blam! running 6 tests
Oct 16 15:07:04.591 INFO blam! test src/lib.rs -  (line 74) ... ok
Oct 16 15:07:05.663 INFO blam! test src/lib.rs -  (line 53) ... ok
Oct 16 15:07:06.811 INFO blam! test src/client.rs - client::ClientBuilder (line 17) ... ok
Oct 16 15:07:07.347 INFO blam! test src/lib.rs -  (line 37) ... ok
Oct 16 15:07:07.467 INFO blam! test src/lib.rs -  (line 11) ... ok
Oct 16 15:07:08.039 INFO blam! test src/lib.rs -  (line 26) ... ok
Oct 16 15:07:08.039 INFO blam! 
Oct 16 15:07:08.039 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 15:07:08.039 INFO blam! 
Oct 16 15:07:08.075 INFO kablam! su: No module specific data is present
Oct 16 15:07:08.619 INFO running `"docker" "rm" "-f" "ccbea0cb6d6c7aabc5c48d4546ead06126a62a8f73f6b616d1f625aa6f6035a4"`
Oct 16 15:07:08.933 INFO blam! ccbea0cb6d6c7aabc5c48d4546ead06126a62a8f73f6b616d1f625aa6f6035a4