Dec 15 17:50:41.009 INFO testing crisp-status-reporter-1.1.2 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 17:50:41.009 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 17:50:41.509 INFO [stdout] 74f4e4fba6f797aa05657d1cc5989d9d06b6fafc58d012f6b3ad727434717021
Dec 15 17:50:41.511 INFO running `"docker" "start" "-a" "74f4e4fba6f797aa05657d1cc5989d9d06b6fafc58d012f6b3ad727434717021"`
Dec 15 17:50:42.111 INFO [stderr] usermod: no changes
Dec 15 17:50:42.368 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 15 17:50:49.919 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.79s
Dec 15 17:50:49.939 INFO [stderr] su: No module specific data is present
Dec 15 17:50:50.272 INFO running `"docker" "inspect" "74f4e4fba6f797aa05657d1cc5989d9d06b6fafc58d012f6b3ad727434717021"`
Dec 15 17:50:50.477 INFO running `"docker" "rm" "-f" "74f4e4fba6f797aa05657d1cc5989d9d06b6fafc58d012f6b3ad727434717021"`
Dec 15 17:50:50.666 INFO [stdout] 74f4e4fba6f797aa05657d1cc5989d9d06b6fafc58d012f6b3ad727434717021
Dec 15 17:50:50.675 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 17:50:50.972 INFO [stdout] 66649b978ec4093b79e24a983a4e0b000746986983f39a47e45fe1096af3b6c2
Dec 15 17:50:50.974 INFO running `"docker" "start" "-a" "66649b978ec4093b79e24a983a4e0b000746986983f39a47e45fe1096af3b6c2"`
Dec 15 17:50:51.995 INFO [stderr] usermod: no changes
Dec 15 17:50:52.231 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 15 17:51:07.002 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.98s
Dec 15 17:51:07.019 INFO [stderr] su: No module specific data is present
Dec 15 17:51:08.315 INFO running `"docker" "inspect" "66649b978ec4093b79e24a983a4e0b000746986983f39a47e45fe1096af3b6c2"`
Dec 15 17:51:08.671 INFO running `"docker" "rm" "-f" "66649b978ec4093b79e24a983a4e0b000746986983f39a47e45fe1096af3b6c2"`
Dec 15 17:51:09.059 INFO [stdout] 66649b978ec4093b79e24a983a4e0b000746986983f39a47e45fe1096af3b6c2
Dec 15 17:51:09.065 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 17:51:09.490 INFO [stdout] b782371d323d29049795933647cc2e0ee5bf40a4278eb5bd4746964237c778a0
Dec 15 17:51:09.492 INFO running `"docker" "start" "-a" "b782371d323d29049795933647cc2e0ee5bf40a4278eb5bd4746964237c778a0"`
Dec 15 17:51:11.311 INFO [stderr] usermod: no changes
Dec 15 17:51:11.583 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.25s
Dec 15 17:51:11.606 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-b957ca6bd3a98a93
Dec 15 17:51:11.620 INFO [stdout] 
Dec 15 17:51:11.620 INFO [stdout] running 0 tests
Dec 15 17:51:11.620 INFO [stdout] 
Dec 15 17:51:11.620 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:11.620 INFO [stdout] 
Dec 15 17:51:11.620 INFO [stderr]      Running /target/debug/deps/report-423d2e3c18f3ee66
Dec 15 17:51:11.623 INFO [stdout] 
Dec 15 17:51:11.623 INFO [stdout] running 5 tests
Dec 15 17:51:11.623 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 15 17:51:11.624 INFO [stdout] test initialize_valid ... ok
Dec 15 17:51:11.675 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.675 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: tokio_threadpool::builder: build; num-workers=8
Dec 15 17:51:11.676 INFO [stderr] TRACE 2018-12-15T17:51:11Z: mio::poll: registering with poller
Dec 15 17:51:11.723 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: tokio_reactor::background: starting background reactor
Dec 15 17:51:11.744 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: tokio_core::reactor: consuming notification queue
Dec 15 17:51:11.744 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: tokio_core::reactor: loop poll - 108.008µs
Dec 15 17:51:11.744 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569447, tv_nsec: 624368427 }
Dec 15 17:51:11.744 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: tokio_core::reactor: loop process, 49.475µs
Dec 15 17:51:11.744 INFO [stderr] DEBUG 2018-12-15T17:51:11Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 15 17:51:11.868 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 15 17:51:11.868 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 15 17:51:11.868 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 15 17:51:21.755 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 15 17:51:21.756 INFO [stderr] DEBUG 2018-12-15T17:51: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.09625, ram: 0.16408825 } }
Dec 15 17:51:21.757 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop poll - 10.013578652s
Dec 15 17:51:21.757 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569457, tv_nsec: 638020838 }
Dec 15 17:51:21.757 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop process, 463.027µs
Dec 15 17:51:21.758 INFO [stderr] TRACE 2018-12-15T17:51:21Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 15 17:51:21.758 INFO [stderr] TRACE 2018-12-15T17:51:21Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Dec 15 17:51:21.758 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop poll - 680.342µs
Dec 15 17:51:21.758 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569457, tv_nsec: 639663092 }
Dec 15 17:51:21.759 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop process, 459.768µs
Dec 15 17:51:21.759 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 15 17:51:21.761 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop poll - 2.256062ms
Dec 15 17:51:21.761 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569457, tv_nsec: 642580111 }
Dec 15 17:51:21.762 INFO [stderr] DEBUG 2018-12-15T17:51:21Z: tokio_core::reactor: loop process, 490.234µs
Dec 15 17:51:21.762 INFO [stderr] ERROR 2018-12-15T17:51: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
Dec 15 17:51:21.762 INFO [stderr]  WARN 2018-12-15T17:51:21Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 15 17:51:26.743 INFO [stdout] test run_and_end_valid ... ok
Dec 15 17:51:26.743 INFO [stdout] 
Dec 15 17:51:26.743 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:26.743 INFO [stdout] 
Dec 15 17:51:26.749 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 15 17:51:40.795 INFO [stdout] 
Dec 15 17:51:40.795 INFO [stdout] running 0 tests
Dec 15 17:51:40.795 INFO [stdout] 
Dec 15 17:51:40.795 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:40.795 INFO [stdout] 
Dec 15 17:51:40.809 INFO [stderr] su: No module specific data is present
Dec 15 17:51:41.655 INFO running `"docker" "inspect" "b782371d323d29049795933647cc2e0ee5bf40a4278eb5bd4746964237c778a0"`
Dec 15 17:51:41.975 INFO running `"docker" "rm" "-f" "b782371d323d29049795933647cc2e0ee5bf40a4278eb5bd4746964237c778a0"`
Dec 15 17:51:42.411 INFO [stdout] b782371d323d29049795933647cc2e0ee5bf40a4278eb5bd4746964237c778a0