Nov 01 13:32:33.443 INFO testing casey/tokio-by-hand against 1.30.0 for beta-1.31-1
Nov 01 13:32:33.444 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/1.30.0:/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 +1.30.0 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 13:32:33.903 INFO blam! 2c96706fb66ebf9ab4f56be151b21147d36ebb40b005237ddc7b5766bc4abdfd
Nov 01 13:32:33.911 INFO running `"docker" "start" "-a" "2c96706fb66ebf9ab4f56be151b21147d36ebb40b005237ddc7b5766bc4abdfd"`
Nov 01 13:32:35.242 INFO kablam! usermod: no changes
Nov 01 13:32:35.363 INFO kablam!    Compiling iovec v0.1.1
Nov 01 13:32:35.375 INFO kablam!    Compiling net2 v0.2.31
Nov 01 13:32:36.379 INFO kablam!    Compiling bytes v0.4.5
Nov 01 13:32:37.254 INFO kablam!    Compiling mio v0.6.10
Nov 01 13:32:39.216 INFO kablam!    Compiling tokio-io v0.1.3
Nov 01 13:32:42.099 INFO kablam!    Compiling tokio-core v0.1.10
Nov 01 13:32:45.415 INFO kablam!    Compiling tokio-by-hand v0.0.0 (/source)
Nov 01 13:32:47.058 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 11.78s
Nov 01 13:32:47.064 INFO kablam! su: No module specific data is present
Nov 01 13:32:47.877 INFO running `"docker" "rm" "-f" "2c96706fb66ebf9ab4f56be151b21147d36ebb40b005237ddc7b5766bc4abdfd"`
Nov 01 13:32:48.167 INFO blam! 2c96706fb66ebf9ab4f56be151b21147d36ebb40b005237ddc7b5766bc4abdfd
Nov 01 13:32:48.176 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 13:32:48.605 INFO blam! d43714b686cfb1c98f8831deb75c6416499f25aff69efc2a995e3dad99fe88b3
Nov 01 13:32:48.607 INFO running `"docker" "start" "-a" "d43714b686cfb1c98f8831deb75c6416499f25aff69efc2a995e3dad99fe88b3"`
Nov 01 13:32:50.311 INFO kablam! usermod: no changes
Nov 01 13:32:50.416 INFO kablam!    Compiling tokio-by-hand v0.0.0 (/source)
Nov 01 13:32:54.519 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 4.18s
Nov 01 13:32:54.524 INFO kablam! su: No module specific data is present
Nov 01 13:32:55.423 INFO running `"docker" "rm" "-f" "d43714b686cfb1c98f8831deb75c6416499f25aff69efc2a995e3dad99fe88b3"`
Nov 01 13:32:55.759 INFO blam! d43714b686cfb1c98f8831deb75c6416499f25aff69efc2a995e3dad99fe88b3
Nov 01 13:32:55.776 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 13:32:56.323 INFO blam! a80d118eb64cd87a76b86ad4f3a2dcb05218698368687002e619dbf5506617d3
Nov 01 13:32:56.326 INFO running `"docker" "start" "-a" "a80d118eb64cd87a76b86ad4f3a2dcb05218698368687002e619dbf5506617d3"`
Nov 01 13:32:57.583 INFO kablam! usermod: no changes
Nov 01 13:32:57.680 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Nov 01 13:32:57.683 INFO kablam!      Running /target/debug/deps/tokio_by_hand-e588dbf792648f27
Nov 01 13:32:57.691 INFO blam! 
Nov 01 13:32:57.691 INFO blam! running 29 tests
Nov 01 13:32:58.793 INFO blam! test extended::delayed::tests::producer_completes_in_about_one_second ... ok
Nov 01 13:32:58.807 INFO blam! test extended::delayed::tests::consumer_completes_in_about_one_second ... ok
Nov 01 13:32:58.970 INFO blam! test extended::instant::tests::producer_completes_quickly ... ok
Nov 01 13:32:59.119 INFO blam! test extended::instant::tests::consumer_completes_quickly ... ok
Nov 01 13:32:59.231 INFO blam! test extended::instant::tests::producer_returns_all_values ... ok
Nov 01 13:32:59.479 INFO blam! test extended::instant_series::tests::producer_returns_all_values ... ok
Nov 01 13:32:59.479 INFO blam! test extended::instant_series::tests::forward_to_consumer ... ok
Nov 01 13:33:00.131 INFO blam! test extended::delayed::tests::producer_returns_all_values ... ok
Nov 01 13:33:00.479 INFO blam! test extended::sleeper::tests::with_core ... ok
Nov 01 13:33:00.481 INFO blam! test extended::sleeper::tests::with_wait ... ok
Nov 01 13:33:01.487 INFO blam! test standard::delayed::tests::consumer_completes_in_about_one_second ... ok
Nov 01 13:33:01.487 INFO blam! test standard::delayed::tests::producer_completes_in_about_one_second ... ok
Nov 01 13:33:02.935 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_consume ... ok
Nov 01 13:33:03.051 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_produce ... ok
Nov 01 13:33:03.883 INFO blam! test standard::delayed::tests::producer_returns_all_values ... ok
Nov 01 13:33:03.921 INFO blam! test standard::instant::tests::consumer_completes_quickly ... ok
Nov 01 13:33:03.935 INFO blam! test standard::instant::tests::producer_completes_quickly ... ok
Nov 01 13:33:03.974 INFO blam! test standard::instant::tests::producer_returns_all_values ... ok
Nov 01 13:33:04.011 INFO blam! test standard::instant_series::tests::forward_to_consumer ... ok
Nov 01 13:33:04.015 INFO blam! test standard::instant_series::tests::producer_returns_all_values ... ok
Nov 01 13:33:04.315 INFO blam! test extended::delayed_series::tests::production_and_consumption_are_concurrent ... FAILED
Nov 01 13:33:04.315 INFO blam! test extended::buffered::tests::production_and_consumption_are_concurrent ... FAILED
Nov 01 13:33:05.015 INFO blam! test standard::sleeper::tests::with_core ... ok
Nov 01 13:33:05.331 INFO blam! test standard::sleeper::tests::with_wait ... ok
Nov 01 13:33:06.240 INFO blam! test standard::buffered::tests::production_and_consumption_are_concurrent ... ok
Nov 01 13:33:07.639 INFO blam! test standard::delayed_series::tests::production_and_consumption_are_concurrent ... ok
Nov 01 13:33:08.047 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_consume ... ok
Nov 01 13:33:08.072 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_produce ... ok
Nov 01 13:33:08.135 INFO blam! test extended::adapter::tests::basic ... ok
Nov 01 13:33:08.135 INFO blam! 
Nov 01 13:33:08.135 INFO blam! failures:
Nov 01 13:33:08.139 INFO blam! 
Nov 01 13:33:08.139 INFO blam! ---- extended::delayed_series::tests::production_and_consumption_are_concurrent stdout ----
Nov 01 13:33:08.139 INFO blam! thread 'extended::delayed_series::tests::production_and_consumption_are_concurrent' panicked at 'assertion failed: elapsed < Duration::new(6, 500000000)', src/extended/delayed_series.rs:130:5
Nov 01 13:33:08.139 INFO blam! stack backtrace:
Nov 01 13:33:08.139 INFO blam!    0:     0x5570bc6e9e9e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 01 13:33:08.139 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 13:33:08.139 INFO blam!    1:     0x5570bc6dce86 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 01 13:33:08.139 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 13:33:08.139 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 13:33:08.139 INFO blam!    2:     0x5570bc6db12d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:211
Nov 01 13:33:08.139 INFO blam!    3:     0x5570bc6dae3b - std::panicking::default_hook::h3a27b204a313406b
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:221
Nov 01 13:33:08.139 INFO blam!    4:     0x5570bc6db7dc - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:477
Nov 01 13:33:08.139 INFO blam!    5:     0x5570bc6c249f - std::panicking::begin_panic::h3e0289acc97881ec
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:411
Nov 01 13:33:08.139 INFO blam!    6:     0x5570bc61829e - tokio_by_hand::extended::delayed_series::tests::production_and_consumption_are_concurrent::h8f3082409d0b12f4
Nov 01 13:33:08.139 INFO blam!                                at src/extended/delayed_series.rs:130
Nov 01 13:33:08.139 INFO blam!    7:     0x5570bc6180a9 - tokio_by_hand::extended::delayed_series::tests::production_and_consumption_are_concurrent::{{closure}}::hf6f24c1ffca1f4c6
Nov 01 13:33:08.139 INFO blam!                                at src/extended/delayed_series.rs:121
Nov 01 13:33:08.139 INFO blam!    8:     0x5570bc636aed - core::ops::function::FnOnce::call_once::h1a8d4a1b9efc1d08
Nov 01 13:33:08.139 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 13:33:08.139 INFO blam!    9:     0x5570bc64232e - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 01 13:33:08.139 INFO blam!                                at libtest/lib.rs:1461
Nov 01 13:33:08.139 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 13:33:08.139 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 13:33:08.139 INFO blam!   10:     0x5570bc6f8ab9 - __rust_maybe_catch_panic
Nov 01 13:33:08.139 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 13:33:08.139 INFO blam!   11:     0x5570bc64dc3d - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:289
Nov 01 13:33:08.139 INFO blam!                                at libstd/panic.rs:392
Nov 01 13:33:08.139 INFO blam!                                at libtest/lib.rs:1416
Nov 01 13:33:08.139 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 13:33:08.139 INFO blam!   12:     0x5570bc666274 - std::panicking::try::do_call::h1f0578498122cc00
Nov 01 13:33:08.139 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 13:33:08.139 INFO blam!                                at libstd/panic.rs:313
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:310
Nov 01 13:33:08.139 INFO blam!   13:     0x5570bc6f8ab9 - __rust_maybe_catch_panic
Nov 01 13:33:08.139 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 13:33:08.139 INFO blam!   14:     0x5570bc656b46 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 01 13:33:08.139 INFO blam!                                at libstd/panicking.rs:289
Nov 01 13:33:08.140 INFO blam!                                at libstd/panic.rs:392
Nov 01 13:33:08.140 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 13:33:08.140 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 13:33:08.140 INFO blam!   15:     0x5570bc6eb7ba - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 01 13:33:08.140 INFO blam!                                at liballoc/boxed.rs:656
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 13:33:08.140 INFO blam!   16:     0x5570bc6dbf95 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 13:33:08.140 INFO blam!   17:     0x7f4cd63b76b9 - start_thread
Nov 01 13:33:08.140 INFO blam!   18:     0x7f4cd5ed741c - clone
Nov 01 13:33:08.140 INFO blam!   19:                0x0 - <unknown>
Nov 01 13:33:08.140 INFO blam! 
Nov 01 13:33:08.140 INFO blam! ---- extended::buffered::tests::production_and_consumption_are_concurrent stdout ----
Nov 01 13:33:08.140 INFO blam! thread 'extended::buffered::tests::production_and_consumption_are_concurrent' panicked at 'assertion failed: elapsed < Duration::new(6, 500000000)', src/extended/buffered.rs:93:5
Nov 01 13:33:08.140 INFO blam! stack backtrace:
Nov 01 13:33:08.140 INFO blam!    0:     0x5570bc6e9e9e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 13:33:08.140 INFO blam!    1:     0x5570bc6dce86 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 13:33:08.140 INFO blam!    2:     0x5570bc6db12d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:211
Nov 01 13:33:08.140 INFO blam!    3:     0x5570bc6dae3b - std::panicking::default_hook::h3a27b204a313406b
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:221
Nov 01 13:33:08.140 INFO blam!    4:     0x5570bc6db7dc - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:477
Nov 01 13:33:08.140 INFO blam!    5:     0x5570bc6c249f - std::panicking::begin_panic::h3e0289acc97881ec
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:411
Nov 01 13:33:08.140 INFO blam!    6:     0x5570bc63dc4d - tokio_by_hand::extended::buffered::tests::production_and_consumption_are_concurrent::hae6d5e6794d8a108
Nov 01 13:33:08.140 INFO blam!                                at src/extended/buffered.rs:93
Nov 01 13:33:08.140 INFO blam!    7:     0x5570bc63da29 - tokio_by_hand::extended::buffered::tests::production_and_consumption_are_concurrent::{{closure}}::h9a12ffda7a874f0d
Nov 01 13:33:08.140 INFO blam!                                at src/extended/buffered.rs:84
Nov 01 13:33:08.140 INFO blam!    8:     0x5570bc637c8d - core::ops::function::FnOnce::call_once::hf18b178015152841
Nov 01 13:33:08.140 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 13:33:08.140 INFO blam!    9:     0x5570bc64232e - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 01 13:33:08.140 INFO blam!                                at libtest/lib.rs:1461
Nov 01 13:33:08.140 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 13:33:08.140 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 13:33:08.140 INFO blam!   10:     0x5570bc6f8ab9 - __rust_maybe_catch_panic
Nov 01 13:33:08.140 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 13:33:08.140 INFO blam!   11:     0x5570bc64dc3d - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:289
Nov 01 13:33:08.140 INFO blam!                                at libstd/panic.rs:392
Nov 01 13:33:08.140 INFO blam!                                at libtest/lib.rs:1416
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 13:33:08.140 INFO blam!   12:     0x5570bc666274 - std::panicking::try::do_call::h1f0578498122cc00
Nov 01 13:33:08.140 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 13:33:08.140 INFO blam!                                at libstd/panic.rs:313
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:310
Nov 01 13:33:08.140 INFO blam!   13:     0x5570bc6f8ab9 - __rust_maybe_catch_panic
Nov 01 13:33:08.140 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 13:33:08.140 INFO blam!   14:     0x5570bc656b46 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 01 13:33:08.140 INFO blam!                                at libstd/panicking.rs:289
Nov 01 13:33:08.140 INFO blam!                                at libstd/panic.rs:392
Nov 01 13:33:08.140 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 13:33:08.140 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 13:33:08.140 INFO blam!   15:     0x5570bc6eb7ba - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 01 13:33:08.140 INFO blam!                                at liballoc/boxed.rs:656
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 13:33:08.140 INFO blam!   16:     0x5570bc6dbf95 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 01 13:33:08.140 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 13:33:08.140 INFO blam!   17:     0x7f4cd63b76b9 - start_thread
Nov 01 13:33:08.140 INFO blam!   18:     0x7f4cd5ed741c - clone
Nov 01 13:33:08.140 INFO blam!   19:                0x0 - <unknown>
Nov 01 13:33:08.140 INFO blam! 
Nov 01 13:33:08.140 INFO blam! 
Nov 01 13:33:08.140 INFO blam! failures:
Nov 01 13:33:08.140 INFO blam!     extended::buffered::tests::production_and_consumption_are_concurrent
Nov 01 13:33:08.140 INFO blam!     extended::delayed_series::tests::production_and_consumption_are_concurrent
Nov 01 13:33:08.140 INFO blam! 
Nov 01 13:33:08.140 INFO blam! test result: FAILED. 27 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 13:33:08.140 INFO blam! 
Nov 01 13:33:08.147 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 01 13:33:08.148 INFO kablam! su: No module specific data is present
Nov 01 13:33:08.925 INFO running `"docker" "rm" "-f" "a80d118eb64cd87a76b86ad4f3a2dcb05218698368687002e619dbf5506617d3"`
Nov 01 13:33:09.255 INFO blam! a80d118eb64cd87a76b86ad4f3a2dcb05218698368687002e619dbf5506617d3
