Oct 12 02:18:50.054 INFO testing crisp-status-reporter-1.1.2 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling
Oct 12 02:18:50.054 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 02:18:50.399 INFO blam! d518b2f2119742cd922b3eabf28ee80fe8d9d622a0f105f5f3f043d21856c7bc
Oct 12 02:18:50.401 INFO running `"docker" "start" "-a" "d518b2f2119742cd922b3eabf28ee80fe8d9d622a0f105f5f3f043d21856c7bc"`
Oct 12 02:18:50.863 INFO kablam! usermod: no changes
Oct 12 02:18:51.181 INFO kablam!    Compiling sys-info v0.5.6
Oct 12 02:18:51.184 INFO kablam!    Compiling hyper v0.11.27
Oct 12 02:19:00.326 INFO kablam!    Compiling hyper-tls v0.1.4
Oct 12 02:19:01.308 INFO kablam!    Compiling reqwest v0.8.8
Oct 12 02:19:07.640 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 12 02:19:08.980 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 18.08s
Oct 12 02:19:08.991 INFO kablam! su: No module specific data is present
Oct 12 02:19:09.386 INFO running `"docker" "rm" "-f" "d518b2f2119742cd922b3eabf28ee80fe8d9d622a0f105f5f3f043d21856c7bc"`
Oct 12 02:19:09.592 INFO blam! d518b2f2119742cd922b3eabf28ee80fe8d9d622a0f105f5f3f043d21856c7bc
Oct 12 02:19:09.598 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 02:19:09.915 INFO blam! 0b3ab08324ea8280b42a89471cf86049d89d62aa48ee54cf10b6c615431a12bc
Oct 12 02:19:09.916 INFO running `"docker" "start" "-a" "0b3ab08324ea8280b42a89471cf86049d89d62aa48ee54cf10b6c615431a12bc"`
Oct 12 02:19:10.963 INFO kablam! usermod: no changes
Oct 12 02:19:11.274 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 12 02:19:16.739 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.75s
Oct 12 02:19:16.753 INFO kablam! su: No module specific data is present
Oct 12 02:19:17.505 INFO running `"docker" "rm" "-f" "0b3ab08324ea8280b42a89471cf86049d89d62aa48ee54cf10b6c615431a12bc"`
Oct 12 02:19:17.704 INFO blam! 0b3ab08324ea8280b42a89471cf86049d89d62aa48ee54cf10b6c615431a12bc
Oct 12 02:19:17.707 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 02:19:18.013 INFO blam! d76637e6c90e8cbf7a3d191b4ed5939965b095e1966f4a1e212773d2c779a149
Oct 12 02:19:18.015 INFO running `"docker" "start" "-a" "d76637e6c90e8cbf7a3d191b4ed5939965b095e1966f4a1e212773d2c779a149"`
Oct 12 02:19:18.764 INFO kablam! usermod: no changes
Oct 12 02:19:18.973 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Oct 12 02:19:18.995 INFO kablam!      Running /target/debug/deps/crisp_status_reporter-2248ec583b06ed4e
Oct 12 02:19:18.998 INFO blam! 
Oct 12 02:19:18.998 INFO blam! running 0 tests
Oct 12 02:19:18.998 INFO blam! 
Oct 12 02:19:18.998 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:19:18.998 INFO blam! 
Oct 12 02:19:18.999 INFO kablam!      Running /target/debug/deps/report-6cc39f8c17ea188d
Oct 12 02:19:19.000 INFO blam! 
Oct 12 02:19:19.000 INFO blam! running 5 tests
Oct 12 02:19:19.008 INFO blam! test initialize_valid ... ok
Oct 12 02:19:19.008 INFO kablam! DEBUG 2018-10-12T02:19:19Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Oct 12 02:19:19.053 INFO kablam! TRACE 2018-10-12T02:19:19Z: mio::poll: registering with poller
Oct 12 02:19:19.053 INFO kablam! TRACE 2018-10-12T02:19:19Z: tokio_threadpool::builder: build; num-workers=8
Oct 12 02:19:19.084 INFO kablam! DEBUG 2018-10-12T02:19:19Z: tokio_core::reactor: consuming notification queue
Oct 12 02:19:19.085 INFO kablam! DEBUG 2018-10-12T02:19:19Z: tokio_core::reactor: loop poll - 75.593µs
Oct 12 02:19:19.085 INFO kablam! DEBUG 2018-10-12T02:19:19Z: tokio_core::reactor: loop time - Instant { tv_sec: 983816, tv_nsec: 416308366 }
Oct 12 02:19:19.085 INFO kablam! DEBUG 2018-10-12T02:19:19Z: tokio_core::reactor: loop process, 63.027µs
Oct 12 02:19:19.100 INFO kablam! DEBUG 2018-10-12T02:19:19Z: tokio_reactor::background: starting background reactor
Oct 12 02:19:19.121 INFO kablam! DEBUG 2018-10-12T02:19:19Z: crisp_status_reporter: Crisp Status Reporter: Now running
Oct 12 02:19:19.163 INFO blam! test initialize_invalid_replica_id ... ok
Oct 12 02:19:19.163 INFO blam! test initialize_invalid_node_id ... ok
Oct 12 02:19:19.163 INFO blam! test initialize_invalid_service_id ... ok
Oct 12 02:19:29.121 INFO kablam! DEBUG 2018-10-12T02:19:29Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Oct 12 02:19:29.122 INFO kablam! DEBUG 2018-10-12T02:19:29Z: 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.69875, ram: 0.13895327 } }
Oct 12 02:19:29.122 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop poll - 10.037534746s
Oct 12 02:19:29.122 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop time - Instant { tv_sec: 983826, tv_nsec: 453927908 }
Oct 12 02:19:29.122 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop process, 60.856µs
Oct 12 02:19:29.123 INFO kablam! TRACE 2018-10-12T02:19:29Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Oct 12 02:19:29.123 INFO kablam! TRACE 2018-10-12T02:19:29Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Oct 12 02:19:29.123 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop poll - 208.391µs
Oct 12 02:19:29.123 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop time - Instant { tv_sec: 983826, tv_nsec: 454433612 }
Oct 12 02:19:29.123 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop process, 35.895µs
Oct 12 02:19:29.123 INFO kablam! DEBUG 2018-10-12T02:19:29Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Oct 12 02:19:29.124 INFO kablam! ERROR 2018-10-12T02:19:29Z: 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 12 02:19:29.124 INFO kablam!  WARN 2018-10-12T02:19:29Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Oct 12 02:19:29.124 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop poll - 1.071929ms
Oct 12 02:19:29.124 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop time - Instant { tv_sec: 983826, tv_nsec: 455563677 }
Oct 12 02:19:29.124 INFO kablam! DEBUG 2018-10-12T02:19:29Z: tokio_core::reactor: loop process, 103.6µs
Oct 12 02:19:34.085 INFO blam! test run_and_end_valid ... ok
Oct 12 02:19:34.085 INFO blam! 
Oct 12 02:19:34.085 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:19:34.085 INFO blam! 
Oct 12 02:19:34.089 INFO kablam!    Doc-tests crisp_status_reporter
Oct 12 02:19:35.211 INFO blam! 
Oct 12 02:19:35.211 INFO blam! running 0 tests
Oct 12 02:19:35.211 INFO blam! 
Oct 12 02:19:35.211 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:19:35.211 INFO blam! 
Oct 12 02:19:35.224 INFO kablam! su: No module specific data is present
Oct 12 02:19:35.542 INFO running `"docker" "rm" "-f" "d76637e6c90e8cbf7a3d191b4ed5939965b095e1966f4a1e212773d2c779a149"`
Oct 12 02:19:35.629 INFO blam! d76637e6c90e8cbf7a3d191b4ed5939965b095e1966f4a1e212773d2c779a149