Nov 18 16:51:27.843 INFO testing crisp-status-reporter-1.1.2 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 16:51:27.844 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 16:51:28.064 INFO [stdout] b23ccca980dd9d60cb1becd0d1906663b811bd9323a274eda29b92127cff6f9d
Nov 18 16:51:28.066 INFO running `"docker" "start" "-a" "b23ccca980dd9d60cb1becd0d1906663b811bd9323a274eda29b92127cff6f9d"`
Nov 18 16:51:28.675 INFO [stderr] usermod: no changes
Nov 18 16:51:28.885 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 18 16:51:35.321 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.62s
Nov 18 16:51:35.347 INFO [stderr] su: No module specific data is present
Nov 18 16:51:36.116 INFO running `"docker" "inspect" "b23ccca980dd9d60cb1becd0d1906663b811bd9323a274eda29b92127cff6f9d"`
Nov 18 16:51:36.332 INFO running `"docker" "rm" "-f" "b23ccca980dd9d60cb1becd0d1906663b811bd9323a274eda29b92127cff6f9d"`
Nov 18 16:51:36.571 INFO [stdout] b23ccca980dd9d60cb1becd0d1906663b811bd9323a274eda29b92127cff6f9d
Nov 18 16:51:36.573 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 16:51:36.892 INFO [stdout] 02552832b1cb59d126dd26e0dcfaa2ed3737fe41ab782c99852271922487c07f
Nov 18 16:51:36.895 INFO running `"docker" "start" "-a" "02552832b1cb59d126dd26e0dcfaa2ed3737fe41ab782c99852271922487c07f"`
Nov 18 16:51:37.942 INFO [stderr] usermod: no changes
Nov 18 16:51:38.181 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 18 16:51:49.687 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.73s
Nov 18 16:51:49.703 INFO [stderr] su: No module specific data is present
Nov 18 16:51:50.269 INFO running `"docker" "inspect" "02552832b1cb59d126dd26e0dcfaa2ed3737fe41ab782c99852271922487c07f"`
Nov 18 16:51:50.488 INFO running `"docker" "rm" "-f" "02552832b1cb59d126dd26e0dcfaa2ed3737fe41ab782c99852271922487c07f"`
Nov 18 16:51:50.727 INFO [stdout] 02552832b1cb59d126dd26e0dcfaa2ed3737fe41ab782c99852271922487c07f
Nov 18 16:51:50.735 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 16:51:51.018 INFO [stdout] 54ce1486fb05891e64e0c69954a4cd1ccf7441bc1c9fb3d451861604e3f60bb5
Nov 18 16:51:51.021 INFO running `"docker" "start" "-a" "54ce1486fb05891e64e0c69954a4cd1ccf7441bc1c9fb3d451861604e3f60bb5"`
Nov 18 16:51:51.651 INFO [stderr] usermod: no changes
Nov 18 16:51:51.895 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Nov 18 16:51:51.903 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-48ecb13c7d23f2c0
Nov 18 16:51:51.906 INFO [stdout] 
Nov 18 16:51:51.906 INFO [stdout] running 0 tests
Nov 18 16:51:51.906 INFO [stdout] 
Nov 18 16:51:51.906 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:51:51.906 INFO [stdout] 
Nov 18 16:51:51.907 INFO [stderr]      Running /target/debug/deps/report-9270117c448373c1
Nov 18 16:51:51.910 INFO [stdout] 
Nov 18 16:51:51.910 INFO [stdout] running 5 tests
Nov 18 16:51:51.911 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Nov 18 16:51:51.912 INFO [stdout] test initialize_valid ... ok
Nov 18 16:51:51.938 INFO [stderr] TRACE 2018-11-18T16:51:51Z: mio::poll: registering with poller
Nov 18 16:51:51.939 INFO [stderr] TRACE 2018-11-18T16:51:51Z: tokio_threadpool::builder: build; num-workers=8
Nov 18 16:51:51.939 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: tokio_reactor::background: starting background reactor
Nov 18 16:51:51.939 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: tokio_core::reactor: consuming notification queue
Nov 18 16:51:51.939 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: tokio_core::reactor: loop poll - 52.928µs
Nov 18 16:51:51.939 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233087, tv_nsec: 820706286 }
Nov 18 16:51:51.940 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: tokio_core::reactor: loop process, 143.577µs
Nov 18 16:51:51.940 INFO [stderr] DEBUG 2018-11-18T16:51:51Z: crisp_status_reporter: Crisp Status Reporter: Now running
Nov 18 16:51:52.016 INFO [stdout] test initialize_invalid_replica_id ... ok
Nov 18 16:51:52.016 INFO [stdout] test initialize_invalid_node_id ... ok
Nov 18 16:51:52.016 INFO [stdout] test initialize_invalid_service_id ... ok
Nov 18 16:52:01.940 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Nov 18 16:52:01.941 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: 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.35375, ram: 0.1204111 } }
Nov 18 16:52:01.943 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop poll - 10.003029228s
Nov 18 16:52:01.943 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233097, tv_nsec: 823939902 }
Nov 18 16:52:01.944 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop process, 1.089135ms
Nov 18 16:52:01.944 INFO [stderr] TRACE 2018-11-18T16:52:01Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Nov 18 16:52:01.945 INFO [stderr] TRACE 2018-11-18T16:52:01Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Nov 18 16:52:01.946 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop poll - 1.306047ms
Nov 18 16:52:01.946 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233097, tv_nsec: 827134059 }
Nov 18 16:52:01.947 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop process, 1.09516ms
Nov 18 16:52:01.947 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Nov 18 16:52:01.949 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop poll - 2.008255ms
Nov 18 16:52:01.950 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233097, tv_nsec: 830754102 }
Nov 18 16:52:01.950 INFO [stderr] DEBUG 2018-11-18T16:52:01Z: tokio_core::reactor: loop process, 1.054876ms
Nov 18 16:52:01.951 INFO [stderr] ERROR 2018-11-18T16:52:01Z: 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 18 16:52:01.951 INFO [stderr]  WARN 2018-11-18T16:52:01Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Nov 18 16:52:06.940 INFO [stdout] test run_and_end_valid ... ok
Nov 18 16:52:06.940 INFO [stdout] 
Nov 18 16:52:06.940 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:52:06.940 INFO [stdout] 
Nov 18 16:52:06.948 INFO [stderr]    Doc-tests crisp_status_reporter
Nov 18 16:52:17.351 INFO [stdout] 
Nov 18 16:52:17.351 INFO [stdout] running 0 tests
Nov 18 16:52:17.351 INFO [stdout] 
Nov 18 16:52:17.351 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:52:17.351 INFO [stdout] 
Nov 18 16:52:17.364 INFO [stderr] su: No module specific data is present
Nov 18 16:52:17.620 INFO running `"docker" "inspect" "54ce1486fb05891e64e0c69954a4cd1ccf7441bc1c9fb3d451861604e3f60bb5"`
Nov 18 16:52:17.724 INFO running `"docker" "rm" "-f" "54ce1486fb05891e64e0c69954a4cd1ccf7441bc1c9fb3d451861604e3f60bb5"`
Nov 18 16:52:17.820 INFO [stdout] 54ce1486fb05891e64e0c69954a4cd1ccf7441bc1c9fb3d451861604e3f60bb5