Oct 15 01:22:16.595 INFO testing casey/tokio-by-hand against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1 Oct 15 01:22:16.596 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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 01:22:17.052 INFO blam! c2a9ee591e0d53e048ffe164a9d7accc5f7ba0b19dc06fe46aa5a289c4437de5 Oct 15 01:22:17.055 INFO running `"docker" "start" "-a" "c2a9ee591e0d53e048ffe164a9d7accc5f7ba0b19dc06fe46aa5a289c4437de5"` Oct 15 01:22:18.971 INFO kablam! usermod: no changes Oct 15 01:22:19.068 INFO kablam! Compiling net2 v0.2.31 Oct 15 01:22:19.079 INFO kablam! Compiling tokio-io v0.1.3 Oct 15 01:22:21.079 INFO kablam! Compiling mio v0.6.10 Oct 15 01:22:25.034 INFO kablam! Compiling tokio-core v0.1.10 Oct 15 01:22:28.832 INFO kablam! Compiling tokio-by-hand v0.0.0 (/source) Oct 15 01:22:30.309 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 11.30s Oct 15 01:22:30.313 INFO kablam! su: No module specific data is present Oct 15 01:22:32.054 INFO running `"docker" "rm" "-f" "c2a9ee591e0d53e048ffe164a9d7accc5f7ba0b19dc06fe46aa5a289c4437de5"` Oct 15 01:22:32.319 INFO blam! c2a9ee591e0d53e048ffe164a9d7accc5f7ba0b19dc06fe46aa5a289c4437de5 Oct 15 01:22:32.321 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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 01:22:32.969 INFO blam! 2ee6a7390677619b007159c4bcc0cc450002fc871b01053a83be813223e48e1f Oct 15 01:22:32.971 INFO running `"docker" "start" "-a" "2ee6a7390677619b007159c4bcc0cc450002fc871b01053a83be813223e48e1f"` Oct 15 01:22:34.580 INFO kablam! usermod: no changes Oct 15 01:22:34.731 INFO kablam! Compiling tokio-by-hand v0.0.0 (/source) Oct 15 01:22:39.075 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 4.47s Oct 15 01:22:39.081 INFO kablam! su: No module specific data is present Oct 15 01:22:40.493 INFO running `"docker" "rm" "-f" "2ee6a7390677619b007159c4bcc0cc450002fc871b01053a83be813223e48e1f"` Oct 15 01:22:40.819 INFO blam! 2ee6a7390677619b007159c4bcc0cc450002fc871b01053a83be813223e48e1f Oct 15 01:22:40.831 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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 01:22:41.446 INFO blam! bbf6462bfccd802cc92300e0df374c308105bc442f26c601036f6287f722360e Oct 15 01:22:41.448 INFO running `"docker" "start" "-a" "bbf6462bfccd802cc92300e0df374c308105bc442f26c601036f6287f722360e"` Oct 15 01:22:43.247 INFO kablam! usermod: no changes Oct 15 01:22:43.311 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 0.04s Oct 15 01:22:43.323 INFO kablam! Running /target/debug/deps/tokio_by_hand-3ae5c59583f0e029 Oct 15 01:22:43.323 INFO blam! Oct 15 01:22:43.323 INFO blam! running 29 tests Oct 15 01:22:44.367 INFO blam! test extended::delayed::tests::consumer_completes_in_about_one_second ... ok Oct 15 01:22:44.435 INFO blam! test extended::delayed::tests::producer_completes_in_about_one_second ... ok Oct 15 01:22:44.444 INFO blam! test extended::instant::tests::producer_completes_quickly ... ok Oct 15 01:22:44.499 INFO blam! test extended::instant::tests::producer_returns_all_values ... ok Oct 15 01:22:44.503 INFO blam! test extended::instant::tests::consumer_completes_quickly ... ok Oct 15 01:22:44.603 INFO blam! test extended::instant_series::tests::producer_returns_all_values ... ok Oct 15 01:22:44.707 INFO blam! test extended::instant_series::tests::forward_to_consumer ... ok Oct 15 01:22:45.602 INFO blam! test extended::delayed::tests::producer_returns_all_values ... ok Oct 15 01:22:45.655 INFO blam! test extended::sleeper::tests::with_core ... ok Oct 15 01:22:45.739 INFO blam! test extended::sleeper::tests::with_wait ... ok Oct 15 01:22:46.675 INFO blam! test standard::delayed::tests::consumer_completes_in_about_one_second ... ok Oct 15 01:22:46.877 INFO blam! test standard::delayed::tests::producer_completes_in_about_one_second ... ok Oct 15 01:22:49.484 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_produce ... FAILED Oct 15 01:22:49.491 INFO blam! test extended::delayed_series::tests::values_take_one_second_to_consume ... FAILED Oct 15 01:22:49.503 INFO blam! test standard::delayed::tests::producer_returns_all_values ... ok Oct 15 01:22:49.539 INFO blam! test extended::delayed_series::tests::production_and_consumption_are_concurrent ... ok Oct 15 01:22:49.539 INFO blam! test standard::instant::tests::producer_completes_quickly ... ok Oct 15 01:22:49.540 INFO blam! test standard::instant::tests::producer_returns_all_values ... ok Oct 15 01:22:49.540 INFO blam! test standard::instant::tests::consumer_completes_quickly ... ok Oct 15 01:22:49.551 INFO blam! test extended::buffered::tests::production_and_consumption_are_concurrent ... ok Oct 15 01:22:49.615 INFO blam! test standard::instant_series::tests::producer_returns_all_values ... ok Oct 15 01:22:49.615 INFO blam! test standard::instant_series::tests::forward_to_consumer ... ok Oct 15 01:22:50.600 INFO blam! test standard::sleeper::tests::with_core ... ok Oct 15 01:22:50.617 INFO blam! test standard::sleeper::tests::with_wait ... ok Oct 15 01:22:51.655 INFO blam! test standard::buffered::tests::production_and_consumption_are_concurrent ... ok Oct 15 01:22:53.039 INFO blam! test standard::delayed_series::tests::production_and_consumption_are_concurrent ... ok Oct 15 01:22:54.531 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_consume ... ok Oct 15 01:22:54.547 INFO blam! test standard::delayed_series::tests::values_take_one_second_to_produce ... ok Oct 15 01:22:54.566 INFO blam! test extended::adapter::tests::basic ... ok Oct 15 01:22:54.566 INFO blam! Oct 15 01:22:54.566 INFO blam! failures: Oct 15 01:22:54.566 INFO blam! Oct 15 01:22:54.566 INFO blam! ---- extended::delayed_series::tests::values_take_one_second_to_produce stdout ---- Oct 15 01:22:54.566 INFO blam! thread 'extended::delayed_series::tests::values_take_one_second_to_produce' panicked at 'assertion failed: elapsed < Duration::new(5, 500000000)', src/extended/delayed_series.rs:101:5 Oct 15 01:22:54.566 INFO blam! stack backtrace: Oct 15 01:22:54.566 INFO blam! 0: 0x5556a19a96fe - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h7fa1e90165a4b6b7 Oct 15 01:22:54.566 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 15 01:22:54.566 INFO blam! 1: 0x5556a19b2896 - std::sys_common::backtrace::print::hce31971a380b167f Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 15 01:22:54.566 INFO blam! 2: 0x5556a19ad5ed - std::panicking::default_hook::{{closure}}::h9590c1e09d0fb8eb Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:211 Oct 15 01:22:54.566 INFO blam! 3: 0x5556a19ad2fb - std::panicking::default_hook::hb1838a6568eb05e3 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:221 Oct 15 01:22:54.566 INFO blam! 4: 0x5556a19adc9c - std::panicking::rust_panic_with_hook::h9ad880ee5fc38368 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:476 Oct 15 01:22:54.566 INFO blam! 5: 0x5556a198127f - std::panicking::begin_panic::h5ad532a9568e4ad2 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:410 Oct 15 01:22:54.566 INFO blam! 6: 0x5556a18d6a97 - tokio_by_hand::extended::delayed_series::tests::values_take_one_second_to_produce::h75311b45dcc29052 Oct 15 01:22:54.566 INFO blam! at src/extended/delayed_series.rs:101 Oct 15 01:22:54.566 INFO blam! 7: 0x5556a18d68b9 - tokio_by_hand::extended::delayed_series::tests::values_take_one_second_to_produce::{{closure}}::h49505c592e7f15ff Oct 15 01:22:54.566 INFO blam! at src/extended/delayed_series.rs:93 Oct 15 01:22:54.566 INFO blam! 8: 0x5556a18ecc4d - core::ops::function::FnOnce::call_once::h014786c9874e8fcf Oct 15 01:22:54.566 INFO blam! at libcore/ops/function.rs:238 Oct 15 01:22:54.566 INFO blam! 9: 0x5556a190750e - >::call_box::hae226a42c8d94b19 Oct 15 01:22:54.566 INFO blam! at libtest/lib.rs:1468 Oct 15 01:22:54.566 INFO blam! at libcore/ops/function.rs:238 Oct 15 01:22:54.566 INFO blam! at liballoc/boxed.rs:672 Oct 15 01:22:54.566 INFO blam! 10: 0x5556a19bddb9 - __rust_maybe_catch_panic Oct 15 01:22:54.566 INFO blam! at libpanic_unwind/lib.rs:102 Oct 15 01:22:54.566 INFO blam! 11: 0x5556a1927f3d - std::sys_common::backtrace::__rust_begin_short_backtrace::ha5325a24fd096595 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:289 Oct 15 01:22:54.566 INFO blam! at libstd/panic.rs:392 Oct 15 01:22:54.566 INFO blam! at libtest/lib.rs:1423 Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 15 01:22:54.566 INFO blam! 12: 0x5556a1928ae4 - std::panicking::try::do_call::h2c632c17829d0621 Oct 15 01:22:54.566 INFO blam! at libstd/thread/mod.rs:409 Oct 15 01:22:54.566 INFO blam! at libstd/panic.rs:313 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:310 Oct 15 01:22:54.566 INFO blam! 13: 0x5556a19bddb9 - __rust_maybe_catch_panic Oct 15 01:22:54.566 INFO blam! at libpanic_unwind/lib.rs:102 Oct 15 01:22:54.566 INFO blam! 14: 0x5556a1916126 - >::call_box::h1a0832645645f090 Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:289 Oct 15 01:22:54.566 INFO blam! at libstd/panic.rs:392 Oct 15 01:22:54.566 INFO blam! at libstd/thread/mod.rs:408 Oct 15 01:22:54.566 INFO blam! at liballoc/boxed.rs:672 Oct 15 01:22:54.566 INFO blam! 15: 0x5556a19aba2a - std::sys_common::thread::start_thread::h49b8ca61f624f031 Oct 15 01:22:54.566 INFO blam! at liballoc/boxed.rs:682 Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/thread.rs:24 Oct 15 01:22:54.566 INFO blam! 16: 0x5556a19a0a45 - std::sys::unix::thread::Thread::new::thread_start::hc999bd1fb8f071a1 Oct 15 01:22:54.566 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 15 01:22:54.566 INFO blam! 17: 0x7fb82ef786b9 - start_thread Oct 15 01:22:54.566 INFO blam! 18: 0x7fb82ea9841c - clone Oct 15 01:22:54.566 INFO blam! 19: 0x0 - Oct 15 01:22:54.566 INFO blam! Oct 15 01:22:54.566 INFO blam! ---- extended::delayed_series::tests::values_take_one_second_to_consume stdout ---- Oct 15 01:22:54.566 INFO blam! thread 'extended::delayed_series::tests::values_take_one_second_to_consume' panicked at 'assertion failed: elapsed < Duration::new(5, 500000000)', src/extended/delayed_series.rs:115:5 Oct 15 01:22:54.566 INFO blam! stack backtrace: Oct 15 01:22:54.566 INFO blam! 0: 0x5556a19a96fe - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h7fa1e90165a4b6b7 Oct 15 01:22:54.566 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 15 01:22:54.566 INFO blam! 1: 0x5556a19b2896 - std::sys_common::backtrace::print::hce31971a380b167f Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 15 01:22:54.566 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 15 01:22:54.566 INFO blam! 2: 0x5556a19ad5ed - std::panicking::default_hook::{{closure}}::h9590c1e09d0fb8eb Oct 15 01:22:54.566 INFO blam! at libstd/panicking.rs:211 Oct 15 01:22:54.567 INFO blam! 3: 0x5556a19ad2fb - std::panicking::default_hook::hb1838a6568eb05e3 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:221 Oct 15 01:22:54.567 INFO blam! 4: 0x5556a19adc9c - std::panicking::rust_panic_with_hook::h9ad880ee5fc38368 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:476 Oct 15 01:22:54.567 INFO blam! 5: 0x5556a198127f - std::panicking::begin_panic::h5ad532a9568e4ad2 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:410 Oct 15 01:22:54.567 INFO blam! 6: 0x5556a18d6d14 - tokio_by_hand::extended::delayed_series::tests::values_take_one_second_to_consume::ha4a30cbafc21936a Oct 15 01:22:54.567 INFO blam! at src/extended/delayed_series.rs:115 Oct 15 01:22:54.567 INFO blam! 7: 0x5556a18d6b49 - tokio_by_hand::extended::delayed_series::tests::values_take_one_second_to_consume::{{closure}}::h1bcae6d17ba8da0b Oct 15 01:22:54.567 INFO blam! at src/extended/delayed_series.rs:106 Oct 15 01:22:54.567 INFO blam! 8: 0x5556a18ed61d - core::ops::function::FnOnce::call_once::h6c7d857b32386cf8 Oct 15 01:22:54.567 INFO blam! at libcore/ops/function.rs:238 Oct 15 01:22:54.567 INFO blam! 9: 0x5556a190750e - >::call_box::hae226a42c8d94b19 Oct 15 01:22:54.567 INFO blam! at libtest/lib.rs:1468 Oct 15 01:22:54.567 INFO blam! at libcore/ops/function.rs:238 Oct 15 01:22:54.567 INFO blam! at liballoc/boxed.rs:672 Oct 15 01:22:54.567 INFO blam! 10: 0x5556a19bddb9 - __rust_maybe_catch_panic Oct 15 01:22:54.567 INFO blam! at libpanic_unwind/lib.rs:102 Oct 15 01:22:54.567 INFO blam! 11: 0x5556a1927f3d - std::sys_common::backtrace::__rust_begin_short_backtrace::ha5325a24fd096595 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:289 Oct 15 01:22:54.567 INFO blam! at libstd/panic.rs:392 Oct 15 01:22:54.567 INFO blam! at libtest/lib.rs:1423 Oct 15 01:22:54.567 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 15 01:22:54.567 INFO blam! 12: 0x5556a1928ae4 - std::panicking::try::do_call::h2c632c17829d0621 Oct 15 01:22:54.567 INFO blam! at libstd/thread/mod.rs:409 Oct 15 01:22:54.567 INFO blam! at libstd/panic.rs:313 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:310 Oct 15 01:22:54.567 INFO blam! 13: 0x5556a19bddb9 - __rust_maybe_catch_panic Oct 15 01:22:54.567 INFO blam! at libpanic_unwind/lib.rs:102 Oct 15 01:22:54.567 INFO blam! 14: 0x5556a1916126 - >::call_box::h1a0832645645f090 Oct 15 01:22:54.567 INFO blam! at libstd/panicking.rs:289 Oct 15 01:22:54.567 INFO blam! at libstd/panic.rs:392 Oct 15 01:22:54.567 INFO blam! at libstd/thread/mod.rs:408 Oct 15 01:22:54.567 INFO blam! at liballoc/boxed.rs:672 Oct 15 01:22:54.567 INFO blam! 15: 0x5556a19aba2a - std::sys_common::thread::start_thread::h49b8ca61f624f031 Oct 15 01:22:54.567 INFO blam! at liballoc/boxed.rs:682 Oct 15 01:22:54.567 INFO blam! at libstd/sys_common/thread.rs:24 Oct 15 01:22:54.567 INFO blam! 16: 0x5556a19a0a45 - std::sys::unix::thread::Thread::new::thread_start::hc999bd1fb8f071a1 Oct 15 01:22:54.567 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 15 01:22:54.567 INFO blam! 17: 0x7fb82ef786b9 - start_thread Oct 15 01:22:54.567 INFO blam! 18: 0x7fb82ea9841c - clone Oct 15 01:22:54.567 INFO blam! 19: 0x0 - Oct 15 01:22:54.567 INFO blam! Oct 15 01:22:54.567 INFO blam! Oct 15 01:22:54.567 INFO blam! failures: Oct 15 01:22:54.567 INFO blam! extended::delayed_series::tests::values_take_one_second_to_consume Oct 15 01:22:54.567 INFO blam! extended::delayed_series::tests::values_take_one_second_to_produce Oct 15 01:22:54.567 INFO blam! Oct 15 01:22:54.567 INFO blam! test result: FAILED. 27 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out Oct 15 01:22:54.567 INFO blam! Oct 15 01:22:54.582 INFO kablam! error: test failed, to rerun pass '--lib' Oct 15 01:22:54.582 INFO kablam! su: No module specific data is present Oct 15 01:22:55.493 INFO running `"docker" "rm" "-f" "bbf6462bfccd802cc92300e0df374c308105bc442f26c601036f6287f722360e"` Oct 15 01:22:55.785 INFO blam! bbf6462bfccd802cc92300e0df374c308105bc442f26c601036f6287f722360e