Dec 06 01:28:08.655 INFO testing dmexe/failsafe-rs against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 01:28:08.655 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 01:28:08.871 INFO [stdout] db8182bec7452990bc9073bd1c85d86e5b096de7a7e9eecfda626873f6c315f4
Dec 06 01:28:08.873 INFO running `"docker" "start" "-a" "db8182bec7452990bc9073bd1c85d86e5b096de7a7e9eecfda626873f6c315f4"`
Dec 06 01:28:09.483 INFO [stderr] usermod: no changes
Dec 06 01:28:09.578 INFO [stderr]    Compiling spin v0.4.9
Dec 06 01:28:09.732 INFO [stderr]    Compiling failsafe v0.3.0 (/source)
Dec 06 01:28:10.334 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.82s
Dec 06 01:28:10.341 INFO [stderr] su: No module specific data is present
Dec 06 01:28:10.602 INFO running `"docker" "inspect" "db8182bec7452990bc9073bd1c85d86e5b096de7a7e9eecfda626873f6c315f4"`
Dec 06 01:28:10.673 INFO running `"docker" "rm" "-f" "db8182bec7452990bc9073bd1c85d86e5b096de7a7e9eecfda626873f6c315f4"`
Dec 06 01:28:10.777 INFO [stdout] db8182bec7452990bc9073bd1c85d86e5b096de7a7e9eecfda626873f6c315f4
Dec 06 01:28:10.783 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 01:28:10.981 INFO [stdout] e4f715cf62b368f5fb6e766829766413f931ff2e7f3ffff2fccdfd8f78853e7a
Dec 06 01:28:10.983 INFO running `"docker" "start" "-a" "e4f715cf62b368f5fb6e766829766413f931ff2e7f3ffff2fccdfd8f78853e7a"`
Dec 06 01:28:11.352 INFO [stderr] usermod: no changes
Dec 06 01:28:11.452 INFO [stderr]    Compiling mio v0.6.15
Dec 06 01:28:11.453 INFO [stderr]    Compiling tokio-timer v0.2.6
Dec 06 01:28:11.453 INFO [stderr]    Compiling tokio-io v0.1.8
Dec 06 01:28:11.462 INFO [stderr]    Compiling tokio-threadpool v0.1.8
Dec 06 01:28:13.048 INFO [stderr]    Compiling tokio-codec v0.1.0
Dec 06 01:28:14.704 INFO [stderr]    Compiling tokio-fs v0.1.3
Dec 06 01:28:14.930 INFO [stderr]    Compiling tokio-reactor v0.1.5
Dec 06 01:28:14.930 INFO [stderr]    Compiling mio-uds v0.6.6
Dec 06 01:28:17.435 INFO [stderr]    Compiling tokio-udp v0.1.2
Dec 06 01:28:17.437 INFO [stderr]    Compiling tokio-uds v0.2.1
Dec 06 01:28:17.448 INFO [stderr]    Compiling tokio-tcp v0.1.1
Dec 06 01:28:19.411 INFO [stderr]    Compiling tokio v0.1.11
Dec 06 01:28:21.426 INFO [stderr]    Compiling failsafe v0.3.0 (/source)
Dec 06 01:28:25.200 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.83s
Dec 06 01:28:25.207 INFO [stderr] su: No module specific data is present
Dec 06 01:28:25.804 INFO running `"docker" "inspect" "e4f715cf62b368f5fb6e766829766413f931ff2e7f3ffff2fccdfd8f78853e7a"`
Dec 06 01:28:26.026 INFO running `"docker" "rm" "-f" "e4f715cf62b368f5fb6e766829766413f931ff2e7f3ffff2fccdfd8f78853e7a"`
Dec 06 01:28:26.245 INFO [stdout] e4f715cf62b368f5fb6e766829766413f931ff2e7f3ffff2fccdfd8f78853e7a
Dec 06 01:28:26.247 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 01:28:26.520 INFO [stdout] e18da35c4413795f85ec6dfb5c1e6db477a6cd7357ab44c1d95c42f3f026b55a
Dec 06 01:28:26.526 INFO running `"docker" "start" "-a" "e18da35c4413795f85ec6dfb5c1e6db477a6cd7357ab44c1d95c42f3f026b55a"`
Dec 06 01:28:27.069 INFO [stderr] usermod: no changes
Dec 06 01:28:27.169 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 06 01:28:27.172 INFO [stderr]      Running /target/debug/deps/failsafe-dcc01d7a729f132f
Dec 06 01:28:27.175 INFO [stdout] 
Dec 06 01:28:27.175 INFO [stdout] running 30 tests
Dec 06 01:28:27.180 INFO [stdout] test backoff::tests::constant_growth ... ok
Dec 06 01:28:27.181 INFO [stdout] test backoff::tests::equal_jittered_growth ... ok
Dec 06 01:28:27.184 INFO [stdout] test circuit_breaker::tests::call_err ... ok
Dec 06 01:28:27.184 INFO [stdout] test circuit_breaker::tests::call_ok ... ok
Dec 06 01:28:27.185 INFO [stdout] test circuit_breaker::tests::call_with ... ok
Dec 06 01:28:27.185 INFO [stdout] test backoff::tests::full_jittered_growth ... ok
Dec 06 01:28:27.185 INFO [stdout] test backoff::tests::exponential_growth ... ok
Dec 06 01:28:27.185 INFO [stdout] test failure_policy::tests::consecutive_failures::reset_to_zero_on_success ... ok
Dec 06 01:28:27.185 INFO [stdout] test failure_policy::tests::consecutive_failures::fail_on_nth_attempt ... ok
Dec 06 01:28:27.186 INFO [stdout] test failure_policy::tests::consecutive_failures::iterates_over_backoff ... ok
Dec 06 01:28:27.186 INFO [stdout] test failure_policy::tests::or_else::compose_policies ... ok
Dec 06 01:28:27.187 INFO [stdout] test failure_policy::tests::consecutive_failures::reset_to_zero_on_revived ... ok
Dec 06 01:28:27.187 INFO [stdout] test ema::tests::compute ... ok
Dec 06 01:28:27.187 INFO [stdout] test ema::tests::updates_are_time_invariant ... ok
Dec 06 01:28:27.187 INFO [stdout] test failure_policy::tests::success_rate_over_time_window::fail_when_success_rate_not_met ... ok
Dec 06 01:28:27.188 INFO [stdout] test failure_policy::tests::success_rate_over_time_window::fractional_success_rate ... ok
Dec 06 01:28:27.188 INFO [stdout] test ema::tests::reset ... ok
Dec 06 01:28:27.189 INFO [stdout] test failure_policy::tests::success_rate_over_time_window::revived_resets_failures ... ok
Dec 06 01:28:27.194 INFO [stdout] test failure_policy::tests::success_rate_over_time_window::respects_rps_threshold ... ok
Dec 06 01:28:27.194 INFO [stdout] test windowed_adder::tests::negative_sums ... ok
Dec 06 01:28:27.199 INFO [stdout] test state_machine::tests::state_machine ... ok
Dec 06 01:28:27.199 INFO [stdout] test windowed_adder::tests::sliding_over_small_window ... ok
Dec 06 01:28:27.199 INFO [stdout] test windowed_adder::tests::sum_when_time_stands_still ... ok
Dec 06 01:28:27.199 INFO [stdout] test failure_predicate::tests::use_func_as_failure_predicate ... ok
Dec 06 01:28:27.200 INFO [stdout] test windowed_adder::tests::sliding_window_when_slices_are_skipped ... ok
Dec 06 01:28:27.202 INFO [stdout] test windowed_adder::tests::sliding_over_large_window ... ok
Dec 06 01:28:27.243 INFO [stdout] test futures::tests::call_with ... ok
Dec 06 01:28:27.260 INFO [stdout] test futures::tests::call_err ... ok
Dec 06 01:28:27.296 INFO [stdout] test ema::tests::non_monotonic_timestamp ... ok
Dec 06 01:28:27.305 INFO [stdout] test futures::tests::call_ok ... ok
Dec 06 01:28:27.305 INFO [stdout] 
Dec 06 01:28:27.306 INFO [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:28:27.306 INFO [stdout] 
Dec 06 01:28:27.315 INFO [stderr]    Doc-tests failsafe
Dec 06 01:28:27.521 INFO [stdout] 
Dec 06 01:28:27.521 INFO [stdout] running 3 tests
Dec 06 01:28:28.141 INFO [stdout] test src/lib.rs -  (line 52) ... ok
Dec 06 01:28:28.874 INFO [stdout] test src/lib.rs -  (line 15) ... ok
Dec 06 01:28:28.991 INFO [stdout] test src/futures/mod.rs - futures (line 7) ... ok
Dec 06 01:28:28.991 INFO [stdout] 
Dec 06 01:28:28.992 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:28:28.992 INFO [stdout] 
Dec 06 01:28:29.011 INFO [stderr] su: No module specific data is present
Dec 06 01:28:29.569 INFO running `"docker" "inspect" "e18da35c4413795f85ec6dfb5c1e6db477a6cd7357ab44c1d95c42f3f026b55a"`
Dec 06 01:28:29.671 INFO running `"docker" "rm" "-f" "e18da35c4413795f85ec6dfb5c1e6db477a6cd7357ab44c1d95c42f3f026b55a"`
Dec 06 01:28:29.774 INFO [stdout] e18da35c4413795f85ec6dfb5c1e6db477a6cd7357ab44c1d95c42f3f026b55a