Dec 07 19:33:49.437 INFO testing crisp-status-reporter-1.1.2 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 19:33:49.437 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:33:49.875 INFO [stdout] e723eb136f43335cab65e6d307dff62d1a645e07a0eef2c97ea37a1793bd3485
Dec 07 19:33:49.877 INFO running `"docker" "start" "-a" "e723eb136f43335cab65e6d307dff62d1a645e07a0eef2c97ea37a1793bd3485"`
Dec 07 19:33:51.704 INFO [stderr] usermod: no changes
Dec 07 19:33:52.104 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 07 19:34:01.395 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.67s
Dec 07 19:34:01.413 INFO [stderr] su: No module specific data is present
Dec 07 19:34:02.250 INFO running `"docker" "inspect" "e723eb136f43335cab65e6d307dff62d1a645e07a0eef2c97ea37a1793bd3485"`
Dec 07 19:34:02.528 INFO running `"docker" "rm" "-f" "e723eb136f43335cab65e6d307dff62d1a645e07a0eef2c97ea37a1793bd3485"`
Dec 07 19:34:02.924 INFO [stdout] e723eb136f43335cab65e6d307dff62d1a645e07a0eef2c97ea37a1793bd3485
Dec 07 19:34:02.930 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:34:03.451 INFO [stdout] e70bc4fbddf23b8dc9640d117db8a4f8e56c7d49d1d3bdddf7d05037a6d3566d
Dec 07 19:34:03.453 INFO running `"docker" "start" "-a" "e70bc4fbddf23b8dc9640d117db8a4f8e56c7d49d1d3bdddf7d05037a6d3566d"`
Dec 07 19:34:04.860 INFO [stderr] usermod: no changes
Dec 07 19:34:05.175 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 07 19:34:22.359 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.47s
Dec 07 19:34:22.375 INFO [stderr] su: No module specific data is present
Dec 07 19:34:23.584 INFO running `"docker" "inspect" "e70bc4fbddf23b8dc9640d117db8a4f8e56c7d49d1d3bdddf7d05037a6d3566d"`
Dec 07 19:34:23.850 INFO running `"docker" "rm" "-f" "e70bc4fbddf23b8dc9640d117db8a4f8e56c7d49d1d3bdddf7d05037a6d3566d"`
Dec 07 19:34:24.144 INFO [stdout] e70bc4fbddf23b8dc9640d117db8a4f8e56c7d49d1d3bdddf7d05037a6d3566d
Dec 07 19:34:24.152 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:34:24.516 INFO [stdout] 2d66cd00e6ce91e5bc084166522c915de6dd072399a31aacd84e7cd948b693b2
Dec 07 19:34:24.518 INFO running `"docker" "start" "-a" "2d66cd00e6ce91e5bc084166522c915de6dd072399a31aacd84e7cd948b693b2"`
Dec 07 19:34:26.193 INFO [stderr] usermod: no changes
Dec 07 19:34:26.496 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Dec 07 19:34:26.503 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-bda91c380daf8ec9
Dec 07 19:34:26.510 INFO [stdout] 
Dec 07 19:34:26.510 INFO [stdout] running 0 tests
Dec 07 19:34:26.510 INFO [stdout] 
Dec 07 19:34:26.510 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:26.510 INFO [stdout] 
Dec 07 19:34:26.512 INFO [stderr]      Running /target/debug/deps/report-66179678398c166e
Dec 07 19:34:26.515 INFO [stdout] 
Dec 07 19:34:26.515 INFO [stdout] running 5 tests
Dec 07 19:34:26.540 INFO [stdout] test initialize_valid ... ok
Dec 07 19:34:26.540 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.569 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.570 INFO [stderr] TRACE 2018-12-07T19:34:26Z: tokio_threadpool::builder: build; num-workers=8
Dec 07 19:34:26.570 INFO [stderr] TRACE 2018-12-07T19:34:26Z: mio::poll: registering with poller
Dec 07 19:34:26.570 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: tokio_core::reactor: consuming notification queue
Dec 07 19:34:26.571 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: tokio_core::reactor: loop poll - 65.144µs
Dec 07 19:34:26.571 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884323, tv_nsec: 902514140 }
Dec 07 19:34:26.571 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: tokio_core::reactor: loop process, 46.919µs
Dec 07 19:34:26.580 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: tokio_reactor::background: starting background reactor
Dec 07 19:34:26.580 INFO [stderr] DEBUG 2018-12-07T19:34:26Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 07 19:34:26.668 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 07 19:34:26.668 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 07 19:34:26.668 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 07 19:34:36.581 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 07 19:34:36.581 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: 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.1275, ram: 0.13956022 } }
Dec 07 19:34:36.582 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop poll - 10.011152703s
Dec 07 19:34:36.582 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884333, tv_nsec: 913736122 }
Dec 07 19:34:36.582 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop process, 243.254µs
Dec 07 19:34:36.582 INFO [stderr] TRACE 2018-12-07T19:34:36Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 07 19:34:36.583 INFO [stderr] TRACE 2018-12-07T19:34:36Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Dec 07 19:34:36.583 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop poll - 486.743µs
Dec 07 19:34:36.583 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884333, tv_nsec: 914854430 }
Dec 07 19:34:36.583 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop process, 203.459µs
Dec 07 19:34:36.583 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 07 19:34:36.584 INFO [stderr] ERROR 2018-12-07T19:34:36Z: 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 07 19:34:36.584 INFO [stderr]  WARN 2018-12-07T19:34:36Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 07 19:34:36.585 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop poll - 1.397916ms
Dec 07 19:34:36.585 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884333, tv_nsec: 916554163 }
Dec 07 19:34:36.585 INFO [stderr] DEBUG 2018-12-07T19:34:36Z: tokio_core::reactor: loop process, 191.033µs
Dec 07 19:34:41.576 INFO [stdout] test run_and_end_valid ... ok
Dec 07 19:34:41.576 INFO [stdout] 
Dec 07 19:34:41.576 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:41.576 INFO [stdout] 
Dec 07 19:34:41.580 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 07 19:34:54.628 INFO [stdout] 
Dec 07 19:34:54.628 INFO [stdout] running 0 tests
Dec 07 19:34:54.628 INFO [stdout] 
Dec 07 19:34:54.628 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:54.628 INFO [stdout] 
Dec 07 19:34:54.640 INFO [stderr] su: No module specific data is present
Dec 07 19:34:55.745 INFO running `"docker" "inspect" "2d66cd00e6ce91e5bc084166522c915de6dd072399a31aacd84e7cd948b693b2"`
Dec 07 19:34:56.084 INFO running `"docker" "rm" "-f" "2d66cd00e6ce91e5bc084166522c915de6dd072399a31aacd84e7cd948b693b2"`
Dec 07 19:34:56.444 INFO [stdout] 2d66cd00e6ce91e5bc084166522c915de6dd072399a31aacd84e7cd948b693b2