Oct 15 14:38:20.621 INFO testing vigil-reporter-1.0.3 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 15 14:38:20.622 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:20.992 INFO blam! 5c822540da2516a89e35dc20a3c6b1d03747dc2f7baf0e3f10a8da9c8b02ca16
Oct 15 14:38:20.995 INFO running `"docker" "start" "-a" "5c822540da2516a89e35dc20a3c6b1d03747dc2f7baf0e3f10a8da9c8b02ca16"`
Oct 15 14:38:23.271 INFO kablam! usermod: no changes
Oct 15 14:38:23.751 INFO kablam!    Compiling sys-info v0.5.6
Oct 15 14:38:23.767 INFO kablam!    Compiling tokio-reactor v0.1.6
Oct 15 14:38:29.351 INFO kablam!    Compiling tokio-tcp v0.1.2
Oct 15 14:38:29.351 INFO kablam!    Compiling tokio-udp v0.1.2
Oct 15 14:38:29.351 INFO kablam!    Compiling tokio-uds v0.2.2
Oct 15 14:38:33.563 INFO kablam!    Compiling tokio v0.1.11
Oct 15 14:38:37.331 INFO kablam!    Compiling tokio-core v0.1.17
Oct 15 14:38:41.547 INFO kablam!    Compiling hyper v0.11.27
Oct 15 14:38:41.548 INFO kablam!    Compiling tokio-tls v0.1.4
Oct 15 14:38:54.291 INFO kablam!    Compiling hyper-tls v0.1.4
Oct 15 14:38:57.900 INFO kablam!    Compiling reqwest v0.8.8
Oct 15 14:39:09.079 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Oct 15 14:39:14.352 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 51.03s
Oct 15 14:39:14.372 INFO kablam! su: No module specific data is present
Oct 15 14:39:15.152 INFO running `"docker" "rm" "-f" "5c822540da2516a89e35dc20a3c6b1d03747dc2f7baf0e3f10a8da9c8b02ca16"`
Oct 15 14:39:15.399 INFO blam! 5c822540da2516a89e35dc20a3c6b1d03747dc2f7baf0e3f10a8da9c8b02ca16
Oct 15 14:39:15.407 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:15.821 INFO blam! 214dfc55753fdbcf40797776e0c29ec5cf0e8ca5fcb32fd2987cea7f2cae48fc
Oct 15 14:39:15.824 INFO running `"docker" "start" "-a" "214dfc55753fdbcf40797776e0c29ec5cf0e8ca5fcb32fd2987cea7f2cae48fc"`
Oct 15 14:39:16.724 INFO kablam! usermod: no changes
Oct 15 14:39:17.018 INFO kablam!    Compiling vigil-reporter v1.0.3 (/source)
Oct 15 14:39:29.723 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 12.97s
Oct 15 14:39:29.743 INFO kablam! su: No module specific data is present
Oct 15 14:39:30.703 INFO running `"docker" "rm" "-f" "214dfc55753fdbcf40797776e0c29ec5cf0e8ca5fcb32fd2987cea7f2cae48fc"`
Oct 15 14:39:31.003 INFO blam! 214dfc55753fdbcf40797776e0c29ec5cf0e8ca5fcb32fd2987cea7f2cae48fc
Oct 15 14:39:31.015 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:31.480 INFO blam! 9d8afbd374c0e6fec142ac20b3cbe6072847cd806972d13e5fbc0f8116435b4d
Oct 15 14:39:31.503 INFO running `"docker" "start" "-a" "9d8afbd374c0e6fec142ac20b3cbe6072847cd806972d13e5fbc0f8116435b4d"`
Oct 15 14:39:32.743 INFO kablam! usermod: no changes
Oct 15 14:39:32.998 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Oct 15 14:39:33.025 INFO kablam!      Running /target/debug/deps/vigil_reporter-987adce786c97a23
Oct 15 14:39:33.026 INFO blam! 
Oct 15 14:39:33.026 INFO blam! running 0 tests
Oct 15 14:39:33.026 INFO blam! 
Oct 15 14:39:33.027 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:33.027 INFO blam! 
Oct 15 14:39:33.035 INFO kablam!      Running /target/debug/deps/report-db7f110b95eb5997
Oct 15 14:39:33.043 INFO blam! 
Oct 15 14:39:33.043 INFO blam! running 5 tests
Oct 15 14:39:33.055 INFO kablam! DEBUG 2018-10-15T14:39:33Z: vigil_reporter: Vigil Reporter: Will run using URL: http://status.example.com.local
Oct 15 14:39:33.063 INFO blam! test initialize_valid ... ok
Oct 15 14:39:33.071 INFO kablam! TRACE 2018-10-15T14:39:33Z: mio::poll: registering with poller
Oct 15 14:39:33.071 INFO kablam! TRACE 2018-10-15T14:39:33Z: tokio_threadpool::builder: build; num-workers=8
Oct 15 14:39:33.072 INFO kablam! DEBUG 2018-10-15T14:39:33Z: tokio_core::reactor: consuming notification queue
Oct 15 14:39:33.072 INFO kablam! DEBUG 2018-10-15T14:39:33Z: tokio_core::reactor: loop poll - 41.432µs
Oct 15 14:39:33.072 INFO kablam! DEBUG 2018-10-15T14:39:33Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287548, tv_nsec: 941174759 }
Oct 15 14:39:33.072 INFO kablam! DEBUG 2018-10-15T14:39:33Z: tokio_core::reactor: loop process, 45.928µs
Oct 15 14:39:33.073 INFO kablam! DEBUG 2018-10-15T14:39:33Z: tokio_reactor::background: starting background reactor
Oct 15 14:39:33.073 INFO kablam! DEBUG 2018-10-15T14:39:33Z: vigil_reporter: Vigil Reporter: Now running
Oct 15 14:39:33.199 INFO blam! test initialize_invalid_node_id ... ok
Oct 15 14:39:33.207 INFO blam! test initialize_invalid_replica_id ... ok
Oct 15 14:39:33.207 INFO blam! test initialize_invalid_probe_id ... ok
Oct 15 14:39:43.063 INFO kablam! DEBUG 2018-10-15T14:39:43Z: vigil_reporter: Vigil Reporter: Will dispatch request
Oct 15 14:39:43.065 INFO kablam! DEBUG 2018-10-15T14:39:43Z: 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.5975, ram: 0.14229149 } }
Oct 15 14:39:43.066 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop poll - 10.006095551s
Oct 15 14:39:43.067 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287558, tv_nsec: 947335614 }
Oct 15 14:39:43.067 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop process, 1.132119ms
Oct 15 14:39:43.068 INFO kablam! TRACE 2018-10-15T14:39:43Z: hyper::client::pool: park; waiting for idle connection: "http://status.example.com.local"
Oct 15 14:39:43.069 INFO kablam! TRACE 2018-10-15T14:39:43Z: hyper::client::connect: Http::connect("http://status.example.com.local/reporter/relay/socket-client/")
Oct 15 14:39:43.069 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop poll - 1.406825ms
Oct 15 14:39:43.070 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287558, tv_nsec: 950656957 }
Oct 15 14:39:43.070 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop process, 1.029779ms
Oct 15 14:39:43.071 INFO kablam! DEBUG 2018-10-15T14:39:43Z: hyper::client::dns: resolving host="status.example.com.local", port=80
Oct 15 14:39:43.073 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop poll - 2.54587ms
Oct 15 14:39:43.074 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop time - Instant { tv_sec: 1287558, tv_nsec: 954786706 }
Oct 15 14:39:43.074 INFO kablam! DEBUG 2018-10-15T14:39:43Z: tokio_core::reactor: loop process, 1.05865ms
Oct 15 14:39:43.075 INFO kablam! ERROR 2018-10-15T14:39:43Z: 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:43.076 INFO kablam!  WARN 2018-10-15T14:39:43Z: vigil_reporter: Vigil Reporter: Last report failed, trying again sooner than usual
Oct 15 14:39:48.063 INFO blam! test run_and_end_valid ... ok
Oct 15 14:39:48.063 INFO blam! 
Oct 15 14:39:48.063 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:48.063 INFO blam! 
Oct 15 14:39:48.079 INFO kablam!    Doc-tests vigil_reporter
Oct 15 14:39:56.147 INFO blam! 
Oct 15 14:39:56.149 INFO blam! running 0 tests
Oct 15 14:39:56.149 INFO blam! 
Oct 15 14:39:56.150 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 14:39:56.150 INFO blam! 
Oct 15 14:39:56.167 INFO kablam! su: No module specific data is present
Oct 15 14:39:56.886 INFO running `"docker" "rm" "-f" "9d8afbd374c0e6fec142ac20b3cbe6072847cd806972d13e5fbc0f8116435b4d"`
Oct 15 14:39:57.251 INFO blam! 9d8afbd374c0e6fec142ac20b3cbe6072847cd806972d13e5fbc0f8116435b4d