Nov 02 23:10:42.264 INFO testing crisp-status-reporter-1.1.2 against beta-2018-10-30 for beta-1.31-1
Nov 02 23:10:42.264 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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 02 23:10:42.659 INFO blam! c10d9f24aaa41aef99a3150bfb681237aaa1747ff9b1d9077ca380b489ec2427
Nov 02 23:10:42.662 INFO running `"docker" "start" "-a" "c10d9f24aaa41aef99a3150bfb681237aaa1747ff9b1d9077ca380b489ec2427"`
Nov 02 23:10:43.707 INFO kablam! usermod: no changes
Nov 02 23:10:44.093 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 02 23:10:52.252 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 8.52s
Nov 02 23:10:52.267 INFO kablam! su: No module specific data is present
Nov 02 23:10:52.830 INFO running `"docker" "rm" "-f" "c10d9f24aaa41aef99a3150bfb681237aaa1747ff9b1d9077ca380b489ec2427"`
Nov 02 23:10:53.024 INFO blam! c10d9f24aaa41aef99a3150bfb681237aaa1747ff9b1d9077ca380b489ec2427
Nov 02 23:10:53.032 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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 02 23:10:53.373 INFO blam! c562d11b4884239a5a2b151f8e2d2446deb03299fea22b9c5948f13771640d9d
Nov 02 23:10:53.375 INFO running `"docker" "start" "-a" "c562d11b4884239a5a2b151f8e2d2446deb03299fea22b9c5948f13771640d9d"`
Nov 02 23:10:54.031 INFO kablam! usermod: no changes
Nov 02 23:10:54.282 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 02 23:11:09.155 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 15.10s
Nov 02 23:11:09.171 INFO kablam! su: No module specific data is present
Nov 02 23:11:09.651 INFO running `"docker" "rm" "-f" "c562d11b4884239a5a2b151f8e2d2446deb03299fea22b9c5948f13771640d9d"`
Nov 02 23:11:09.810 INFO blam! c562d11b4884239a5a2b151f8e2d2446deb03299fea22b9c5948f13771640d9d
Nov 02 23:11:09.816 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-1/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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 02 23:11:10.236 INFO blam! 96486724375fcac6dfc145534238c836fa830952087a413eac8f4a0e2b0a0def
Nov 02 23:11:10.238 INFO running `"docker" "start" "-a" "96486724375fcac6dfc145534238c836fa830952087a413eac8f4a0e2b0a0def"`
Nov 02 23:11:11.315 INFO kablam! usermod: no changes
Nov 02 23:11:11.596 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Nov 02 23:11:11.618 INFO kablam!      Running /target/debug/deps/crisp_status_reporter-bdbffa79cd182d98
Nov 02 23:11:11.621 INFO blam! 
Nov 02 23:11:11.621 INFO blam! running 0 tests
Nov 02 23:11:11.621 INFO blam! 
Nov 02 23:11:11.621 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 23:11:11.621 INFO blam! 
Nov 02 23:11:11.621 INFO kablam!      Running /target/debug/deps/report-e9df5f35b75542d5
Nov 02 23:11:11.637 INFO blam! 
Nov 02 23:11:11.637 INFO blam! running 5 tests
Nov 02 23:11:11.643 INFO blam! test initialize_valid ... ok
Nov 02 23:11:11.655 INFO kablam! DEBUG 2018-11-02T23:11:11Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Nov 02 23:11:11.703 INFO kablam! TRACE 2018-11-02T23:11:11Z: mio::poll: registering with poller
Nov 02 23:11:11.704 INFO kablam! TRACE 2018-11-02T23:11:11Z: tokio_threadpool::builder: build; num-workers=8
Nov 02 23:11:11.711 INFO kablam! DEBUG 2018-11-02T23:11:11Z: tokio_reactor::background: starting background reactor
Nov 02 23:11:11.712 INFO kablam! DEBUG 2018-11-02T23:11:11Z: tokio_core::reactor: consuming notification queue
Nov 02 23:11:11.712 INFO kablam! DEBUG 2018-11-02T23:11:11Z: tokio_core::reactor: loop poll - 60.411µs
Nov 02 23:11:11.712 INFO kablam! DEBUG 2018-11-02T23:11:11Z: tokio_core::reactor: loop time - Instant { tv_sec: 2873447, tv_nsec: 593042207 }
Nov 02 23:11:11.712 INFO kablam! DEBUG 2018-11-02T23:11:11Z: tokio_core::reactor: loop process, 48.207µs
Nov 02 23:11:11.724 INFO kablam! DEBUG 2018-11-02T23:11:11Z: crisp_status_reporter: Crisp Status Reporter: Now running
Nov 02 23:11:11.811 INFO blam! test initialize_invalid_service_id ... ok
Nov 02 23:11:11.811 INFO blam! test initialize_invalid_node_id ... ok
Nov 02 23:11:11.812 INFO blam! test initialize_invalid_replica_id ... ok
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: 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.41, ram: 0.15169138 } }
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop poll - 10.013891613s
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 2873457, tv_nsec: 607002276 }
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop process, 55.431µs
Nov 02 23:11:21.727 INFO kablam! TRACE 2018-11-02T23:11:21Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Nov 02 23:11:21.727 INFO kablam! TRACE 2018-11-02T23:11:21Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop poll - 226.66µs
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 2873457, tv_nsec: 607560113 }
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop process, 38.842µs
Nov 02 23:11:21.727 INFO kablam! DEBUG 2018-11-02T23:11:21Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Nov 02 23:11:21.731 INFO kablam! ERROR 2018-11-02T23:11:21Z: 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
Nov 02 23:11:21.731 INFO kablam!  WARN 2018-11-02T23:11:21Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Nov 02 23:11:21.731 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop poll - 4.227181ms
Nov 02 23:11:21.731 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 2873457, tv_nsec: 611852155 }
Nov 02 23:11:21.731 INFO kablam! DEBUG 2018-11-02T23:11:21Z: tokio_core::reactor: loop process, 37.662µs
Nov 02 23:11:26.714 INFO blam! test run_and_end_valid ... ok
Nov 02 23:11:26.714 INFO blam! 
Nov 02 23:11:26.714 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 23:11:26.714 INFO blam! 
Nov 02 23:11:26.719 INFO kablam!    Doc-tests crisp_status_reporter
Nov 02 23:11:37.804 INFO blam! 
Nov 02 23:11:37.804 INFO blam! running 0 tests
Nov 02 23:11:37.804 INFO blam! 
Nov 02 23:11:37.804 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 23:11:37.805 INFO blam! 
Nov 02 23:11:37.806 INFO kablam! su: No module specific data is present
Nov 02 23:11:38.169 INFO running `"docker" "rm" "-f" "96486724375fcac6dfc145534238c836fa830952087a413eac8f4a0e2b0a0def"`
Nov 02 23:11:38.257 INFO blam! 96486724375fcac6dfc145534238c836fa830952087a413eac8f4a0e2b0a0def
