Oct 11 01:34:45.595 INFO testing casey/tokio-by-hand against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 01:34:45.595 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 01:34:45.595 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 01:34:46.033 INFO blam! 4ff4a20724a95e437acb0dbb5da1acac97cd540fa677a27d264099024b924a96
Oct 11 01:34:46.035 INFO running `"docker" "start" "-a" "4ff4a20724a95e437acb0dbb5da1acac97cd540fa677a27d264099024b924a96"`
Oct 11 01:34:47.893 INFO kablam! usermod: no changes
Oct 11 01:34:47.996 INFO kablam!    Compiling iovec v0.1.1
Oct 11 01:34:48.000 INFO kablam!    Compiling net2 v0.2.31
Oct 11 01:34:48.756 INFO kablam!    Compiling bytes v0.4.5
Oct 11 01:34:49.582 INFO kablam!    Compiling mio v0.6.10
Oct 11 01:34:51.280 INFO kablam!    Compiling tokio-io v0.1.3
Oct 11 01:34:53.571 INFO kablam!    Compiling tokio-core v0.1.10
Oct 11 01:34:56.280 INFO kablam!    Compiling tokio-by-hand v0.0.0 (/source)
Oct 11 01:34:57.444 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.51s
Oct 11 01:34:57.445 INFO kablam! su: No module specific data is present
Oct 11 01:34:58.026 INFO running `"docker" "rm" "-f" "4ff4a20724a95e437acb0dbb5da1acac97cd540fa677a27d264099024b924a96"`
Oct 11 01:34:58.280 INFO blam! 4ff4a20724a95e437acb0dbb5da1acac97cd540fa677a27d264099024b924a96
Oct 11 01:34:58.288 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 01:34:58.292 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 01:34:58.710 INFO blam! 97af7014e8368afc9f36b013673668ef3bd155fead786a31527a3c34f74197d6
Oct 11 01:34:58.720 INFO running `"docker" "start" "-a" "97af7014e8368afc9f36b013673668ef3bd155fead786a31527a3c34f74197d6"`
Oct 11 01:34:59.876 INFO kablam! usermod: no changes
Oct 11 01:34:59.948 INFO kablam!    Compiling tokio-by-hand v0.0.0 (/source)
Oct 11 01:35:03.672 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 3.77s
Oct 11 01:35:03.680 INFO kablam! su: No module specific data is present
Oct 11 01:35:04.761 INFO running `"docker" "rm" "-f" "97af7014e8368afc9f36b013673668ef3bd155fead786a31527a3c34f74197d6"`
Oct 11 01:35:04.976 INFO blam! 97af7014e8368afc9f36b013673668ef3bd155fead786a31527a3c34f74197d6
Oct 11 01:35:04.979 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 01:35:04.979 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 01:35:05.347 INFO blam! 7c98f6c4c901540f058a379705407041d5f699a1470eb67580232fbc35670e03
Oct 11 01:35:05.348 INFO running `"docker" "start" "-a" "7c98f6c4c901540f058a379705407041d5f699a1470eb67580232fbc35670e03"`
Oct 11 01:35:06.388 INFO kablam! usermod: no changes
Oct 11 01:35:06.493 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Oct 11 01:35:06.505 INFO kablam!      Running /target/debug/deps/tokio_by_hand-1bac819423ca65f3
Oct 11 01:35:06.508 INFO blam! 
Oct 11 01:35:06.508 INFO blam! running 29 tests
Oct 11 01:35:07.525 INFO blam! test extended::delayed::tests::consumer_completes_in_about_one_second ... ok
Oct 11 01:35:07.637 INFO blam! test extended::delayed::tests::producer_completes_in_about_one_second ... ok
Oct 11 01:35:07.644 INFO blam! test extended::instant::tests::producer_completes_quickly ... ok
Oct 11 01:35:07.684 INFO blam! test extended::instant::tests::consumer_completes_quickly ... ok
Oct 11 01:35:07.684 INFO blam! test extended::instant::tests::producer_returns_all_values ... ok
Oct 11 01:35:07.891 INFO blam! test extended::instant_series::tests::producer_returns_all_values ... ok
Oct 11 01:35:08.153 INFO blam! test extended::instant_series::tests::forward_to_consumer ... ok
Oct 11 01:35:08.717 INFO blam! test extended::delayed::tests::producer_returns_all_values ... ok
Oct 11 01:35:08.900 INFO blam! test extended::sleeper::tests::with_core ... ok
Oct 11 01:35:09.168 INFO blam! test extended::sleeper::tests::with_wait ... ok
Oct 11 01:35:10.012 INFO blam! test standard::delayed::tests::consumer_completes_in_about_one_second ... ok
Oct 11 01:35:10.271 INFO blam! test standard::delayed::tests::producer_completes_in_about_one_second ... ok
Oct 11 01:35:11.733 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_consume ... ok
Oct 11 01:35:11.832 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_produce ... ok
Oct 11 01:35:12.662 INFO blam! test extended::delayed_series::tests::production_and_consumption_are_concurrent ... ok
Oct 11 01:35:12.840 INFO blam! test extended::buffered::tests::production_and_consumption_are_concurrent ... ok
Oct 11 01:35:12.981 INFO blam! test standard::delayed::tests::producer_returns_all_values ... ok
Oct 11 01:35:12.987 INFO blam! test standard::instant::tests::consumer_completes_quickly ... ok
Oct 11 01:35:13.082 INFO blam! test standard::instant::tests::producer_returns_all_values ... ok
Oct 11 01:35:13.103 INFO blam! test standard::instant_series::tests::producer_returns_all_values ... ok
Oct 11 01:35:13.172 INFO blam! test standard::instant::tests::producer_completes_quickly ... ok
Oct 11 01:35:13.304 INFO blam! test standard::instant_series::tests::forward_to_consumer ... ok
Oct 11 01:35:14.106 INFO blam! test standard::sleeper::tests::with_core ... ok
Oct 11 01:35:14.180 INFO blam! test standard::sleeper::tests::with_wait ... ok
Oct 11 01:35:15.132 INFO blam! test standard::buffered::tests::production_and_consumption_are_concurrent ... ok
Oct 11 01:35:17.124 INFO blam! test standard::delayed_series::tests::production_and_consumption_are_concurrent ... FAILED
Oct 11 01:35:17.185 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_consume ... ok
Oct 11 01:35:17.190 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_produce ... ok
Oct 11 01:35:21.880 INFO blam! test extended::adapter::tests::basic ... ok
Oct 11 01:35:21.880 INFO kablam! error: test failed, to rerun pass '--lib'
Oct 11 01:35:21.880 INFO blam! 
Oct 11 01:35:21.880 INFO blam! failures:
Oct 11 01:35:21.880 INFO blam! 
Oct 11 01:35:21.880 INFO blam! ---- standard::delayed_series::tests::production_and_consumption_are_concurrent stdout ----
Oct 11 01:35:21.880 INFO blam! thread 'standard::delayed_series::tests::production_and_consumption_are_concurrent' panicked at 'assertion failed: elapsed < Duration::new(6, 500000000)', src/standard/delayed_series.rs:105:5
Oct 11 01:35:21.880 INFO blam! stack backtrace:
Oct 11 01:35:21.880 INFO blam!    0:     0x55cfb0efb33e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617
Oct 11 01:35:21.880 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Oct 11 01:35:21.880 INFO blam!    1:     0x55cfb0f04206 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9
Oct 11 01:35:21.880 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Oct 11 01:35:21.880 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Oct 11 01:35:21.880 INFO blam!    2:     0x55cfb0efef5d - std::panicking::default_hook::{{closure}}::h47bedf778bf88480
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:211
Oct 11 01:35:21.880 INFO blam!    3:     0x55cfb0efec6b - std::panicking::default_hook::h1ef46fa7cd894809
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:221
Oct 11 01:35:21.880 INFO blam!    4:     0x55cfb0eff60c - std::panicking::rust_panic_with_hook::h05510fde8841c579
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:476
Oct 11 01:35:21.880 INFO blam!    5:     0x55cfb0edc52f - std::panicking::begin_panic::h1c06d2466f3e6124
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:410
Oct 11 01:35:21.880 INFO blam!    6:     0x55cfb0e278ee - tokio_by_hand::standard::delayed_series::tests::production_and_consumption_are_concurrent::h8d78d847dcf8c526
Oct 11 01:35:21.880 INFO blam!                                at src/standard/delayed_series.rs:105
Oct 11 01:35:21.880 INFO blam!    7:     0x55cfb0e276f9 - tokio_by_hand::standard::delayed_series::tests::production_and_consumption_are_concurrent::{{closure}}::had6522545c697de8
Oct 11 01:35:21.880 INFO blam!                                at src/standard/delayed_series.rs:96
Oct 11 01:35:21.880 INFO blam!    8:     0x55cfb0e3efad - core::ops::function::FnOnce::call_once::hc152f77f7e28e631
Oct 11 01:35:21.880 INFO blam!                                at libcore/ops/function.rs:238
Oct 11 01:35:21.880 INFO blam!    9:     0x55cfb0e584fe - <F as alloc::boxed::FnBox<A>>::call_box::h95b7cb918af67b5d
Oct 11 01:35:21.880 INFO blam!                                at libtest/lib.rs:1468
Oct 11 01:35:21.880 INFO blam!                                at libcore/ops/function.rs:238
Oct 11 01:35:21.880 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 01:35:21.880 INFO blam!   10:     0x55cfb0f0f3d9 - __rust_maybe_catch_panic
Oct 11 01:35:21.880 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 01:35:21.880 INFO blam!   11:     0x55cfb0e7904d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:289
Oct 11 01:35:21.880 INFO blam!                                at libstd/panic.rs:392
Oct 11 01:35:21.880 INFO blam!                                at libtest/lib.rs:1423
Oct 11 01:35:21.880 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Oct 11 01:35:21.880 INFO blam!   12:     0x55cfb0e79c54 - std::panicking::try::do_call::ha990a345054c2d63
Oct 11 01:35:21.880 INFO blam!                                at libstd/thread/mod.rs:409
Oct 11 01:35:21.880 INFO blam!                                at libstd/panic.rs:313
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:310
Oct 11 01:35:21.880 INFO blam!   13:     0x55cfb0f0f3d9 - __rust_maybe_catch_panic
Oct 11 01:35:21.880 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 01:35:21.880 INFO blam!   14:     0x55cfb0e67216 - <F as alloc::boxed::FnBox<A>>::call_box::h96125cb29a9ba44b
Oct 11 01:35:21.880 INFO blam!                                at libstd/panicking.rs:289
Oct 11 01:35:21.880 INFO blam!                                at libstd/panic.rs:392
Oct 11 01:35:21.880 INFO blam!                                at libstd/thread/mod.rs:408
Oct 11 01:35:21.880 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 01:35:21.880 INFO blam!   15:     0x55cfb0efd39a - std::sys_common::thread::start_thread::h97c4c5d82028f6b1
Oct 11 01:35:21.880 INFO blam!                                at liballoc/boxed.rs:682
Oct 11 01:35:21.880 INFO blam!                                at libstd/sys_common/thread.rs:24
Oct 11 01:35:21.881 INFO blam!   16:     0x55cfb0ef2695 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886
Oct 11 01:35:21.881 INFO blam!                                at libstd/sys/unix/thread.rs:90
Oct 11 01:35:21.881 INFO blam!   17:     0x7fae998e76b9 - start_thread
Oct 11 01:35:21.881 INFO blam!   18:     0x7fae9940741c - clone
Oct 11 01:35:21.881 INFO blam!   19:                0x0 - <unknown>
Oct 11 01:35:21.881 INFO blam! 
Oct 11 01:35:21.881 INFO blam! 
Oct 11 01:35:21.881 INFO blam! failures:
Oct 11 01:35:21.881 INFO blam!     standard::delayed_series::tests::production_and_consumption_are_concurrent
Oct 11 01:35:21.881 INFO blam! 
Oct 11 01:35:21.881 INFO blam! test result: FAILED. 28 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 01:35:21.881 INFO blam! 
Oct 11 01:35:21.885 INFO kablam! su: No module specific data is present
Oct 11 01:35:22.510 INFO running `"docker" "rm" "-f" "7c98f6c4c901540f058a379705407041d5f699a1470eb67580232fbc35670e03"`
Oct 11 01:35:22.784 INFO blam! 7c98f6c4c901540f058a379705407041d5f699a1470eb67580232fbc35670e03