Oct 12 03:12:56.006 INFO testing chttp-0.2.2 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 12 03:12:56.006 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 03:12:56.467 INFO blam! 71905dceecb749c803153a3b45d2af1dec95170a1f06867c21d54be8db6c25b8
Oct 12 03:12:56.470 INFO running `"docker" "start" "-a" "71905dceecb749c803153a3b45d2af1dec95170a1f06867c21d54be8db6c25b8"`
Oct 12 03:12:57.690 INFO kablam! usermod: no changes
Oct 12 03:12:58.040 INFO kablam!    Compiling libnghttp2-sys v0.1.0
Oct 12 03:12:58.044 INFO kablam!    Compiling curl-sys v0.4.13
Oct 12 03:12:58.045 INFO kablam!    Compiling withers_derive v0.2.0
Oct 12 03:13:22.352 INFO kablam!    Compiling curl v0.4.18
Oct 12 03:13:25.944 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 12 03:13:33.800 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 36.07s
Oct 12 03:13:33.825 INFO kablam! su: No module specific data is present
Oct 12 03:13:34.328 INFO running `"docker" "rm" "-f" "71905dceecb749c803153a3b45d2af1dec95170a1f06867c21d54be8db6c25b8"`
Oct 12 03:13:34.588 INFO blam! 71905dceecb749c803153a3b45d2af1dec95170a1f06867c21d54be8db6c25b8
Oct 12 03:13:34.592 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 03:13:34.989 INFO blam! e09d8eff0f6a1da26b3552baf14c1d08510b69814733b965afc870455ad9b7b2
Oct 12 03:13:34.996 INFO running `"docker" "start" "-a" "e09d8eff0f6a1da26b3552baf14c1d08510b69814733b965afc870455ad9b7b2"`
Oct 12 03:13:36.156 INFO kablam! usermod: no changes
Oct 12 03:13:36.584 INFO kablam!    Compiling futures-io-preview v0.2.2
Oct 12 03:13:36.584 INFO kablam!    Compiling curl-sys v0.4.13
Oct 12 03:13:36.586 INFO kablam!    Compiling crossbeam-channel v0.2.6
Oct 12 03:13:36.586 INFO kablam!    Compiling multipart v0.13.6
Oct 12 03:13:39.000 INFO kablam!    Compiling futures-util-preview v0.2.2
Oct 12 03:13:40.320 INFO kablam!    Compiling curl v0.4.18
Oct 12 03:13:45.416 INFO kablam!    Compiling rouille v2.2.0
Oct 12 03:13:48.864 INFO kablam!    Compiling futures-executor-preview v0.2.2
Oct 12 03:13:52.572 INFO kablam!    Compiling futures-stable-preview v0.2.3
Oct 12 03:13:52.682 INFO kablam!    Compiling futures-async-runtime-preview v0.2.3
Oct 12 03:13:52.788 INFO kablam!    Compiling futures-preview v0.2.2
Oct 12 03:13:54.384 INFO kablam!    Compiling chttp v0.2.2 (/source)
Oct 12 03:14:43.028 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1m 06s
Oct 12 03:14:43.045 INFO kablam! su: No module specific data is present
Oct 12 03:14:43.521 INFO running `"docker" "rm" "-f" "e09d8eff0f6a1da26b3552baf14c1d08510b69814733b965afc870455ad9b7b2"`
Oct 12 03:14:43.739 INFO blam! e09d8eff0f6a1da26b3552baf14c1d08510b69814733b965afc870455ad9b7b2
Oct 12 03:14:43.739 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 03:14:44.118 INFO blam! 79d2a7544f50062f59f7df9fb1315c2abc7743a3a9a031d3432cd7fec7592996
Oct 12 03:14:44.121 INFO running `"docker" "start" "-a" "79d2a7544f50062f59f7df9fb1315c2abc7743a3a9a031d3432cd7fec7592996"`
Oct 12 03:14:45.124 INFO kablam! usermod: no changes
Oct 12 03:14:45.479 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.31s
Oct 12 03:14:45.504 INFO kablam!      Running /target/debug/deps/chttp-9ee596ce3992b3f5
Oct 12 03:14:45.508 INFO kablam!      Running /target/debug/deps/redirects-7ef69ac1b9c36930
Oct 12 03:14:45.508 INFO blam! 
Oct 12 03:14:45.508 INFO blam! running 0 tests
Oct 12 03:14:45.508 INFO blam! 
Oct 12 03:14:45.508 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 03:14:45.508 INFO blam! 
Oct 12 03:14:45.512 INFO blam! 
Oct 12 03:14:45.512 INFO blam! running 3 tests
Oct 12 03:14:45.547 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: agent ready
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f085c0a4000, handler: 0x7f085c0a4000 }))
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f085be4e000, handler: 0x7f085be4e000 }))
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f085bc51000, handler: 0x7f085bc51000 }))
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37305 (#0)
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:37305\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [serially]
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 42651 (#1)
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /a HTTP/1.1\r\nHost: 127.0.0.1:42651\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [serially]
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /0 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.548 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.644 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.644 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.653 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.653 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /b\r\n
Oct 12 03:14:45.653 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.653 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/b")
Oct 12 03:14:45.654 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.654 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 12 03:14:45.654 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:37305/b'
Oct 12 03:14:45.654 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.655 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.655 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#0) with host 127.0.0.1
Oct 12 03:14:45.655 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 37305 (#0)
Oct 12 03:14:45.655 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /b HTTP/1.1\r\nHost: 127.0.0.1:37305\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.656 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.656 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.656 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.656 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.657 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /b\r\n
Oct 12 03:14:45.657 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.657 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.657 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 12 03:14:45.658 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: request with token 1 completed
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /1\r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.658 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/1")
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:41815/1'
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /1 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.658 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /2\r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.676 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/2")
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:41815/2'
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /2 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.676 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.677 INFO blam! test response_301_no_follow ... ok
Oct 12 03:14:45.678 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /3\r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.679 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/3")
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:41815/3'
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /3 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.679 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 2\r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << ok
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received 2 bytes of data
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 12 03:14:45.684 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: request with token 0 completed
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 2 bytes, consuming 2 bytes
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 30 bytes
Oct 12 03:14:45.684 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /4\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.696 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/4")
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:41815/4'
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /4 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /5\r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.696 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/5")
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.696 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Issue another request to this URL: 'http://127.0.0.1:41815/5'
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f085926c030 [can pipeline]
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Could pipeline, but not asked to!
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Re-using existing connection! (#2) with host 127.0.0.1
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41815 (#2)
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET /5 HTTP/1.1\r\nHost: 127.0.0.1:41815\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.697 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.697 INFO blam! test response_301_auto_follow ... ok
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 301 Moved Permanently\r\n
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Location: /6\r\n
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 0\r\n
Oct 12 03:14:45.716 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::request: preparing for redirect to Some("/6")
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #2 to host 127.0.0.1 left intact
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Maximum (5) redirects followed
Oct 12 03:14:45.716 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: curl error: [47] Number of redirects hit maximum amount
Oct 12 03:14:45.716 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 100ms
Oct 12 03:14:45.717 INFO blam! test redirect_limit_is_respected ... ok
Oct 12 03:14:45.717 INFO blam! 
Oct 12 03:14:45.717 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 03:14:45.717 INFO blam! 
Oct 12 03:14:45.728 INFO kablam!      Running /target/debug/deps/response-4ab37c3c0fdbf82b
Oct 12 03:14:45.732 INFO blam! 
Oct 12 03:14:45.732 INFO blam! running 2 tests
Oct 12 03:14:45.752 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: agent ready
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f58d2ca4000, handler: 0x7f58d2ca4000 }))
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f58d2e4e000, handler: 0x7f58d2e4e000 }))
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 35639 (#0)
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:35639\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Found bundle for host 127.0.0.1: 0x7f58cf06c030 [serially]
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Server doesn't support multi-use (yet)
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 41551 (#1)
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> GET / HTTP/1.1\r\nHost: 127.0.0.1:41551\r\nAccept: */*\r\n\r\n
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.752 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.776 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 12 03:14:45.776 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.784 INFO blam! test simple_response_body ... ok
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 11\r\n
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: buffer is empty, read is pending
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << hello world
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received 11 bytes of data
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 11 bytes, consuming 11 bytes
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 21 bytes
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: buffer is empty, read is pending
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #0 to host 127.0.0.1 left intact
Oct 12 03:14:45.784 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: request with token 0 completed
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 21 bytes
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 12 03:14:45.784 INFO kablam!  WARN 2018-10-12T03:14:45Z: chttp::internal::agent: received unpause request for unknown request token: 0
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(0)
Oct 12 03:14:45.784 INFO kablam!  WARN 2018-10-12T03:14:45Z: chttp::internal::agent: received unpause request for unknown request token: 0
Oct 12 03:14:45.784 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << HTTP/1.1 200 OK\r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Server: tiny-http (Rust)\r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Date: Fri, 12 Oct 2018 03:14:45 GMT\r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Type: text/plain; charset=utf8\r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << Content-Length: 13000\r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << \r\n
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: buffer is empty, read is pending
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wo
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received 8192 bytes of data
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 8192 bytes, consuming 32 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 32 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 8160 bytes, consuming 32 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 64 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 8128 bytes, consuming 64 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 128 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 8064 bytes, consuming 128 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 256 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 7936 bytes, consuming 256 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 512 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 7680 bytes, consuming 512 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 1024 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 7168 bytes, consuming 1024 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 2048 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 6144 bytes, consuming 2048 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 4096 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 4096 bytes, consuming 4096 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 8192 bytes
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: buffer is empty, read is pending
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: UnpauseWrite(1)
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: << w so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large wow so large 
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received 4808 bytes of data
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connection #1 to host 127.0.0.1 left intact
Oct 12 03:14:45.809 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: request with token 1 completed
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 100ms
Oct 12 03:14:45.809 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 8192 bytes
Oct 12 03:14:45.816 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: read buffer contains 4808 bytes, consuming 4808 bytes
Oct 12 03:14:45.816 INFO blam! test large_response_body ... ok
Oct 12 03:14:45.816 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: received read request for 3384 bytes
Oct 12 03:14:45.816 INFO blam! 
Oct 12 03:14:45.816 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: request is closed, satisfying read request with EOF
Oct 12 03:14:45.816 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 03:14:45.816 INFO blam! 
Oct 12 03:14:45.828 INFO kablam!      Running /target/debug/deps/timeouts-6296eb58d2c523e2
Oct 12 03:14:45.840 INFO kablam! DEBUG 2018-10-12T03:14:45Z: chttp::internal::agent: agent ready
Oct 12 03:14:45.840 INFO blam! 
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: received message from agent handle: BeginRequest(CurlRequest(Easy { handle: 0x7f9d7eea4000, handler: 0x7f9d7eea4000 }))
Oct 12 03:14:45.840 INFO blam! running 1 test
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request:   Trying 127.0.0.1...
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: Connected to 127.0.0.1 (127.0.0.1) port 36059 (#0)
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::request: >> POST / HTTP/1.1\r\nHost: 127.0.0.1:36059\r\nAccept: */*\r\nContent-Length: 11\r\nExpect: 100-continue\r\n\r\n
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: woke up from notify fd
Oct 12 03:14:45.840 INFO kablam! TRACE 2018-10-12T03:14:45Z: chttp::internal::agent: polling with timeout of 200ms
Oct 12 03:14:46.044 INFO kablam! TRACE 2018-10-12T03:14:46Z: chttp::internal::request: Done waiting for 100-continue
Oct 12 03:14:46.044 INFO kablam! TRACE 2018-10-12T03:14:46Z: chttp::internal::agent: polling with timeout of 1s
Oct 12 03:14:46.044 INFO kablam! TRACE 2018-10-12T03:14:46Z: chttp::internal::request: >> hello world
Oct 12 03:14:46.044 INFO kablam! TRACE 2018-10-12T03:14:46Z: chttp::internal::request: We are completely uploaded and fine
Oct 12 03:14:46.044 INFO kablam! TRACE 2018-10-12T03:14:46Z: chttp::internal::agent: polling with timeout of 1s
Oct 12 03:14:47.038 INFO kablam! TRACE 2018-10-12T03:14:47Z: chttp::internal::request: Operation timed out after 2000 milliseconds with 0 bytes received
Oct 12 03:14:47.038 INFO kablam! TRACE 2018-10-12T03:14:47Z: chttp::internal::request: Closing connection 0
Oct 12 03:14:47.038 INFO kablam! DEBUG 2018-10-12T03:14:47Z: chttp::internal::agent: curl error: [28] Timeout was reached
Oct 12 03:14:47.040 INFO kablam! TRACE 2018-10-12T03:14:47Z: chttp::internal::agent: polling with timeout of 100ms
Oct 12 03:14:47.040 INFO blam! test request_errors_if_read_timeout_is_reached ... ok
Oct 12 03:14:47.040 INFO blam! 
Oct 12 03:14:47.040 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 03:14:47.040 INFO blam! 
Oct 12 03:14:47.041 INFO kablam!    Doc-tests chttp
Oct 12 03:14:57.536 INFO blam! 
Oct 12 03:14:57.536 INFO blam! running 6 tests
Oct 12 03:15:27.840 INFO blam! test src/lib.rs -  (line 11) ... ok
Oct 12 03:15:30.704 INFO blam! test src/lib.rs -  (line 26) ... ok
Oct 12 03:15:31.049 INFO blam! test src/lib.rs -  (line 74) ... ok
Oct 12 03:15:31.296 INFO blam! test src/lib.rs -  (line 53) ... ok
Oct 12 03:15:31.556 INFO blam! test src/lib.rs -  (line 37) ... ok
Oct 12 03:15:31.877 INFO blam! test src/client.rs - client::ClientBuilder (line 17) ... ok
Oct 12 03:15:31.877 INFO blam! 
Oct 12 03:15:31.877 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 03:15:31.877 INFO blam! 
Oct 12 03:15:31.903 INFO kablam! su: No module specific data is present
Oct 12 03:15:32.507 INFO running `"docker" "rm" "-f" "79d2a7544f50062f59f7df9fb1315c2abc7743a3a9a031d3432cd7fec7592996"`
Oct 12 03:15:32.828 INFO blam! 79d2a7544f50062f59f7df9fb1315c2abc7743a3a9a031d3432cd7fec7592996