Nov 18 16:51:20.935 INFO testing crisp-status-reporter-1.1.2 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 16:51:20.936 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:21.241 INFO [stdout] b19137ab28e76f1782232237e153bbef146680a2fbf5595874bbfd006d18fdf7
Nov 18 16:51:21.244 INFO running `"docker" "start" "-a" "b19137ab28e76f1782232237e153bbef146680a2fbf5595874bbfd006d18fdf7"`
Nov 18 16:51:21.815 INFO [stderr] usermod: no changes
Nov 18 16:51:22.139 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 18 16:51:28.818 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.99s
Nov 18 16:51:28.833 INFO [stderr] su: No module specific data is present
Nov 18 16:51:29.195 INFO running `"docker" "inspect" "b19137ab28e76f1782232237e153bbef146680a2fbf5595874bbfd006d18fdf7"`
Nov 18 16:51:29.324 INFO running `"docker" "rm" "-f" "b19137ab28e76f1782232237e153bbef146680a2fbf5595874bbfd006d18fdf7"`
Nov 18 16:51:29.474 INFO [stdout] b19137ab28e76f1782232237e153bbef146680a2fbf5595874bbfd006d18fdf7
Nov 18 16:51:29.479 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:29.731 INFO [stdout] 85be45142115435bf1b6ab114cfbde5d55ec54f1b0585539687923f2e5178bc3
Nov 18 16:51:29.734 INFO running `"docker" "start" "-a" "85be45142115435bf1b6ab114cfbde5d55ec54f1b0585539687923f2e5178bc3"`
Nov 18 16:51:30.275 INFO [stderr] usermod: no changes
Nov 18 16:51:30.522 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Nov 18 16:51:43.567 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.27s
Nov 18 16:51:43.583 INFO [stderr] su: No module specific data is present
Nov 18 16:51:44.557 INFO running `"docker" "inspect" "85be45142115435bf1b6ab114cfbde5d55ec54f1b0585539687923f2e5178bc3"`
Nov 18 16:51:44.723 INFO running `"docker" "rm" "-f" "85be45142115435bf1b6ab114cfbde5d55ec54f1b0585539687923f2e5178bc3"`
Nov 18 16:51:44.915 INFO [stdout] 85be45142115435bf1b6ab114cfbde5d55ec54f1b0585539687923f2e5178bc3
Nov 18 16:51:44.921 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:45.195 INFO [stdout] 2910ca6b494ec3c4362dba63a101a2aeedcce527bc48425dbac9ca74e443bd26
Nov 18 16:51:45.198 INFO running `"docker" "start" "-a" "2910ca6b494ec3c4362dba63a101a2aeedcce527bc48425dbac9ca74e443bd26"`
Nov 18 16:51:45.927 INFO [stderr] usermod: no changes
Nov 18 16:51:46.151 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Nov 18 16:51:46.170 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-9cbbaec068bb50e4
Nov 18 16:51:46.172 INFO [stdout] 
Nov 18 16:51:46.172 INFO [stdout] running 0 tests
Nov 18 16:51:46.172 INFO [stdout] 
Nov 18 16:51:46.172 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:51:46.172 INFO [stdout] 
Nov 18 16:51:46.173 INFO [stderr]      Running /target/debug/deps/report-9c5813ef22f0a24c
Nov 18 16:51:46.182 INFO [stdout] 
Nov 18 16:51:46.182 INFO [stdout] running 5 tests
Nov 18 16:51:46.203 INFO [stdout] test initialize_valid ... ok
Nov 18 16:51:46.207 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Nov 18 16:51:46.231 INFO [stderr] TRACE 2018-11-18T16:51:46Z: mio::poll: registering with poller
Nov 18 16:51:46.235 INFO [stderr] TRACE 2018-11-18T16:51:46Z: tokio_threadpool::builder: build; num-workers=8
Nov 18 16:51:46.237 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: tokio_reactor::background: starting background reactor
Nov 18 16:51:46.237 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: tokio_core::reactor: consuming notification queue
Nov 18 16:51:46.237 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: tokio_core::reactor: loop poll - 61.366µs
Nov 18 16:51:46.237 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233082, tv_nsec: 118442755 }
Nov 18 16:51:46.237 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: tokio_core::reactor: loop process, 43.937µs
Nov 18 16:51:46.242 INFO [stderr] DEBUG 2018-11-18T16:51:46Z: crisp_status_reporter: Crisp Status Reporter: Now running
Nov 18 16:51:46.355 INFO [stdout] test initialize_invalid_node_id ... ok
Nov 18 16:51:46.355 INFO [stdout] test initialize_invalid_replica_id ... ok
Nov 18 16:51:46.355 INFO [stdout] test initialize_invalid_service_id ... ok
Nov 18 16:51:56.243 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Nov 18 16:51:56.243 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: 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.355, ram: 0.12186593 } }
Nov 18 16:51:56.244 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop poll - 10.006767526s
Nov 18 16:51:56.244 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233092, tv_nsec: 125275770 }
Nov 18 16:51:56.244 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop process, 55.89µs
Nov 18 16:51:56.244 INFO [stderr] TRACE 2018-11-18T16:51:56Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Nov 18 16:51:56.244 INFO [stderr] TRACE 2018-11-18T16:51:56Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Nov 18 16:51:56.244 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop poll - 252.392µs
Nov 18 16:51:56.244 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233092, tv_nsec: 125886017 }
Nov 18 16:51:56.245 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop process, 45.372µs
Nov 18 16:51:56.245 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Nov 18 16:51:56.247 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop poll - 2.203427ms
Nov 18 16:51:56.247 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop time - Instant { tv_sec: 4233092, tv_nsec: 128160119 }
Nov 18 16:51:56.247 INFO [stderr] DEBUG 2018-11-18T16:51:56Z: tokio_core::reactor: loop process, 60.152µs
Nov 18 16:51:56.247 INFO [stderr] ERROR 2018-11-18T16:51:56Z: 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:51:56.247 INFO [stderr]  WARN 2018-11-18T16:51:56Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Nov 18 16:52:01.240 INFO [stdout] test run_and_end_valid ... ok
Nov 18 16:52:01.241 INFO [stdout] 
Nov 18 16:52:01.242 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:52:01.242 INFO [stdout] 
Nov 18 16:52:01.247 INFO [stderr]    Doc-tests crisp_status_reporter
Nov 18 16:52:11.462 INFO [stdout] 
Nov 18 16:52:11.462 INFO [stdout] running 0 tests
Nov 18 16:52:11.462 INFO [stdout] 
Nov 18 16:52:11.462 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:52:11.462 INFO [stdout] 
Nov 18 16:52:11.478 INFO [stderr] su: No module specific data is present
Nov 18 16:52:11.883 INFO running `"docker" "inspect" "2910ca6b494ec3c4362dba63a101a2aeedcce527bc48425dbac9ca74e443bd26"`
Nov 18 16:52:11.992 INFO running `"docker" "rm" "-f" "2910ca6b494ec3c4362dba63a101a2aeedcce527bc48425dbac9ca74e443bd26"`
Nov 18 16:52:12.125 INFO [stdout] 2910ca6b494ec3c4362dba63a101a2aeedcce527bc48425dbac9ca74e443bd26