Nov 17 18:53:15.778 INFO testing vigil-reporter-1.0.3 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 18:53:15.778 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 18:53:16.110 INFO [stdout] bf68e6a7eaac2303d95306153f0f30cf2fbece2c2f7cd1ce7517c4ea8733f98d
Nov 17 18:53:16.113 INFO running `"docker" "start" "-a" "bf68e6a7eaac2303d95306153f0f30cf2fbece2c2f7cd1ce7517c4ea8733f98d"`
Nov 17 18:53:17.278 INFO [stderr] usermod: no changes
Nov 17 18:53:17.691 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Nov 17 18:53:24.537 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.22s
Nov 17 18:53:24.557 INFO [stderr] su: No module specific data is present
Nov 17 18:53:25.060 INFO running `"docker" "inspect" "bf68e6a7eaac2303d95306153f0f30cf2fbece2c2f7cd1ce7517c4ea8733f98d"`
Nov 17 18:53:25.402 INFO running `"docker" "rm" "-f" "bf68e6a7eaac2303d95306153f0f30cf2fbece2c2f7cd1ce7517c4ea8733f98d"`
Nov 17 18:53:25.623 INFO [stdout] bf68e6a7eaac2303d95306153f0f30cf2fbece2c2f7cd1ce7517c4ea8733f98d
Nov 17 18:53:25.630 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 18:53:26.116 INFO [stdout] b22639b2ceac6b6aca36a20988f971d659dc494f8d970d9e26e1ee4d53e47064
Nov 17 18:53:26.118 INFO running `"docker" "start" "-a" "b22639b2ceac6b6aca36a20988f971d659dc494f8d970d9e26e1ee4d53e47064"`
Nov 17 18:53:27.998 INFO [stderr] usermod: no changes
Nov 17 18:53:28.256 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Nov 17 18:53:41.221 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.19s
Nov 17 18:53:41.236 INFO [stderr] su: No module specific data is present
Nov 17 18:53:41.639 INFO running `"docker" "inspect" "b22639b2ceac6b6aca36a20988f971d659dc494f8d970d9e26e1ee4d53e47064"`
Nov 17 18:53:41.751 INFO running `"docker" "rm" "-f" "b22639b2ceac6b6aca36a20988f971d659dc494f8d970d9e26e1ee4d53e47064"`
Nov 17 18:53:41.905 INFO [stdout] b22639b2ceac6b6aca36a20988f971d659dc494f8d970d9e26e1ee4d53e47064
Nov 17 18:53:41.908 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 18:53:42.106 INFO [stdout] 1408a8125bbaa81446552f096204e5dc9271b415b5530f86d5ff650d0c98760b
Nov 17 18:53:42.106 INFO running `"docker" "start" "-a" "1408a8125bbaa81446552f096204e5dc9271b415b5530f86d5ff650d0c98760b"`
Nov 17 18:53:42.650 INFO [stderr] usermod: no changes
Nov 17 18:53:42.872 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Nov 17 18:53:42.892 INFO [stderr]      Running /target/debug/deps/vigil_reporter-a2a6366f065909d7
Nov 17 18:53:42.899 INFO [stderr]      Running /target/debug/deps/report-d533bde0a9584344
Nov 17 18:53:42.899 INFO [stdout] 
Nov 17 18:53:42.899 INFO [stdout] running 0 tests
Nov 17 18:53:42.899 INFO [stdout] 
Nov 17 18:53:42.899 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:53:42.899 INFO [stdout] 
Nov 17 18:53:42.900 INFO [stdout] 
Nov 17 18:53:42.900 INFO [stdout] running 5 tests
Nov 17 18:53:42.903 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Nov 17 18:53:42.929 INFO [stdout] test initialize_valid ... ok
Nov 17 18:53:42.935 INFO [stderr] TRACE 2018-11-17T18:53:42Z: mio::poll: registering with poller
Nov 17 18:53:42.935 INFO [stderr] TRACE 2018-11-17T18:53:42Z: tokio_threadpool::builder: build; num-workers=8
Nov 17 18:53:42.935 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: tokio_reactor::background: starting background reactor
Nov 17 18:53:42.935 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: vigil_reporter: Vigil Reporter: Now running
Nov 17 18:53:42.935 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: tokio_core::reactor: consuming notification queue
Nov 17 18:53:42.936 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: tokio_core::reactor: loop poll - 63.785µs
Nov 17 18:53:42.936 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: tokio_core::reactor: loop time - Instant { tv_sec: 4153998, tv_nsec: 812670119 }
Nov 17 18:53:42.936 INFO [stderr] DEBUG 2018-11-17T18:53:42Z: tokio_core::reactor: loop process, 43.27µs
Nov 17 18:53:43.001 INFO [stdout] test initialize_invalid_node_id ... ok
Nov 17 18:53:43.001 INFO [stdout] test initialize_invalid_probe_id ... ok
Nov 17 18:53:43.001 INFO [stdout] test initialize_invalid_replica_id ... ok
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: vigil_reporter: Vigil Reporter: Will dispatch request
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: 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: 8.50875, ram: 0.13249308 } }
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop poll - 10.001280594s
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154008, tv_nsec: 814017932 }
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop process, 68.331µs
Nov 17 18:53:52.935 INFO [stderr] TRACE 2018-11-17T18:53:52Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Nov 17 18:53:52.935 INFO [stderr] TRACE 2018-11-17T18:53:52Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop poll - 416.951µs
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154008, tv_nsec: 814840093 }
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop process, 72.807µs
Nov 17 18:53:52.935 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Nov 17 18:53:52.939 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop poll - 1.217518ms
Nov 17 18:53:52.939 INFO [stderr] ERROR 2018-11-17T18:53:52Z: 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 17 18:53:52.939 INFO [stderr]  WARN 2018-11-17T18:53:52Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Nov 17 18:53:52.939 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154008, tv_nsec: 816328076 }
Nov 17 18:53:52.939 INFO [stderr] DEBUG 2018-11-17T18:53:52Z: tokio_core::reactor: loop process, 345.845µs
Nov 17 18:53:57.932 INFO [stdout] test run_and_end_valid ... ok
Nov 17 18:53:57.932 INFO [stdout] 
Nov 17 18:53:57.932 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:53:57.932 INFO [stdout] 
Nov 17 18:53:57.939 INFO [stderr]    Doc-tests vigil_reporter
Nov 17 18:54:05.355 INFO [stdout] 
Nov 17 18:54:05.355 INFO [stdout] running 0 tests
Nov 17 18:54:05.355 INFO [stdout] 
Nov 17 18:54:05.355 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:54:05.355 INFO [stdout] 
Nov 17 18:54:05.368 INFO [stderr] su: No module specific data is present
Nov 17 18:54:05.685 INFO running `"docker" "inspect" "1408a8125bbaa81446552f096204e5dc9271b415b5530f86d5ff650d0c98760b"`
Nov 17 18:54:05.775 INFO running `"docker" "rm" "-f" "1408a8125bbaa81446552f096204e5dc9271b415b5530f86d5ff650d0c98760b"`
Nov 17 18:54:05.940 INFO [stdout] 1408a8125bbaa81446552f096204e5dc9271b415b5530f86d5ff650d0c98760b