Nov 17 18:53:07.322 INFO testing vigil-reporter-1.0.3 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 18:53:07.323 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 18:53:07.765 INFO [stdout] 1fee539fb5bc2b2459eecf0d49728df3f606b42aae12dca6347a1735d7a028d8
Nov 17 18:53:07.765 INFO running `"docker" "start" "-a" "1fee539fb5bc2b2459eecf0d49728df3f606b42aae12dca6347a1735d7a028d8"`
Nov 17 18:53:09.124 INFO [stderr] usermod: no changes
Nov 17 18:53:09.411 INFO [stderr]    Compiling sys-info v0.5.6
Nov 17 18:53:09.421 INFO [stderr]    Compiling hyper v0.11.27
Nov 17 18:53:23.159 INFO [stderr]    Compiling hyper-tls v0.1.4
Nov 17 18:53:25.363 INFO [stderr]    Compiling reqwest v0.8.8
Nov 17 18:53:34.403 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Nov 17 18:53:37.139 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 27.99s
Nov 17 18:53:37.152 INFO [stderr] su: No module specific data is present
Nov 17 18:53:37.568 INFO running `"docker" "inspect" "1fee539fb5bc2b2459eecf0d49728df3f606b42aae12dca6347a1735d7a028d8"`
Nov 17 18:53:37.702 INFO running `"docker" "rm" "-f" "1fee539fb5bc2b2459eecf0d49728df3f606b42aae12dca6347a1735d7a028d8"`
Nov 17 18:53:37.802 INFO [stdout] 1fee539fb5bc2b2459eecf0d49728df3f606b42aae12dca6347a1735d7a028d8
Nov 17 18:53:37.804 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 18:53:38.010 INFO [stdout] 1a10bbc55e460d768ed3589f343c95c09e0216a4840bc8ec15b0a0f46b908d2b
Nov 17 18:53:38.012 INFO running `"docker" "start" "-a" "1a10bbc55e460d768ed3589f343c95c09e0216a4840bc8ec15b0a0f46b908d2b"`
Nov 17 18:53:38.927 INFO [stderr] usermod: no changes
Nov 17 18:53:39.178 INFO [stderr]    Compiling vigil-reporter v1.0.3 (/source)
Nov 17 18:53:46.703 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.74s
Nov 17 18:53:46.715 INFO [stderr] su: No module specific data is present
Nov 17 18:53:47.324 INFO running `"docker" "inspect" "1a10bbc55e460d768ed3589f343c95c09e0216a4840bc8ec15b0a0f46b908d2b"`
Nov 17 18:53:47.503 INFO running `"docker" "rm" "-f" "1a10bbc55e460d768ed3589f343c95c09e0216a4840bc8ec15b0a0f46b908d2b"`
Nov 17 18:53:47.645 INFO [stdout] 1a10bbc55e460d768ed3589f343c95c09e0216a4840bc8ec15b0a0f46b908d2b
Nov 17 18:53:47.652 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 18:53:47.888 INFO [stdout] f072a0110ce7b118120b905dab5e820355817fd05f61f3c8e1e710d70e028c02
Nov 17 18:53:47.892 INFO running `"docker" "start" "-a" "f072a0110ce7b118120b905dab5e820355817fd05f61f3c8e1e710d70e028c02"`
Nov 17 18:53:48.519 INFO [stderr] usermod: no changes
Nov 17 18:53:48.779 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Nov 17 18:53:48.799 INFO [stderr]      Running /target/debug/deps/vigil_reporter-9ce23b3956441036
Nov 17 18:53:48.800 INFO [stdout] 
Nov 17 18:53:48.800 INFO [stdout] running 0 tests
Nov 17 18:53:48.800 INFO [stdout] 
Nov 17 18:53:48.800 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:53:48.800 INFO [stdout] 
Nov 17 18:53:48.803 INFO [stderr]      Running /target/debug/deps/report-80a705be768539f0
Nov 17 18:53:48.806 INFO [stdout] 
Nov 17 18:53:48.807 INFO [stdout] running 5 tests
Nov 17 18:53:48.819 INFO [stdout] test initialize_valid ... ok
Nov 17 18:53:48.847 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Nov 17 18:53:48.855 INFO [stderr] TRACE 2018-11-17T18:53:48Z: mio::poll: registering with poller
Nov 17 18:53:48.856 INFO [stderr] TRACE 2018-11-17T18:53:48Z: tokio_threadpool::builder: build; num-workers=8
Nov 17 18:53:48.856 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: tokio_core::reactor: consuming notification queue
Nov 17 18:53:48.856 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: tokio_core::reactor: loop poll - 60.649µs
Nov 17 18:53:48.856 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154004, tv_nsec: 729435791 }
Nov 17 18:53:48.857 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: tokio_core::reactor: loop process, 43.759µs
Nov 17 18:53:48.867 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: tokio_reactor::background: starting background reactor
Nov 17 18:53:48.867 INFO [stderr] DEBUG 2018-11-17T18:53:48Z: vigil_reporter: Vigil Reporter: Now running
Nov 17 18:53:48.951 INFO [stdout] test initialize_invalid_probe_id ... ok
Nov 17 18:53:48.952 INFO [stdout] test initialize_invalid_replica_id ... ok
Nov 17 18:53:48.952 INFO [stdout] test initialize_invalid_node_id ... ok
Nov 17 18:53:58.863 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: vigil_reporter: Vigil Reporter: Will dispatch request
Nov 17 18:53:58.864 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: 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.47, ram: 0.1252622 } }
Nov 17 18:53:58.864 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop poll - 10.01625097s
Nov 17 18:53:58.865 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154014, tv_nsec: 745753552 }
Nov 17 18:53:58.865 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop process, 1.062383ms
Nov 17 18:53:58.867 INFO [stderr] TRACE 2018-11-17T18:53:58Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Nov 17 18:53:58.868 INFO [stderr] TRACE 2018-11-17T18:53:58Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Nov 17 18:53:58.868 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop poll - 1.255883ms
Nov 17 18:53:58.869 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154014, tv_nsec: 749585434 }
Nov 17 18:53:58.869 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop process, 921.755µs
Nov 17 18:53:58.870 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Nov 17 18:53:58.871 INFO [stderr] ERROR 2018-11-17T18:53:58Z: 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:58.872 INFO [stderr]  WARN 2018-11-17T18:53:58Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Nov 17 18:53:58.872 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop poll - 2.803977ms
Nov 17 18:53:58.873 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop time - Instant { tv_sec: 4154014, tv_nsec: 753779219 }
Nov 17 18:53:58.873 INFO [stderr] DEBUG 2018-11-17T18:53:58Z: tokio_core::reactor: loop process, 1.012438ms
Nov 17 18:54:03.861 INFO [stdout] test run_and_end_valid ... ok
Nov 17 18:54:03.861 INFO [stdout] 
Nov 17 18:54:03.861 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:54:03.861 INFO [stdout] 
Nov 17 18:54:03.864 INFO [stderr]    Doc-tests vigil_reporter
Nov 17 18:54:08.558 INFO [stdout] 
Nov 17 18:54:08.558 INFO [stdout] running 0 tests
Nov 17 18:54:08.558 INFO [stdout] 
Nov 17 18:54:08.558 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:54:08.558 INFO [stdout] 
Nov 17 18:54:08.567 INFO [stderr] su: No module specific data is present
Nov 17 18:54:09.167 INFO running `"docker" "inspect" "f072a0110ce7b118120b905dab5e820355817fd05f61f3c8e1e710d70e028c02"`
Nov 17 18:54:09.389 INFO running `"docker" "rm" "-f" "f072a0110ce7b118120b905dab5e820355817fd05f61f3c8e1e710d70e028c02"`
Nov 17 18:54:09.585 INFO [stdout] f072a0110ce7b118120b905dab5e820355817fd05f61f3c8e1e710d70e028c02