Dec 02 11:48:33.305 INFO testing vigil-reporter-1.0.3 against 1.30.1 for beta-1.31-5
Dec 02 11:48:33.305 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 02 11:48:33.542 INFO [stdout] 3d4c55c90b516a52c357fb10b49d05eb8f763b378735ec3706504ee132b0c573
Dec 02 11:48:33.544 INFO running `"docker" "start" "-a" "3d4c55c90b516a52c357fb10b49d05eb8f763b378735ec3706504ee132b0c573"`
Dec 02 11:48:34.099 INFO [stderr] usermod: no changes
Dec 02 11:48:34.467 INFO [stderr]    Compiling sys-info v0.5.6
Dec 02 11:48:38.023 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Dec 02 11:48:45.963 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.83s
Dec 02 11:48:45.979 INFO [stderr] su: No module specific data is present
Dec 02 11:48:46.301 INFO running `"docker" "inspect" "3d4c55c90b516a52c357fb10b49d05eb8f763b378735ec3706504ee132b0c573"`
Dec 02 11:48:46.436 INFO running `"docker" "rm" "-f" "3d4c55c90b516a52c357fb10b49d05eb8f763b378735ec3706504ee132b0c573"`
Dec 02 11:48:46.537 INFO [stdout] 3d4c55c90b516a52c357fb10b49d05eb8f763b378735ec3706504ee132b0c573
Dec 02 11:48:46.539 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 02 11:48:46.724 INFO [stdout] 114f8319a603dee76f42ec9d6234ba30126c090252453697480898e76d324723
Dec 02 11:48:46.728 INFO running `"docker" "start" "-a" "114f8319a603dee76f42ec9d6234ba30126c090252453697480898e76d324723"`
Dec 02 11:48:47.150 INFO [stderr] usermod: no changes
Dec 02 11:48:47.399 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Dec 02 11:49:02.135 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.95s
Dec 02 11:49:02.149 INFO [stderr] su: No module specific data is present
Dec 02 11:49:02.605 INFO running `"docker" "inspect" "114f8319a603dee76f42ec9d6234ba30126c090252453697480898e76d324723"`
Dec 02 11:49:02.839 INFO running `"docker" "rm" "-f" "114f8319a603dee76f42ec9d6234ba30126c090252453697480898e76d324723"`
Dec 02 11:49:03.063 INFO [stdout] 114f8319a603dee76f42ec9d6234ba30126c090252453697480898e76d324723
Dec 02 11:49:03.071 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 02 11:49:03.500 INFO [stdout] ee344ad9a74a3a58c4e5fed5c58f1e99df7107490e4be86df3e13e7fb99ad63d
Dec 02 11:49:03.502 INFO running `"docker" "start" "-a" "ee344ad9a74a3a58c4e5fed5c58f1e99df7107490e4be86df3e13e7fb99ad63d"`
Dec 02 11:49:04.207 INFO [stderr] usermod: no changes
Dec 02 11:49:04.459 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Dec 02 11:49:04.471 INFO [stderr]      Running /target/debug/deps/vigil_reporter-c662a1cc47c6957f
Dec 02 11:49:04.491 INFO [stderr]      Running /target/debug/deps/report-b7f9b6b4eb2af93d
Dec 02 11:49:04.491 INFO [stdout] 
Dec 02 11:49:04.491 INFO [stdout] running 0 tests
Dec 02 11:49:04.491 INFO [stdout] 
Dec 02 11:49:04.491 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 11:49:04.491 INFO [stdout] 
Dec 02 11:49:04.491 INFO [stdout] 
Dec 02 11:49:04.491 INFO [stdout] running 5 tests
Dec 02 11:49:04.495 INFO [stdout] test initialize_valid ... ok
Dec 02 11:49:04.531 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: tokio_threadpool::builder: build; num-workers=8
Dec 02 11:49:04.572 INFO [stderr] TRACE 2018-12-02T11:49:04Z: mio::poll: registering with poller
Dec 02 11:49:04.591 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: tokio_core::reactor: consuming notification queue
Dec 02 11:49:04.591 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: tokio_core::reactor: loop poll - 62.055µs
Dec 02 11:49:04.591 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: tokio_core::reactor: loop time - Instant { tv_sec: 5424520, tv_nsec: 464515287 }
Dec 02 11:49:04.591 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: tokio_core::reactor: loop process, 40.136µs
Dec 02 11:49:04.627 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: tokio_reactor::background: starting background reactor
Dec 02 11:49:04.627 INFO [stderr] DEBUG 2018-12-02T11:49:04Z: vigil_reporter: Vigil Reporter: Now running
Dec 02 11:49:04.659 INFO [stdout] test initialize_invalid_probe_id ... ok
Dec 02 11:49:04.659 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 02 11:49:04.659 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: vigil_reporter: Vigil Reporter: Will dispatch request
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: 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: 4.045, ram: 0.1310693 } }
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop poll - 10.037021217s
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop time - Instant { tv_sec: 5424530, tv_nsec: 501595901 }
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop process, 57.967µs
Dec 02 11:49:14.623 INFO [stderr] TRACE 2018-12-02T11:49:14Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Dec 02 11:49:14.623 INFO [stderr] TRACE 2018-12-02T11:49:14Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop poll - 214.455µs
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop time - Instant { tv_sec: 5424530, tv_nsec: 502130019 }
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop process, 41.621µs
Dec 02 11:49:14.623 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Dec 02 11:49:14.625 INFO [stderr] ERROR 2018-12-02T11:49:14Z: 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
Dec 02 11:49:14.625 INFO [stderr]  WARN 2018-12-02T11:49:14Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Dec 02 11:49:14.625 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop poll - 3.468695ms
Dec 02 11:49:14.625 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop time - Instant { tv_sec: 5424530, tv_nsec: 505664956 }
Dec 02 11:49:14.625 INFO [stderr] DEBUG 2018-12-02T11:49:14Z: tokio_core::reactor: loop process, 35.815µs
Dec 02 11:49:19.609 INFO [stdout] test run_and_end_valid ... ok
Dec 02 11:49:19.609 INFO [stdout] 
Dec 02 11:49:19.609 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 11:49:19.609 INFO [stdout] 
Dec 02 11:49:19.611 INFO [stderr]    Doc-tests vigil_reporter
Dec 02 11:49:33.050 INFO [stdout] 
Dec 02 11:49:33.050 INFO [stdout] running 0 tests
Dec 02 11:49:33.050 INFO [stdout] 
Dec 02 11:49:33.050 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 11:49:33.050 INFO [stdout] 
Dec 02 11:49:33.065 INFO [stderr] su: No module specific data is present
Dec 02 11:49:34.616 INFO running `"docker" "inspect" "ee344ad9a74a3a58c4e5fed5c58f1e99df7107490e4be86df3e13e7fb99ad63d"`
Dec 02 11:49:35.063 INFO running `"docker" "rm" "-f" "ee344ad9a74a3a58c4e5fed5c58f1e99df7107490e4be86df3e13e7fb99ad63d"`
Dec 02 11:49:35.419 INFO [stdout] ee344ad9a74a3a58c4e5fed5c58f1e99df7107490e4be86df3e13e7fb99ad63d
