Nov 03 00:14:42.023 INFO testing chttp-0.2.2 against 1.30.0 for beta-1.31-1
Nov 03 00:14:42.024 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/1.30.0:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.0 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.407 INFO blam! 9516dcbb203b0afd90409c1bc3e2319859df0f124dd7cd2b0137757544ecd110
Nov 03 00:14:42.413 INFO running `"docker" "start" "-a" "9516dcbb203b0afd90409c1bc3e2319859df0f124dd7cd2b0137757544ecd110"`
Nov 03 00:14:43.706 INFO kablam! usermod: no changes
Nov 03 00:14:43.979 INFO kablam!    Compiling libnghttp2-sys v0.1.0
Nov 03 00:14:43.979 INFO kablam!    Compiling curl-sys v0.4.13
Nov 03 00:14:43.991 INFO kablam!    Compiling withers_derive v0.2.0
Nov 03 00:14:43.991 INFO kablam!    Compiling crossbeam-channel v0.2.6
Nov 03 00:15:09.015 INFO kablam!    Compiling curl v0.4.18
Nov 03 00:15:12.243 INFO kablam!    Compiling chttp v0.2.2 (/source)
Nov 03 00:15:19.447 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 35.70s
Nov 03 00:15:19.463 INFO kablam! su: No module specific data is present
Nov 03 00:15:20.071 INFO running `"docker" "rm" "-f" "9516dcbb203b0afd90409c1bc3e2319859df0f124dd7cd2b0137757544ecd110"`
Nov 03 00:15:20.384 INFO blam! 9516dcbb203b0afd90409c1bc3e2319859df0f124dd7cd2b0137757544ecd110
Nov 03 00:15:20.386 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/1.30.0:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.0 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.856 INFO blam! f0ba8de0b7b2bd529db56d6bda70c4f9517943ce63a2f1f10b5f52b2fb389174
Nov 03 00:15:20.859 INFO running `"docker" "start" "-a" "f0ba8de0b7b2bd529db56d6bda70c4f9517943ce63a2f1f10b5f52b2fb389174"`
Nov 03 00:15:22.486 INFO kablam! usermod: no changes
Nov 03 00:15:22.903 INFO kablam!    Compiling socket2 v0.3.8
Nov 03 00:15:22.907 INFO kablam!    Compiling futures-io-preview v0.2.2
Nov 03 00:15:22.907 INFO kablam!    Compiling buf_redux v0.6.3
Nov 03 00:15:22.907 INFO kablam!    Compiling libz-sys v1.0.25
Nov 03 00:15:22.919 INFO kablam!    Compiling twoway v0.1.8
Nov 03 00:15:22.919 INFO kablam!    Compiling gzip-header v0.2.0
Nov 03 00:15:22.919 INFO kablam!    Compiling tiny_http v0.6.0
Nov 03 00:15:22.919 INFO kablam!    Compiling crossbeam-channel v0.2.6
Nov 03 00:15:27.795 INFO kablam!    Compiling curl-sys v0.4.13
Nov 03 00:15:30.283 INFO kablam!    Compiling futures-util-preview v0.2.2
Nov 03 00:15:30.283 INFO kablam!    Compiling deflate v0.7.19
Nov 03 00:15:30.367 INFO kablam!    Compiling multipart v0.13.6
Nov 03 00:15:33.839 INFO kablam!    Compiling curl v0.4.18
Nov 03 00:15:44.467 INFO kablam!    Compiling rouille v2.2.0
Nov 03 00:15:47.711 INFO kablam!    Compiling futures-executor-preview v0.2.2
Nov 03 00:15:51.655 INFO kablam!    Compiling futures-stable-preview v0.2.3
Nov 03 00:15:51.803 INFO kablam!    Compiling futures-async-runtime-preview v0.2.3
Nov 03 00:15:51.975 INFO kablam!    Compiling futures-preview v0.2.2
Nov 03 00:15:54.007 INFO kablam!    Compiling chttp v0.2.2 (/source)
Nov 03 00:16:44.600 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1m 22s
Nov 03 00:16:44.618 INFO kablam! su: No module specific data is present
Nov 03 00:16:45.615 INFO running `"docker" "rm" "-f" "f0ba8de0b7b2bd529db56d6bda70c4f9517943ce63a2f1f10b5f52b2fb389174"`
Nov 03 00:16:45.819 INFO blam! f0ba8de0b7b2bd529db56d6bda70c4f9517943ce63a2f1f10b5f52b2fb389174
Nov 03 00:16:45.827 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/1.30.0:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.0 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:46.408 INFO blam! d1cb7a387ae57589c753f7ef5795a7068c59d15a83110978b0a476cbd85edb64
Nov 03 00:16:46.411 INFO running `"docker" "start" "-a" "d1cb7a387ae57589c753f7ef5795a7068c59d15a83110978b0a476cbd85edb64"`
Nov 03 00:16:48.278 INFO kablam! usermod: no changes
Nov 03 00:16:48.555 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.23s
Nov 03 00:16:48.567 INFO kablam!      Running /target/debug/deps/chttp-243aa5f4ec361030
Nov 03 00:16:48.567 INFO blam! 
Nov 03 00:16:48.567 INFO blam! running 0 tests
Nov 03 00:16:48.567 INFO blam! 
Nov 03 00:16:48.567 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:48.567 INFO blam! 
Nov 03 00:16:48.567 INFO kablam!      Running /target/debug/deps/redirects-1127f04af6c62678
Nov 03 00:16:48.571 INFO blam! 
Nov 03 00:16:48.571 INFO blam! running 3 tests
Nov 03 00:16:48.583 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::agent: agent ready
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f353e4a4000, handler: 0x7f353e4a4000 }))
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f353c24e000, handler: 0x7f353c24e000 }))
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42789 (#0)
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:42789\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [serially]
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 43807 (#1)
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:43807\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:48.583 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f353ae51000, handler: 0x7f353ae51000 }))
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [serially]
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.591 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.695 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.698 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.704 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.707 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /b\r\n
Nov 03 00:16:48.707 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.707 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/b")
Nov 03 00:16:48.708 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.711 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Nov 03 00:16:48.711 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42789/b'
Nov 03 00:16:48.712 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.712 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.712 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Nov 03 00:16:48.715 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42789 (#0)
Nov 03 00:16:48.715 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:42789\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.747 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:48.747 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.755 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.755 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:48.755 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 2\r\n
Nov 03 00:16:48.756 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.756 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << ok
Nov 03 00:16:48.756 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: received 2 bytes of data
Nov 03 00:16:48.756 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Nov 03 00:16:48.765 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.766 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.766 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.766 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /b\r\n
Nov 03 00:16:48.766 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.767 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.767 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:48.767 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.767 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.768 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.768 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /1\r\n
Nov 03 00:16:48.768 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.769 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/1")
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: received read request for 30 bytes
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42435/1'
Nov 03 00:16:48.775 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.776 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.776 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:48.776 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.776 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.777 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::agent: request with token 0 completed
Nov 03 00:16:48.777 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: received read request for 30 bytes
Nov 03 00:16:48.777 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:48.777 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::agent: request with token 1 completed
Nov 03 00:16:48.777 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.777 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:48.777 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Nov 03 00:16:48.777 INFO kablam!  WARN 2018-11-03T00:16:48Z: chttp::internal::agent: received unpause request for unknown request token: 0
Nov 03 00:16:48.778 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.778 INFO blam! test response_301_auto_follow ... ok
Nov 03 00:16:48.791 INFO blam! test response_301_no_follow ... ok
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /2\r\n
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.808 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/2")
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.808 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42435/2'
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.809 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.815 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.815 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.815 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.815 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /3\r\n
Nov 03 00:16:48.815 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.815 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/3")
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42435/3'
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.816 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /4\r\n
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.827 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/4")
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42435/4'
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.827 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:48.828 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.828 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.828 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /5\r\n
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.859 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/5")
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:42435/5'
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f353b06c030 [can pipeline]
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Could pipeline, but not asked to!
Nov 03 00:16:48.859 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Nov 03 00:16:48.860 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42435 (#2)
Nov 03 00:16:48.860 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:42435\r\nAccept: */*\r\n\r\n
Nov 03 00:16:48.860 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:48.875 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Nov 03 00:16:48.875 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:48.875 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:48 GMT\r\n
Nov 03 00:16:48.875 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Location: /6\r\n
Nov 03 00:16:48.875 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << Content-Length: 0\r\n
Nov 03 00:16:48.875 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::request: preparing for redirect to Some("/6")
Nov 03 00:16:48.876 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: << \r\n
Nov 03 00:16:48.876 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Nov 03 00:16:48.876 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Maximum (5) redirects followed
Nov 03 00:16:48.876 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Nov 03 00:16:48.876 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:48.883 INFO blam! test redirect_limit_is_respected ... ok
Nov 03 00:16:48.883 INFO blam! 
Nov 03 00:16:48.883 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:48.883 INFO blam! 
Nov 03 00:16:48.885 INFO kablam!      Running /target/debug/deps/response-2a6d0bb9c5158f28
Nov 03 00:16:48.893 INFO blam! 
Nov 03 00:16:48.893 INFO blam! running 2 tests
Nov 03 00:16:48.999 INFO kablam! DEBUG 2018-11-03T00:16:48Z: chttp::internal::agent: agent ready
Nov 03 00:16:48.999 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f5a4eea4000, handler: 0x7f5a4eea4000 }))
Nov 03 00:16:48.999 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f5a4e24e000, handler: 0x7f5a4e24e000 }))
Nov 03 00:16:48.999 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:48Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 44911 (#0)
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:44911\r\nAccept: */*\r\n\r\n
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f5a49c6c030 [serially]
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Server doesn't support multi-use (yet)
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:49.000 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41873 (#1)
Nov 03 00:16:49.001 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:41873\r\nAccept: */*\r\n\r\n
Nov 03 00:16:49.001 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.001 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:49.001 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.040 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:49.051 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:49.053 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:49 GMT\r\n
Nov 03 00:16:49.059 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:49.059 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Content-Length: 11\r\n
Nov 03 00:16:49.059 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << \r\n
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << hello world
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received 11 bytes of data
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 21 bytes
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Nov 03 00:16:49.060 INFO kablam! DEBUG 2018-11-03T00:16:49Z: chttp::internal::agent: request with token 1 completed
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 21 bytes
Nov 03 00:16:49.060 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:49.061 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:49.061 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:49.061 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Nov 03 00:16:49.061 INFO kablam!  WARN 2018-11-03T00:16:49Z: chttp::internal::agent: received unpause request for unknown request token: 1
Nov 03 00:16:49.061 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Nov 03 00:16:49.061 INFO kablam!  WARN 2018-11-03T00:16:49Z: chttp::internal::agent: received unpause request for unknown request token: 1
Nov 03 00:16:49.061 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Date: Sat,  3 Nov 2018 00:16:49 GMT\r\n
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << Content-Length: 13000\r\n
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:49.062 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: << \r\n
Nov 03 00:16:49.064 INFO kablam! TRACE 2018-11-03T00:16:49Z: 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 
Nov 03 00:16:49.079 INFO blam! test simple_response_body ... ok
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received 8047 bytes of data
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 8047 bytes, consuming 32 bytes
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 32 bytes
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 8015 bytes, consuming 32 bytes
Nov 03 00:16:49.083 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 64 bytes
Nov 03 00:16:49.084 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 7983 bytes, consuming 64 bytes
Nov 03 00:16:49.084 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 128 bytes
Nov 03 00:16:49.084 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 7919 bytes, consuming 128 bytes
Nov 03 00:16:49.084 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 256 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 7791 bytes, consuming 256 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 512 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 7535 bytes, consuming 512 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 1024 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 7023 bytes, consuming 1024 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 2048 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 5999 bytes, consuming 2048 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 4096 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 3951 bytes, consuming 3951 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 145 bytes
Nov 03 00:16:49.095 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:49.096 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:49.096 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:49.096 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Nov 03 00:16:49.096 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Nov 03 00:16:49.103 INFO kablam! TRACE 2018-11-03T00:16:49Z: 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 
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received 4953 bytes of data
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 145 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 4953 bytes, consuming 145 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 8192 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 3384 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: buffer is empty, read is pending
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Nov 03 00:16:49.104 INFO kablam! DEBUG 2018-11-03T00:16:49Z: chttp::internal::agent: request with token 0 completed
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: received read request for 3384 bytes
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: request is closed, satisfying read request with EOF
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:49.104 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:49.105 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Nov 03 00:16:49.105 INFO kablam!  WARN 2018-11-03T00:16:49Z: chttp::internal::agent: received unpause request for unknown request token: 0
Nov 03 00:16:49.111 INFO blam! test large_response_body ... ok
Nov 03 00:16:49.111 INFO blam! 
Nov 03 00:16:49.111 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:49.111 INFO blam! 
Nov 03 00:16:49.113 INFO kablam!      Running /target/debug/deps/timeouts-3aab5cad4dca895d
Nov 03 00:16:49.125 INFO blam! 
Nov 03 00:16:49.125 INFO blam! running 1 test
Nov 03 00:16:49.129 INFO kablam! DEBUG 2018-11-03T00:16:49Z: chttp::internal::agent: agent ready
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7ff64e2a4000, handler: 0x7ff64e2a4000 }))
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request:   Trying 127.0.0.1...
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 38389 (#0)
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:38389\r\nAccept: */*\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: woke up from notify fd
Nov 03 00:16:49.129 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.330 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.544 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.744 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:49.944 INFO kablam! TRACE 2018-11-03T00:16:49Z: chttp::internal::agent: polling with timeout of 200ms
Nov 03 00:16:50.145 INFO kablam! TRACE 2018-11-03T00:16:50Z: chttp::internal::request: Done waiting for 100-continue
Nov 03 00:16:50.145 INFO kablam! TRACE 2018-11-03T00:16:50Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:50.145 INFO kablam! TRACE 2018-11-03T00:16:50Z: chttp::internal::request: >> hello world
Nov 03 00:16:50.145 INFO kablam! TRACE 2018-11-03T00:16:50Z: chttp::internal::request: We are completely uploaded and fine
Nov 03 00:16:50.145 INFO kablam! TRACE 2018-11-03T00:16:50Z: chttp::internal::agent: polling with timeout of 1s
Nov 03 00:16:51.146 INFO kablam! TRACE 2018-11-03T00:16:51Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Nov 03 00:16:51.147 INFO kablam! TRACE 2018-11-03T00:16:51Z: chttp::internal::request: Closing connection 0
Nov 03 00:16:51.147 INFO blam! test request_errors_if_read_timeout_is_reached ... ok
Nov 03 00:16:51.147 INFO kablam! DEBUG 2018-11-03T00:16:51Z: chttp::internal::agent: curl error: [28] Timeout was reached
Nov 03 00:16:51.147 INFO blam! 
Nov 03 00:16:51.147 INFO kablam! TRACE 2018-11-03T00:16:51Z: chttp::internal::agent: polling with timeout of 100ms
Nov 03 00:16:51.147 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:16:51.147 INFO blam! 
Nov 03 00:16:51.149 INFO kablam!    Doc-tests chttp
Nov 03 00:16:59.020 INFO blam! 
Nov 03 00:16:59.020 INFO blam! running 6 tests
Nov 03 00:17:37.099 INFO blam! test src/lib.rs -  (line 74) ... ok
Nov 03 00:17:38.828 INFO blam! test src/lib.rs -  (line 53) ... ok
Nov 03 00:17:38.839 INFO blam! test src/lib.rs -  (line 37) ... ok
Nov 03 00:17:39.718 INFO blam! test src/client.rs - client::ClientBuilder (line 17) ... ok
Nov 03 00:17:40.306 INFO blam! test src/lib.rs -  (line 26) ... ok
Nov 03 00:17:41.300 INFO blam! test src/lib.rs -  (line 11) ... ok
Nov 03 00:17:41.300 INFO blam! 
Nov 03 00:17:41.301 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:17:41.301 INFO blam! 
Nov 03 00:17:41.327 INFO kablam! su: No module specific data is present
Nov 03 00:17:42.383 INFO running `"docker" "rm" "-f" "d1cb7a387ae57589c753f7ef5795a7068c59d15a83110978b0a476cbd85edb64"`
Nov 03 00:17:42.738 INFO blam! d1cb7a387ae57589c753f7ef5795a7068c59d15a83110978b0a476cbd85edb64
