Dec 03 14:56:03.019 INFO testing crisp-status-reporter-1.1.2 against 1.30.1 for beta-1.31-5
Dec 03 14:56:03.019 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:03.203 INFO [stdout] cb0998dd0f19452ebfa68e17ed0b1defaaf8dc499168a2c227b235d1e4625d83
Dec 03 14:56:03.209 INFO running `"docker" "start" "-a" "cb0998dd0f19452ebfa68e17ed0b1defaaf8dc499168a2c227b235d1e4625d83"`
Dec 03 14:56:04.023 INFO [stderr] usermod: no changes
Dec 03 14:56:04.429 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 03 14:56:16.003 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.91s
Dec 03 14:56:16.012 INFO [stderr] su: No module specific data is present
Dec 03 14:56:17.193 INFO running `"docker" "inspect" "cb0998dd0f19452ebfa68e17ed0b1defaaf8dc499168a2c227b235d1e4625d83"`
Dec 03 14:56:17.525 INFO running `"docker" "rm" "-f" "cb0998dd0f19452ebfa68e17ed0b1defaaf8dc499168a2c227b235d1e4625d83"`
Dec 03 14:56:17.931 INFO [stdout] cb0998dd0f19452ebfa68e17ed0b1defaaf8dc499168a2c227b235d1e4625d83
Dec 03 14:56:17.939 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:18.366 INFO [stdout] a5bd82c3022d2b466af9d59c6fdc037425cc1dfd834931649594d3cda4387ed3
Dec 03 14:56:18.368 INFO running `"docker" "start" "-a" "a5bd82c3022d2b466af9d59c6fdc037425cc1dfd834931649594d3cda4387ed3"`
Dec 03 14:56:20.449 INFO [stderr] usermod: no changes
Dec 03 14:56:20.793 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 03 14:56:42.825 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.31s
Dec 03 14:56:42.843 INFO [stderr] su: No module specific data is present
Dec 03 14:56:44.059 INFO running `"docker" "inspect" "a5bd82c3022d2b466af9d59c6fdc037425cc1dfd834931649594d3cda4387ed3"`
Dec 03 14:56:44.319 INFO running `"docker" "rm" "-f" "a5bd82c3022d2b466af9d59c6fdc037425cc1dfd834931649594d3cda4387ed3"`
Dec 03 14:56:44.688 INFO [stdout] a5bd82c3022d2b466af9d59c6fdc037425cc1dfd834931649594d3cda4387ed3
Dec 03 14:56:44.693 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:45.155 INFO [stdout] 92321a1cc4f971fc08b973d804360761b18f3795f4fab193f35b9a08c1c35b59
Dec 03 14:56:45.157 INFO running `"docker" "start" "-a" "92321a1cc4f971fc08b973d804360761b18f3795f4fab193f35b9a08c1c35b59"`
Dec 03 14:56:47.090 INFO [stderr] usermod: no changes
Dec 03 14:56:47.412 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Dec 03 14:56:47.424 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-0257cba41a1225ec
Dec 03 14:56:47.426 INFO [stdout] 
Dec 03 14:56:47.426 INFO [stdout] running 0 tests
Dec 03 14:56:47.426 INFO [stdout] 
Dec 03 14:56:47.426 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:56:47.426 INFO [stdout] 
Dec 03 14:56:47.435 INFO [stderr]      Running /target/debug/deps/report-10a948194846133d
Dec 03 14:56:47.441 INFO [stdout] 
Dec 03 14:56:47.441 INFO [stdout] running 5 tests
Dec 03 14:56:47.459 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 03 14:56:47.475 INFO [stdout] test initialize_valid ... ok
Dec 03 14:56:47.479 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.479 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.480 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.480 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.480 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.481 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.481 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.481 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.482 INFO [stderr] TRACE 2018-12-03T14:56:47Z: tokio_threadpool::builder: build; num-workers=8
Dec 03 14:56:47.482 INFO [stderr] TRACE 2018-12-03T14:56:47Z: mio::poll: registering with poller
Dec 03 14:56:47.483 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: tokio_core::reactor: consuming notification queue
Dec 03 14:56:47.483 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: tokio_core::reactor: loop poll - 345.237µs
Dec 03 14:56:47.483 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522183, tv_nsec: 364489978 }
Dec 03 14:56:47.483 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: tokio_core::reactor: loop process, 402.308µs
Dec 03 14:56:47.484 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: tokio_reactor::background: starting background reactor
Dec 03 14:56:47.487 INFO [stderr] DEBUG 2018-12-03T14:56:47Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 03 14:56:47.683 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 03 14:56:47.683 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 03 14:56:47.684 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 03 14:56:57.487 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 03 14:56:57.488 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: 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: 2.965, ram: 0.19190001 } }
Dec 03 14:56:57.489 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop poll - 10.005069863s
Dec 03 14:56:57.489 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522193, tv_nsec: 370149325 }
Dec 03 14:56:57.489 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop process, 223.036µs
Dec 03 14:56:57.489 INFO [stderr] TRACE 2018-12-03T14:56:57Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 03 14:56:57.489 INFO [stderr] TRACE 2018-12-03T14:56:57Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Dec 03 14:56:57.490 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop poll - 422.147µs
Dec 03 14:56:57.490 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522193, tv_nsec: 371111448 }
Dec 03 14:56:57.490 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop process, 333.842µs
Dec 03 14:56:57.490 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 03 14:56:57.492 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop poll - 1.94237ms
Dec 03 14:56:57.492 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522193, tv_nsec: 373518049 }
Dec 03 14:56:57.492 INFO [stderr] DEBUG 2018-12-03T14:56:57Z: tokio_core::reactor: loop process, 311.371µs
Dec 03 14:56:57.493 INFO [stderr] ERROR 2018-12-03T14:56:57Z: 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 03 14:56:57.493 INFO [stderr]  WARN 2018-12-03T14:56:57Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 03 14:57:02.486 INFO [stdout] test run_and_end_valid ... ok
Dec 03 14:57:02.486 INFO [stdout] 
Dec 03 14:57:02.486 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:57:02.486 INFO [stdout] 
Dec 03 14:57:02.496 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 03 14:57:20.551 INFO [stdout] 
Dec 03 14:57:20.551 INFO [stdout] running 0 tests
Dec 03 14:57:20.551 INFO [stdout] 
Dec 03 14:57:20.551 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:57:20.551 INFO [stdout] 
Dec 03 14:57:20.571 INFO [stderr] su: No module specific data is present
Dec 03 14:57:21.413 INFO running `"docker" "inspect" "92321a1cc4f971fc08b973d804360761b18f3795f4fab193f35b9a08c1c35b59"`
Dec 03 14:57:21.709 INFO running `"docker" "rm" "-f" "92321a1cc4f971fc08b973d804360761b18f3795f4fab193f35b9a08c1c35b59"`
Dec 03 14:57:22.193 INFO [stdout] 92321a1cc4f971fc08b973d804360761b18f3795f4fab193f35b9a08c1c35b59
