Dec 15 17:50:50.169 INFO testing crisp-status-reporter-1.1.2 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 17:50:50.169 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:50.409 INFO [stdout] e8b9b40813c48aa53debf345a313b477b6e27419d7d8143c240cec8ca2157ebb
Dec 15 17:50:50.412 INFO running `"docker" "start" "-a" "e8b9b40813c48aa53debf345a313b477b6e27419d7d8143c240cec8ca2157ebb"`
Dec 15 17:50:50.978 INFO [stderr] usermod: no changes
Dec 15 17:50:51.363 INFO [stderr]    Compiling sys-info v0.5.6
Dec 15 17:50:54.184 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 15 17:51:03.599 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.59s
Dec 15 17:51:03.615 INFO [stderr] su: No module specific data is present
Dec 15 17:51:04.751 INFO running `"docker" "inspect" "e8b9b40813c48aa53debf345a313b477b6e27419d7d8143c240cec8ca2157ebb"`
Dec 15 17:51:05.008 INFO running `"docker" "rm" "-f" "e8b9b40813c48aa53debf345a313b477b6e27419d7d8143c240cec8ca2157ebb"`
Dec 15 17:51:05.329 INFO [stdout] e8b9b40813c48aa53debf345a313b477b6e27419d7d8143c240cec8ca2157ebb
Dec 15 17:51:05.337 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:51:05.778 INFO [stdout] d9ae7b01b5ed8beefa9482a50b477fdd1e77c00f0f0fbcd9ab02afb237eb2690
Dec 15 17:51:05.781 INFO running `"docker" "start" "-a" "d9ae7b01b5ed8beefa9482a50b477fdd1e77c00f0f0fbcd9ab02afb237eb2690"`
Dec 15 17:51:07.673 INFO [stderr] usermod: no changes
Dec 15 17:51:07.937 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 15 17:51:24.730 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.04s
Dec 15 17:51:24.755 INFO [stderr] su: No module specific data is present
Dec 15 17:51:26.214 INFO running `"docker" "inspect" "d9ae7b01b5ed8beefa9482a50b477fdd1e77c00f0f0fbcd9ab02afb237eb2690"`
Dec 15 17:51:26.565 INFO running `"docker" "rm" "-f" "d9ae7b01b5ed8beefa9482a50b477fdd1e77c00f0f0fbcd9ab02afb237eb2690"`
Dec 15 17:51:26.826 INFO [stdout] d9ae7b01b5ed8beefa9482a50b477fdd1e77c00f0f0fbcd9ab02afb237eb2690
Dec 15 17:51:26.828 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:27.328 INFO [stdout] 5af08517147c2cc95fd321631c398e4a5ee142185812ba90060fcf560e26935e
Dec 15 17:51:27.330 INFO running `"docker" "start" "-a" "5af08517147c2cc95fd321631c398e4a5ee142185812ba90060fcf560e26935e"`
Dec 15 17:51:29.411 INFO [stderr] usermod: no changes
Dec 15 17:51:29.759 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.30s
Dec 15 17:51:29.779 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-ac4f3d1acaba9129
Dec 15 17:51:29.789 INFO [stdout] 
Dec 15 17:51:29.789 INFO [stdout] running 0 tests
Dec 15 17:51:29.789 INFO [stdout] 
Dec 15 17:51:29.789 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:29.789 INFO [stdout] 
Dec 15 17:51:29.790 INFO [stderr]      Running /target/debug/deps/report-0621e93469473dfc
Dec 15 17:51:29.797 INFO [stdout] 
Dec 15 17:51:29.797 INFO [stdout] running 5 tests
Dec 15 17:51:29.798 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 15 17:51:29.819 INFO [stdout] test initialize_valid ... ok
Dec 15 17:51:29.855 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.855 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.855 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.855 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.856 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.856 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.856 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.856 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.856 INFO [stderr] TRACE 2018-12-15T17:51:29Z: tokio_threadpool::builder: build; num-workers=8
Dec 15 17:51:29.857 INFO [stderr] TRACE 2018-12-15T17:51:29Z: mio::poll: registering with poller
Dec 15 17:51:29.857 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: tokio_reactor::background: starting background reactor
Dec 15 17:51:29.857 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: tokio_core::reactor: consuming notification queue
Dec 15 17:51:29.857 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: tokio_core::reactor: loop poll - 64.916µs
Dec 15 17:51:29.857 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569465, tv_nsec: 738807401 }
Dec 15 17:51:29.858 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: tokio_core::reactor: loop process, 54.273µs
Dec 15 17:51:29.875 INFO [stderr] DEBUG 2018-12-15T17:51:29Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 15 17:51:30.072 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 15 17:51:30.072 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 15 17:51:30.075 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 15 17:51:39.875 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 15 17:51:39.876 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: 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.08875, ram: 0.14891213 } }
Dec 15 17:51:39.876 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop poll - 10.018671904s
Dec 15 17:51:39.876 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569475, tv_nsec: 757563400 }
Dec 15 17:51:39.876 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop process, 140.24µs
Dec 15 17:51:39.877 INFO [stderr] TRACE 2018-12-15T17:51:39Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 15 17:51:39.877 INFO [stderr] TRACE 2018-12-15T17:51:39Z: 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:39.877 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop poll - 348.861µs
Dec 15 17:51:39.877 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 15 17:51:39.877 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569475, tv_nsec: 758412303 }
Dec 15 17:51:39.877 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop process, 75.756µs
Dec 15 17:51:39.879 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop poll - 1.4223ms
Dec 15 17:51:39.879 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop time - Instant { tv_sec: 6569475, tv_nsec: 759942567 }
Dec 15 17:51:39.879 INFO [stderr] DEBUG 2018-12-15T17:51:39Z: tokio_core::reactor: loop process, 77.24µs
Dec 15 17:51:39.879 INFO [stderr] ERROR 2018-12-15T17:51:39Z: 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:39.879 INFO [stderr]  WARN 2018-12-15T17:51:39Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 15 17:51:44.871 INFO [stdout] test run_and_end_valid ... ok
Dec 15 17:51:44.871 INFO [stdout] 
Dec 15 17:51:44.871 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:44.871 INFO [stdout] 
Dec 15 17:51:44.878 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 15 17:51:58.655 INFO [stdout] 
Dec 15 17:51:58.655 INFO [stdout] running 0 tests
Dec 15 17:51:58.655 INFO [stdout] 
Dec 15 17:51:58.655 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 17:51:58.655 INFO [stdout] 
Dec 15 17:51:58.667 INFO [stderr] su: No module specific data is present
Dec 15 17:51:59.580 INFO running `"docker" "inspect" "5af08517147c2cc95fd321631c398e4a5ee142185812ba90060fcf560e26935e"`
Dec 15 17:51:59.904 INFO running `"docker" "rm" "-f" "5af08517147c2cc95fd321631c398e4a5ee142185812ba90060fcf560e26935e"`
Dec 15 17:52:00.271 INFO [stdout] 5af08517147c2cc95fd321631c398e4a5ee142185812ba90060fcf560e26935e