Dec 07 19:33:57.293 INFO testing crisp-status-reporter-1.1.2 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 19:33:57.293 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:57.761 INFO [stdout] 31639816396b8e65d9397a044754cb55cc41a0bda27520fbeed3aede73a070a7
Dec 07 19:33:57.763 INFO running `"docker" "start" "-a" "31639816396b8e65d9397a044754cb55cc41a0bda27520fbeed3aede73a070a7"`
Dec 07 19:33:59.708 INFO [stderr] usermod: no changes
Dec 07 19:33:59.956 INFO [stderr]    Compiling sys-info v0.5.6
Dec 07 19:34:02.815 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 07 19:34:09.006 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.27s
Dec 07 19:34:09.030 INFO [stderr] su: No module specific data is present
Dec 07 19:34:10.248 INFO running `"docker" "inspect" "31639816396b8e65d9397a044754cb55cc41a0bda27520fbeed3aede73a070a7"`
Dec 07 19:34:10.554 INFO running `"docker" "rm" "-f" "31639816396b8e65d9397a044754cb55cc41a0bda27520fbeed3aede73a070a7"`
Dec 07 19:34:10.868 INFO [stdout] 31639816396b8e65d9397a044754cb55cc41a0bda27520fbeed3aede73a070a7
Dec 07 19:34:10.874 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:11.304 INFO [stdout] 172372619ba13b825ca367cddd502e6fe672029098100448cd152def62d61693
Dec 07 19:34:11.306 INFO running `"docker" "start" "-a" "172372619ba13b825ca367cddd502e6fe672029098100448cd152def62d61693"`
Dec 07 19:34:13.372 INFO [stderr] usermod: no changes
Dec 07 19:34:13.714 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 07 19:34:27.333 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.91s
Dec 07 19:34:27.353 INFO [stderr] su: No module specific data is present
Dec 07 19:34:28.224 INFO running `"docker" "inspect" "172372619ba13b825ca367cddd502e6fe672029098100448cd152def62d61693"`
Dec 07 19:34:28.532 INFO running `"docker" "rm" "-f" "172372619ba13b825ca367cddd502e6fe672029098100448cd152def62d61693"`
Dec 07 19:34:28.675 INFO [stdout] 172372619ba13b825ca367cddd502e6fe672029098100448cd152def62d61693
Dec 07 19:34:28.677 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:29.198 INFO [stdout] cc493f59b32aaf596b47b58f765bf24552c739e42b174c650b894765faf4556e
Dec 07 19:34:29.199 INFO running `"docker" "start" "-a" "cc493f59b32aaf596b47b58f765bf24552c739e42b174c650b894765faf4556e"`
Dec 07 19:34:31.458 INFO [stderr] usermod: no changes
Dec 07 19:34:31.699 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Dec 07 19:34:31.716 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-1c513f637ba9fea3
Dec 07 19:34:31.719 INFO [stdout] 
Dec 07 19:34:31.719 INFO [stdout] running 0 tests
Dec 07 19:34:31.720 INFO [stdout] 
Dec 07 19:34:31.720 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:31.720 INFO [stdout] 
Dec 07 19:34:31.724 INFO [stderr]      Running /target/debug/deps/report-0dd2bcd2950d972c
Dec 07 19:34:31.735 INFO [stdout] 
Dec 07 19:34:31.735 INFO [stdout] running 5 tests
Dec 07 19:34:31.768 INFO [stdout] test initialize_valid ... ok
Dec 07 19:34:31.788 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.841 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.842 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.852 INFO [stderr] TRACE 2018-12-07T19:34:31Z: tokio_threadpool::builder: build; num-workers=8
Dec 07 19:34:31.872 INFO [stderr] TRACE 2018-12-07T19:34:31Z: mio::poll: registering with poller
Dec 07 19:34:31.888 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: tokio_reactor::background: starting background reactor
Dec 07 19:34:31.892 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: tokio_core::reactor: consuming notification queue
Dec 07 19:34:31.892 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: tokio_core::reactor: loop poll - 77.191µs
Dec 07 19:34:31.892 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884329, tv_nsec: 224447469 }
Dec 07 19:34:31.893 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: tokio_core::reactor: loop process, 46.42µs
Dec 07 19:34:31.920 INFO [stderr] DEBUG 2018-12-07T19:34:31Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 07 19:34:32.018 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 07 19:34:32.018 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 07 19:34:32.018 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: 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.125, ram: 0.12435222 } }
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop poll - 10.028882082s
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884339, tv_nsec: 253401489 }
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop process, 58.746µs
Dec 07 19:34:41.928 INFO [stderr] TRACE 2018-12-07T19:34:41Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 07 19:34:41.928 INFO [stderr] TRACE 2018-12-07T19:34:41Z: 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:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop poll - 256.628µs
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884339, tv_nsec: 254011536 }
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop process, 42.267µs
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop poll - 1.433816ms
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop time - Instant { tv_sec: 5884339, tv_nsec: 255514322 }
Dec 07 19:34:41.928 INFO [stderr] DEBUG 2018-12-07T19:34:41Z: tokio_core::reactor: loop process, 54.479µs
Dec 07 19:34:41.928 INFO [stderr] ERROR 2018-12-07T19:34:41Z: 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:41.928 INFO [stderr]  WARN 2018-12-07T19:34:41Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 07 19:34:46.905 INFO [stdout] test run_and_end_valid ... ok
Dec 07 19:34:46.905 INFO [stdout] 
Dec 07 19:34:46.905 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:46.905 INFO [stdout] 
Dec 07 19:34:46.912 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 07 19:34:56.140 INFO [stdout] 
Dec 07 19:34:56.140 INFO [stdout] running 0 tests
Dec 07 19:34:56.140 INFO [stdout] 
Dec 07 19:34:56.140 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:34:56.140 INFO [stdout] 
Dec 07 19:34:56.157 INFO [stderr] su: No module specific data is present
Dec 07 19:34:57.749 INFO running `"docker" "inspect" "cc493f59b32aaf596b47b58f765bf24552c739e42b174c650b894765faf4556e"`
Dec 07 19:34:58.110 INFO running `"docker" "rm" "-f" "cc493f59b32aaf596b47b58f765bf24552c739e42b174c650b894765faf4556e"`
Dec 07 19:34:58.496 INFO [stdout] cc493f59b32aaf596b47b58f765bf24552c739e42b174c650b894765faf4556e