Oct 15 14:38:19.504 INFO testing vigil-reporter-1.0.3 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 15 14:38:19.504 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 14:38:19.912 INFO blam! 8016b7ddae1c398235588ffb34cc5c7941b28cff58e7cf7d8e2ae0adba701a7c
Oct 15 14:38:19.914 INFO running `"docker" "start" "-a" "8016b7ddae1c398235588ffb34cc5c7941b28cff58e7cf7d8e2ae0adba701a7c"`
Oct 15 14:38:21.415 INFO kablam! usermod: no changes
Oct 15 14:38:21.807 INFO kablam!    Compiling sys-info v0.5.6
Oct 15 14:38:21.815 INFO kablam!    Compiling tokio-core v0.1.17
Oct 15 14:38:29.407 INFO kablam!    Compiling hyper v0.11.27
Oct 15 14:38:29.407 INFO kablam!    Compiling tokio-tls v0.1.4
Oct 15 14:38:41.604 INFO kablam!    Compiling hyper-tls v0.1.4
Oct 15 14:38:45.295 INFO kablam!    Compiling reqwest v0.8.8
Oct 15 14:38:56.883 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Oct 15 14:39:01.987 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 40.53s
Oct 15 14:39:02.014 INFO kablam! su: No module specific data is present
Oct 15 14:39:02.582 INFO running `"docker" "rm" "-f" "8016b7ddae1c398235588ffb34cc5c7941b28cff58e7cf7d8e2ae0adba701a7c"`
Oct 15 14:39:02.809 INFO blam! 8016b7ddae1c398235588ffb34cc5c7941b28cff58e7cf7d8e2ae0adba701a7c
Oct 15 14:39:02.825 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 14:39:03.215 INFO blam! 4cd8920b6a02575cd8b8cc0cf2f21725d9d217786e466b7d74a3f1377635b718
Oct 15 14:39:03.227 INFO running `"docker" "start" "-a" "4cd8920b6a02575cd8b8cc0cf2f21725d9d217786e466b7d74a3f1377635b718"`
Oct 15 14:39:04.511 INFO kablam! usermod: no changes
Oct 15 14:39:04.774 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Oct 15 14:39:17.353 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 12.82s
Oct 15 14:39:17.366 INFO kablam! su: No module specific data is present
Oct 15 14:39:17.967 INFO running `"docker" "rm" "-f" "4cd8920b6a02575cd8b8cc0cf2f21725d9d217786e466b7d74a3f1377635b718"`
Oct 15 14:39:18.278 INFO blam! 4cd8920b6a02575cd8b8cc0cf2f21725d9d217786e466b7d74a3f1377635b718
Oct 15 14:39:18.280 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 14:39:18.787 INFO blam! 4791763bdb540df94ded021be2b42e1f2c0b7c947af0338fac80205dc758976a
Oct 15 14:39:18.793 INFO running `"docker" "start" "-a" "4791763bdb540df94ded021be2b42e1f2c0b7c947af0338fac80205dc758976a"`
Oct 15 14:39:20.031 INFO kablam! usermod: no changes
Oct 15 14:39:20.307 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Oct 15 14:39:20.331 INFO kablam!      Running /target/debug/deps/vigil_reporter-c722ede7d92362d5
Oct 15 14:39:20.332 INFO blam! 
Oct 15 14:39:20.332 INFO blam! running 0 tests
Oct 15 14:39:20.332 INFO blam! 
Oct 15 14:39:20.332 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:20.332 INFO blam! 
Oct 15 14:39:20.339 INFO kablam!      Running /target/debug/deps/report-02841bdf83b130b0
Oct 15 14:39:20.344 INFO blam! 
Oct 15 14:39:20.344 INFO blam! running 5 tests
Oct 15 14:39:20.355 INFO kablam! DEBUG 2018-10-15T14:39:20Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Oct 15 14:39:20.355 INFO blam! test initialize_valid ... ok
Oct 15 14:39:20.375 INFO kablam! TRACE 2018-10-15T14:39:20Z: mio::poll: registering with poller
Oct 15 14:39:20.377 INFO kablam! TRACE 2018-10-15T14:39:20Z: tokio_threadpool::builder: build; num-workers=8
Oct 15 14:39:20.377 INFO kablam! DEBUG 2018-10-15T14:39:20Z: tokio_core::reactor: consuming notification queue
Oct 15 14:39:20.377 INFO kablam! DEBUG 2018-10-15T14:39:20Z: tokio_core::reactor: loop poll - 41.325µs
Oct 15 14:39:20.377 INFO kablam! DEBUG 2018-10-15T14:39:20Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287536, tv_nsec: 258130102 }
Oct 15 14:39:20.377 INFO kablam! DEBUG 2018-10-15T14:39:20Z: tokio_core::reactor: loop process, 46.327µs
Oct 15 14:39:20.407 INFO kablam! DEBUG 2018-10-15T14:39:20Z: tokio_reactor::background: starting background reactor
Oct 15 14:39:20.407 INFO kablam! DEBUG 2018-10-15T14:39:20Z: vigil_reporter: Vigil Reporter: Now running
Oct 15 14:39:20.535 INFO blam! test initialize_invalid_node_id ... ok
Oct 15 14:39:20.535 INFO blam! test initialize_invalid_replica_id ... ok
Oct 15 14:39:20.536 INFO blam! test initialize_invalid_probe_id ... ok
Oct 15 14:39:30.408 INFO kablam! DEBUG 2018-10-15T14:39:30Z: vigil_reporter: Vigil Reporter: Will dispatch request
Oct 15 14:39:30.408 INFO kablam! DEBUG 2018-10-15T14:39:30Z: 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.6325, ram: 0.15153277 } }
Oct 15 14:39:30.408 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop poll - 10.031249515s
Oct 15 14:39:30.408 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287546, tv_nsec: 289445187 }
Oct 15 14:39:30.408 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop process, 49.571µs
Oct 15 14:39:30.415 INFO kablam! TRACE 2018-10-15T14:39:30Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Oct 15 14:39:30.415 INFO kablam! TRACE 2018-10-15T14:39:30Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop poll - 227.906µs
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287546, tv_nsec: 291523404 }
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop process, 39.223µs
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop poll - 1.437037ms
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287546, tv_nsec: 293022292 }
Oct 15 14:39:30.415 INFO kablam! DEBUG 2018-10-15T14:39:30Z: tokio_core::reactor: loop process, 49.021µs
Oct 15 14:39:30.415 INFO kablam! ERROR 2018-10-15T14:39:30Z: 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
Oct 15 14:39:30.415 INFO kablam!  WARN 2018-10-15T14:39:30Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Oct 15 14:39:35.407 INFO blam! test run_and_end_valid ... ok
Oct 15 14:39:35.407 INFO blam! 
Oct 15 14:39:35.407 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:35.407 INFO blam! 
Oct 15 14:39:35.409 INFO kablam!    Doc-tests vigil_reporter
Oct 15 14:39:43.181 INFO blam! 
Oct 15 14:39:43.181 INFO blam! running 0 tests
Oct 15 14:39:43.181 INFO blam! 
Oct 15 14:39:43.181 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:43.181 INFO blam! 
Oct 15 14:39:43.192 INFO kablam! su: No module specific data is present
Oct 15 14:39:44.384 INFO running `"docker" "rm" "-f" "4791763bdb540df94ded021be2b42e1f2c0b7c947af0338fac80205dc758976a"`
Oct 15 14:39:44.699 INFO blam! 4791763bdb540df94ded021be2b42e1f2c0b7c947af0338fac80205dc758976a