Oct 12 02:19:08.176 INFO testing crisp-status-reporter-1.1.2 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 12 02:19:08.176 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-6/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-6/rustdoc-test-static-cling/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 12 02:19:08.467 INFO blam! be76c07658f592a97c9a300fea93650b6d635050de9f9b18b00c9ec7c40ff755
Oct 12 02:19:08.469 INFO running `"docker" "start" "-a" "be76c07658f592a97c9a300fea93650b6d635050de9f9b18b00c9ec7c40ff755"`
Oct 12 02:19:09.233 INFO kablam! usermod: no changes
Oct 12 02:19:09.495 INFO kablam!    Compiling sys-info v0.5.6
Oct 12 02:19:11.294 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 12 02:19:21.633 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 12.38s
Oct 12 02:19:21.646 INFO kablam! su: No module specific data is present
Oct 12 02:19:21.956 INFO running `"docker" "rm" "-f" "be76c07658f592a97c9a300fea93650b6d635050de9f9b18b00c9ec7c40ff755"`
Oct 12 02:19:22.061 INFO blam! be76c07658f592a97c9a300fea93650b6d635050de9f9b18b00c9ec7c40ff755
Oct 12 02:19:22.063 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-6/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-6/rustdoc-test-static-cling/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 12 02:19:22.236 INFO blam! f0ca1e978d72c440467d1591e2a52a91ebf4bc57b7499e6a182c58d0d227deb0
Oct 12 02:19:22.238 INFO running `"docker" "start" "-a" "f0ca1e978d72c440467d1591e2a52a91ebf4bc57b7499e6a182c58d0d227deb0"`
Oct 12 02:19:22.620 INFO kablam! usermod: no changes
Oct 12 02:19:22.815 INFO kablam!    Compiling crisp-status-reporter v1.1.2 (/source)
Oct 12 02:19:37.870 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 15.23s
Oct 12 02:19:37.883 INFO kablam! su: No module specific data is present
Oct 12 02:19:38.129 INFO running `"docker" "rm" "-f" "f0ca1e978d72c440467d1591e2a52a91ebf4bc57b7499e6a182c58d0d227deb0"`
Oct 12 02:19:38.217 INFO blam! f0ca1e978d72c440467d1591e2a52a91ebf4bc57b7499e6a182c58d0d227deb0
Oct 12 02:19:38.218 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-6/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-6/rustdoc-test-static-cling/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 12 02:19:38.387 INFO blam! ebf08f931f879d6963a463e641551d21bebccb7a5a5a340676da1c2182465e41
Oct 12 02:19:38.389 INFO running `"docker" "start" "-a" "ebf08f931f879d6963a463e641551d21bebccb7a5a5a340676da1c2182465e41"`
Oct 12 02:19:38.787 INFO kablam! usermod: no changes
Oct 12 02:19:38.961 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Oct 12 02:19:38.981 INFO kablam!      Running /target/debug/deps/crisp_status_reporter-602e1121eb2b2cf6
Oct 12 02:19:38.984 INFO blam! 
Oct 12 02:19:38.984 INFO blam! running 0 tests
Oct 12 02:19:38.984 INFO blam! 
Oct 12 02:19:38.984 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:19:38.984 INFO blam! 
Oct 12 02:19:38.985 INFO kablam!      Running /target/debug/deps/report-9f5273572851955e
Oct 12 02:19:38.989 INFO blam! 
Oct 12 02:19:38.989 INFO blam! running 5 tests
Oct 12 02:19:38.990 INFO blam! test initialize_valid ... ok
Oct 12 02:19:38.991 INFO kablam! DEBUG 2018-10-12T02:19:38Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Oct 12 02:19:39.003 INFO kablam! TRACE 2018-10-12T02:19:39Z: mio::poll: registering with poller
Oct 12 02:19:39.003 INFO kablam! TRACE 2018-10-12T02:19:39Z: tokio_threadpool::builder: build; num-workers=8
Oct 12 02:19:39.003 INFO kablam! DEBUG 2018-10-12T02:19:39Z: tokio_reactor::background: starting background reactor
Oct 12 02:19:39.005 INFO kablam! DEBUG 2018-10-12T02:19:39Z: tokio_core::reactor: consuming notification queue
Oct 12 02:19:39.005 INFO kablam! DEBUG 2018-10-12T02:19:39Z: tokio_core::reactor: loop poll - 51.575µs
Oct 12 02:19:39.005 INFO kablam! DEBUG 2018-10-12T02:19:39Z: tokio_core::reactor: loop time - Instant { tv_sec: 983836, tv_nsec: 337187752 }
Oct 12 02:19:39.005 INFO kablam! DEBUG 2018-10-12T02:19:39Z: tokio_core::reactor: loop process, 54.685µs
Oct 12 02:19:39.005 INFO kablam! DEBUG 2018-10-12T02:19:39Z: crisp_status_reporter: Crisp Status Reporter: Now running
Oct 12 02:19:39.059 INFO blam! test initialize_invalid_replica_id ... ok
Oct 12 02:19:39.059 INFO blam! test initialize_invalid_node_id ... ok
Oct 12 02:19:39.059 INFO blam! test initialize_invalid_service_id ... ok
Oct 12 02:19:49.006 INFO kablam! DEBUG 2018-10-12T02:19:49Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Oct 12 02:19:49.006 INFO kablam! DEBUG 2018-10-12T02:19:49Z: crisp_status_reporter: Crisp Status Reporter: Will send request to URL: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/ with payload: ReportPayload { replica_id: "192.168.1.10", interval: 30, load: ReportPayloadLoad { cpu: 3.6725, ram: 0.15553373 } }
Oct 12 02:19:49.007 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop poll - 10.001409112s
Oct 12 02:19:49.007 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop time - Instant { tv_sec: 983846, tv_nsec: 338672455 }
Oct 12 02:19:49.008 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop process, 841.436µs
Oct 12 02:19:49.008 INFO kablam! TRACE 2018-10-12T02:19:49Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Oct 12 02:19:49.008 INFO kablam! TRACE 2018-10-12T02:19:49Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Oct 12 02:19:49.009 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop poll - 641.188µs
Oct 12 02:19:49.009 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop time - Instant { tv_sec: 983846, tv_nsec: 340610595 }
Oct 12 02:19:49.009 INFO kablam! DEBUG 2018-10-12T02:19:49Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Oct 12 02:19:49.009 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop process, 599.343µs
Oct 12 02:19:49.011 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop poll - 1.742709ms
Oct 12 02:19:49.011 INFO kablam! ERROR 2018-10-12T02:19:49Z: crisp_status_reporter: Crisp Status Reporter: Failed dispatching request: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/: failed to lookup address information: Temporary failure in name resolution
Oct 12 02:19:49.011 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop time - Instant { tv_sec: 983846, tv_nsec: 343200821 }
Oct 12 02:19:49.011 INFO kablam! DEBUG 2018-10-12T02:19:49Z: tokio_core::reactor: loop process, 55.31µs
Oct 12 02:19:49.011 INFO kablam!  WARN 2018-10-12T02:19:49Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Oct 12 02:19:54.005 INFO blam! test run_and_end_valid ... ok
Oct 12 02:19:54.006 INFO blam! 
Oct 12 02:19:54.006 INFO blam! test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:19:54.006 INFO blam! 
Oct 12 02:19:54.010 INFO kablam!    Doc-tests crisp_status_reporter
Oct 12 02:20:08.889 INFO blam! 
Oct 12 02:20:08.889 INFO blam! running 0 tests
Oct 12 02:20:08.889 INFO blam! 
Oct 12 02:20:08.889 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 02:20:08.889 INFO blam! 
Oct 12 02:20:08.901 INFO kablam! su: No module specific data is present
Oct 12 02:20:09.184 INFO running `"docker" "rm" "-f" "ebf08f931f879d6963a463e641551d21bebccb7a5a5a340676da1c2182465e41"`
Oct 12 02:20:09.312 INFO blam! ebf08f931f879d6963a463e641551d21bebccb7a5a5a340676da1c2182465e41