Oct 16 14:06:26.100 INFO testing crisp-status-reporter-1.1.2 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 16 14:06:26.100 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/./work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +40d4795669493f1965de7c44029c69552134fe1f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 16 14:06:26.363 INFO blam! 09bdf075900b993f92ddd7706c6bcfdcdd0bbe5beda390a5c01c88b2c0a680f3
Oct 16 14:06:26.374 INFO running `"docker" "start" "-a" "09bdf075900b993f92ddd7706c6bcfdcdd0bbe5beda390a5c01c88b2c0a680f3"`
Oct 16 14:06:27.355 INFO kablam! usermod: no changes
Oct 16 14:06:27.695 INFO kablam!    Compiling sys-info v0.5.6
Oct 16 14:06:29.103 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 16 14:06:36.711 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.33s
Oct 16 14:06:36.726 INFO kablam! su: No module specific data is present
Oct 16 14:06:37.414 INFO running `"docker" "rm" "-f" "09bdf075900b993f92ddd7706c6bcfdcdd0bbe5beda390a5c01c88b2c0a680f3"`
Oct 16 14:06:37.559 INFO blam! 09bdf075900b993f92ddd7706c6bcfdcdd0bbe5beda390a5c01c88b2c0a680f3
Oct 16 14:06:37.563 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/./work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +40d4795669493f1965de7c44029c69552134fe1f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 16 14:06:37.853 INFO blam! 7975386a0a072ce073b5c191bb02f7470722bdf205926fc93bb026f3e1391dec
Oct 16 14:06:37.856 INFO running `"docker" "start" "-a" "7975386a0a072ce073b5c191bb02f7470722bdf205926fc93bb026f3e1391dec"`
Oct 16 14:06:38.547 INFO kablam! usermod: no changes
Oct 16 14:06:38.794 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 16 14:06:52.845 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 14.27s
Oct 16 14:06:52.860 INFO kablam! su: No module specific data is present
Oct 16 14:06:53.385 INFO running `"docker" "rm" "-f" "7975386a0a072ce073b5c191bb02f7470722bdf205926fc93bb026f3e1391dec"`
Oct 16 14:06:53.498 INFO blam! 7975386a0a072ce073b5c191bb02f7470722bdf205926fc93bb026f3e1391dec
Oct 16 14:06:53.504 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/./work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +40d4795669493f1965de7c44029c69552134fe1f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 16 14:06:53.674 INFO blam! d1b61e06802defdcf9cd03e45b134d817ce147c1995e262c14ca7eb7d6101e0d
Oct 16 14:06:53.676 INFO running `"docker" "start" "-a" "d1b61e06802defdcf9cd03e45b134d817ce147c1995e262c14ca7eb7d6101e0d"`
Oct 16 14:06:54.061 INFO kablam! usermod: no changes
Oct 16 14:06:54.258 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.17s
Oct 16 14:06:54.276 INFO kablam!      Running /target/debug/deps/crisp_status_reporter-26a6a949d16be323
Oct 16 14:06:54.278 INFO blam! 
Oct 16 14:06:54.278 INFO blam! running 0 tests
Oct 16 14:06:54.278 INFO blam! 
Oct 16 14:06:54.278 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 14:06:54.278 INFO blam! 
Oct 16 14:06:54.279 INFO kablam!      Running /target/debug/deps/report-e19b5b35424c6935
Oct 16 14:06:54.281 INFO blam! 
Oct 16 14:06:54.281 INFO blam! running 5 tests
Oct 16 14:06:54.287 INFO kablam! DEBUG 2018-10-16T14:06:54Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Oct 16 14:06:54.288 INFO blam! test initialize_valid ... ok
Oct 16 14:06:54.299 INFO kablam! TRACE 2018-10-16T14:06:54Z: mio::poll: registering with poller
Oct 16 14:06:54.299 INFO kablam! TRACE 2018-10-16T14:06:54Z: tokio_threadpool::builder: build; num-workers=8
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: tokio_core::reactor: consuming notification queue
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: tokio_core::reactor: loop poll - 50.232µs
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: tokio_core::reactor: loop time - Instant { tv_sec: 1371990, tv_nsec: 181023328 }
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: tokio_core::reactor: loop process, 51.732µs
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: crisp_status_reporter: Crisp Status Reporter: Now running
Oct 16 14:06:54.300 INFO kablam! DEBUG 2018-10-16T14:06:54Z: tokio_reactor::background: starting background reactor
Oct 16 14:06:54.361 INFO blam! test initialize_invalid_replica_id ... ok
Oct 16 14:06:54.361 INFO blam! test initialize_invalid_service_id ... ok
Oct 16 14:06:54.361 INFO blam! test initialize_invalid_node_id ... ok
Oct 16 14:07:04.300 INFO kablam! DEBUG 2018-10-16T14:07:04Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Oct 16 14:07:04.301 INFO kablam! DEBUG 2018-10-16T14:07:04Z: crisp_status_reporter: Crisp Status Reporter: Will send request to URL: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/ with payload: ReportPayload { replica_id: "192.168.1.10", interval: 30, load: ReportPayloadLoad { cpu: 3.63375, ram: 0.13139844 } }
Oct 16 14:07:04.301 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop poll - 10.001483229s
Oct 16 14:07:04.301 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop time - Instant { tv_sec: 1372000, tv_nsec: 182583335 }
Oct 16 14:07:04.301 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop process, 96.11µs
Oct 16 14:07:04.302 INFO kablam! TRACE 2018-10-16T14:07:04Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Oct 16 14:07:04.302 INFO kablam! TRACE 2018-10-16T14:07:04Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Oct 16 14:07:04.302 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop poll - 301.918µs
Oct 16 14:07:04.302 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop time - Instant { tv_sec: 1372000, tv_nsec: 183282873 }
Oct 16 14:07:04.302 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop process, 217.83µs
Oct 16 14:07:04.302 INFO kablam! DEBUG 2018-10-16T14:07:04Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Oct 16 14:07:04.304 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop poll - 1.82718ms
Oct 16 14:07:04.304 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop time - Instant { tv_sec: 1372000, tv_nsec: 185404362 }
Oct 16 14:07:04.304 INFO kablam! DEBUG 2018-10-16T14:07:04Z: tokio_core::reactor: loop process, 104.475µs
Oct 16 14:07:04.304 INFO kablam! ERROR 2018-10-16T14:07:04Z: crisp_status_reporter: Crisp Status Reporter: Failed dispatching request: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/: failed to lookup address information: Temporary failure in name resolution
Oct 16 14:07:04.304 INFO kablam!  WARN 2018-10-16T14:07:04Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Oct 16 14:07:09.300 INFO blam! test run_and_end_valid ... ok
Oct 16 14:07:09.300 INFO blam! 
Oct 16 14:07:09.300 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 14:07:09.300 INFO blam! 
Oct 16 14:07:09.306 INFO kablam!    Doc-tests crisp_status_reporter
Oct 16 14:07:19.977 INFO blam! 
Oct 16 14:07:19.977 INFO blam! running 0 tests
Oct 16 14:07:19.977 INFO blam! 
Oct 16 14:07:19.977 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 14:07:19.977 INFO blam! 
Oct 16 14:07:19.987 INFO kablam! su: No module specific data is present
Oct 16 14:07:20.252 INFO running `"docker" "rm" "-f" "d1b61e06802defdcf9cd03e45b134d817ce147c1995e262c14ca7eb7d6101e0d"`
Oct 16 14:07:20.333 INFO blam! d1b61e06802defdcf9cd03e45b134d817ce147c1995e262c14ca7eb7d6101e0d