Nov 02 00:43:47.706 INFO testing vigil-reporter-1.0.3 against beta-2018-10-30 for beta-1.31-1
Nov 02 00:43:47.708 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 02 00:43:48.214 INFO blam! ff8229cd481972f59ffe89fa15463f8629136d0e11457ae95b0c9761cdab03b0
Nov 02 00:43:48.217 INFO running `"docker" "start" "-a" "ff8229cd481972f59ffe89fa15463f8629136d0e11457ae95b0c9761cdab03b0"`
Nov 02 00:43:49.583 INFO kablam! usermod: no changes
Nov 02 00:43:49.955 INFO kablam!    Compiling sys-info v0.5.6
Nov 02 00:43:49.955 INFO kablam!    Compiling hyper v0.11.27
Nov 02 00:43:49.955 INFO kablam!    Compiling tokio-tls v0.1.4
Nov 02 00:44:03.353 INFO kablam!    Compiling hyper-tls v0.1.4
Nov 02 00:44:06.375 INFO kablam!    Compiling reqwest v0.8.8
Nov 02 00:44:14.732 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Nov 02 00:44:19.867 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 30.25s
Nov 02 00:44:19.883 INFO kablam! su: No module specific data is present
Nov 02 00:44:20.352 INFO running `"docker" "rm" "-f" "ff8229cd481972f59ffe89fa15463f8629136d0e11457ae95b0c9761cdab03b0"`
Nov 02 00:44:20.618 INFO blam! ff8229cd481972f59ffe89fa15463f8629136d0e11457ae95b0c9761cdab03b0
Nov 02 00:44:20.627 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 02 00:44:20.982 INFO blam! 5b8a5488a46b97267169fba77c3fdbe19a22f4cfcb8b971414540818dfc805ae
Nov 02 00:44:20.985 INFO running `"docker" "start" "-a" "5b8a5488a46b97267169fba77c3fdbe19a22f4cfcb8b971414540818dfc805ae"`
Nov 02 00:44:21.505 INFO kablam! usermod: no changes
Nov 02 00:44:21.703 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Nov 02 00:44:35.427 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 13.90s
Nov 02 00:44:35.453 INFO kablam! su: No module specific data is present
Nov 02 00:44:36.363 INFO running `"docker" "rm" "-f" "5b8a5488a46b97267169fba77c3fdbe19a22f4cfcb8b971414540818dfc805ae"`
Nov 02 00:44:36.522 INFO blam! 5b8a5488a46b97267169fba77c3fdbe19a22f4cfcb8b971414540818dfc805ae
Nov 02 00:44:36.525 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 02 00:44:36.716 INFO blam! d9925f49b3bf2a222b89100f351cab7852a87987af844200763021a2d3a7b03f
Nov 02 00:44:36.716 INFO running `"docker" "start" "-a" "d9925f49b3bf2a222b89100f351cab7852a87987af844200763021a2d3a7b03f"`
Nov 02 00:44:37.235 INFO kablam! usermod: no changes
Nov 02 00:44:37.426 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.17s
Nov 02 00:44:37.447 INFO kablam!      Running /target/debug/deps/vigil_reporter-b7d199da447d8036
Nov 02 00:44:37.449 INFO blam! 
Nov 02 00:44:37.449 INFO blam! running 0 tests
Nov 02 00:44:37.449 INFO blam! 
Nov 02 00:44:37.449 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 00:44:37.449 INFO blam! 
Nov 02 00:44:37.449 INFO kablam!      Running /target/debug/deps/report-dac322123b9d4be7
Nov 02 00:44:37.453 INFO blam! 
Nov 02 00:44:37.453 INFO blam! running 5 tests
Nov 02 00:44:37.463 INFO blam! test initialize_valid ... ok
Nov 02 00:44:37.479 INFO kablam! DEBUG 2018-11-02T00:44:37Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Nov 02 00:44:37.511 INFO kablam! TRACE 2018-11-02T00:44:37Z: mio::poll: registering with poller
Nov 02 00:44:37.512 INFO kablam! TRACE 2018-11-02T00:44:37Z: tokio_threadpool::builder: build; num-workers=8
Nov 02 00:44:37.535 INFO kablam! DEBUG 2018-11-02T00:44:37Z: tokio_reactor::background: starting background reactor
Nov 02 00:44:37.547 INFO kablam! DEBUG 2018-11-02T00:44:37Z: tokio_core::reactor: consuming notification queue
Nov 02 00:44:37.547 INFO kablam! DEBUG 2018-11-02T00:44:37Z: tokio_core::reactor: loop poll - 102.931µs
Nov 02 00:44:37.547 INFO kablam! DEBUG 2018-11-02T00:44:37Z: tokio_core::reactor: loop time - Instant { tv_sec: 2792653, tv_nsec: 420433386 }
Nov 02 00:44:37.547 INFO kablam! DEBUG 2018-11-02T00:44:37Z: tokio_core::reactor: loop process, 62.72µs
Nov 02 00:44:37.555 INFO kablam! DEBUG 2018-11-02T00:44:37Z: vigil_reporter: Vigil Reporter: Now running
Nov 02 00:44:37.639 INFO blam! test initialize_invalid_node_id ... ok
Nov 02 00:44:37.639 INFO blam! test initialize_invalid_probe_id ... ok
Nov 02 00:44:37.639 INFO blam! test initialize_invalid_replica_id ... ok
Nov 02 00:44:47.551 INFO kablam! DEBUG 2018-11-02T00:44:47Z: vigil_reporter: Vigil Reporter: Will dispatch request
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: vigil_reporter: Vigil Reporter: Will send request to URL: http://status.example.com.local/reporter/relay/socket-client/ with payload: ReportPayload { replica: "192.168.1.10", interval: 30, load: ReportPayloadLoad { cpu: 5.89375, ram: 0.13312727 } }
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop poll - 10.009198383s
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop time - Instant { tv_sec: 2792663, tv_nsec: 429722435 }
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop process, 63.073µs
Nov 02 00:44:47.552 INFO kablam! TRACE 2018-11-02T00:44:47Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Nov 02 00:44:47.552 INFO kablam! TRACE 2018-11-02T00:44:47Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop poll - 233.649µs
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop time - Instant { tv_sec: 2792663, tv_nsec: 430316953 }
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop process, 39.115µs
Nov 02 00:44:47.552 INFO kablam! DEBUG 2018-11-02T00:44:47Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Nov 02 00:44:47.555 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop poll - 5.515435ms
Nov 02 00:44:47.555 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop time - Instant { tv_sec: 2792663, tv_nsec: 435895701 }
Nov 02 00:44:47.555 INFO kablam! DEBUG 2018-11-02T00:44:47Z: tokio_core::reactor: loop process, 57.331µs
Nov 02 00:44:47.563 INFO kablam! ERROR 2018-11-02T00:44:47Z: vigil_reporter: Vigil Reporter: Failed dispatching request: http://status.example.com.local/reporter/relay/socket-client/: failed to lookup address information: Temporary failure in name resolution
Nov 02 00:44:47.563 INFO kablam!  WARN 2018-11-02T00:44:47Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Nov 02 00:44:52.539 INFO blam! test run_and_end_valid ... ok
Nov 02 00:44:52.539 INFO blam! 
Nov 02 00:44:52.539 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 00:44:52.539 INFO blam! 
Nov 02 00:44:52.547 INFO kablam!    Doc-tests vigil_reporter
Nov 02 00:45:01.455 INFO blam! 
Nov 02 00:45:01.455 INFO blam! running 0 tests
Nov 02 00:45:01.455 INFO blam! 
Nov 02 00:45:01.455 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 00:45:01.455 INFO blam! 
Nov 02 00:45:01.458 INFO kablam! su: No module specific data is present
Nov 02 00:45:02.023 INFO running `"docker" "rm" "-f" "d9925f49b3bf2a222b89100f351cab7852a87987af844200763021a2d3a7b03f"`
Nov 02 00:45:02.271 INFO blam! d9925f49b3bf2a222b89100f351cab7852a87987af844200763021a2d3a7b03f
