Oct 12 01:40:26.274 INFO testing desync-0.2.3 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling Oct 12 01:40:26.274 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 01:40:26.674 INFO blam! 888dd69fa583ad36d9ad9131bec28424df816be9bea4a334196d13768b3df2d0 Oct 12 01:40:26.677 INFO running `"docker" "start" "-a" "888dd69fa583ad36d9ad9131bec28424df816be9bea4a334196d13768b3df2d0"` Oct 12 01:40:27.708 INFO kablam! usermod: no changes Oct 12 01:40:27.747 INFO kablam! Compiling desync v0.2.3 (/source) Oct 12 01:40:28.992 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 1.26s Oct 12 01:40:28.992 INFO kablam! su: No module specific data is present Oct 12 01:40:29.227 INFO running `"docker" "rm" "-f" "888dd69fa583ad36d9ad9131bec28424df816be9bea4a334196d13768b3df2d0"` Oct 12 01:40:29.350 INFO blam! 888dd69fa583ad36d9ad9131bec28424df816be9bea4a334196d13768b3df2d0 Oct 12 01:40:29.352 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 01:40:29.569 INFO blam! 816410bc8056e5d058c64b05c84bab11e1e0da98505a189dba34591262b92b82 Oct 12 01:40:29.580 INFO running `"docker" "start" "-a" "816410bc8056e5d058c64b05c84bab11e1e0da98505a189dba34591262b92b82"` Oct 12 01:40:30.671 INFO kablam! usermod: no changes Oct 12 01:40:30.736 INFO kablam! Compiling desync v0.2.3 (/source) Oct 12 01:40:40.216 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 9.52s Oct 12 01:40:40.219 INFO kablam! su: No module specific data is present Oct 12 01:40:41.591 INFO running `"docker" "rm" "-f" "816410bc8056e5d058c64b05c84bab11e1e0da98505a189dba34591262b92b82"` Oct 12 01:40:41.848 INFO blam! 816410bc8056e5d058c64b05c84bab11e1e0da98505a189dba34591262b92b82 Oct 12 01:40:41.861 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling/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 12 01:40:42.298 INFO blam! 98e9f4d186b67950c37be4a503943df93c668fb41ba7420481a58de08a56dc4e Oct 12 01:40:42.302 INFO running `"docker" "start" "-a" "98e9f4d186b67950c37be4a503943df93c668fb41ba7420481a58de08a56dc4e"` Oct 12 01:40:43.820 INFO kablam! usermod: no changes Oct 12 01:40:43.892 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 0.03s Oct 12 01:40:43.897 INFO kablam! Running /target/debug/deps/desync-3fb2a5a70ddf127a Oct 12 01:40:43.904 INFO kablam! Running /target/debug/deps/desync-b7dfa4ac13da20d1 Oct 12 01:40:43.905 INFO blam! Oct 12 01:40:43.905 INFO blam! running 0 tests Oct 12 01:40:43.905 INFO blam! Oct 12 01:40:43.905 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Oct 12 01:40:43.905 INFO blam! Oct 12 01:40:43.905 INFO blam! Oct 12 01:40:43.905 INFO blam! running 27 tests Oct 12 01:40:43.905 INFO blam! test retrieve_data_synchronously ... ok Oct 12 01:40:43.905 INFO blam! test retrieve_data_into_local_var ... ok Oct 12 01:40:43.916 INFO blam! test scheduler::async::schedule_async ... ok Oct 12 01:40:43.916 INFO blam! test scheduler::async::schedule_after_queue_released ... ok Oct 12 01:40:44.017 INFO blam! test scheduler::async::will_schedule_in_order ... ok Oct 12 01:40:44.017 INFO blam! test scheduler::future::future_waits_for_us ... ok Oct 12 01:40:44.049 INFO blam! test scheduler::async::will_schedule_separate_queues_in_parallel ... ok Oct 12 01:40:44.049 INFO blam! test scheduler::future::schedule_future ... ok Oct 12 01:40:44.050 INFO blam! test scheduler::future::wait_for_future ... ok Oct 12 01:40:44.061 INFO blam! test scheduler::sync::reschedule_after_immediate_sync ... ok Oct 12 01:40:44.062 INFO blam! test scheduler::suspend::safe_to_drop_suspended_queue ... ok Oct 12 01:40:44.070 INFO blam! test scheduler::sync::schedule_sync ... ok Oct 12 01:40:44.109 INFO blam! test dropping_while_running_isnt_obviously_bad ... ok Oct 12 01:40:44.137 INFO blam! test scheduler::sync::sync_has_synchronous_lifetime ... ok Oct 12 01:40:44.152 INFO kablam! thread 'desync jobs thread' panicked at 'assertion failed: core.state == QueueState::Running', src/scheduler.rs:179:13 Oct 12 01:40:44.153 INFO kablam! stack backtrace: Oct 12 01:40:44.157 INFO blam! test scheduler::suspend::suspend_queue_with_local_drain ... ok Oct 12 01:40:44.189 INFO blam! test scheduler::sync::sync_drains_with_no_threads ... ok Oct 12 01:40:44.189 INFO blam! test scheduler::sync::schedule_sync_after_async ... ok Oct 12 01:40:44.208 INFO kablam! 0: 0x56198835911e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h7fa1e90165a4b6b7 Oct 12 01:40:44.269 INFO kablam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 12 01:40:44.269 INFO kablam! 1: 0x561988362296 - std::sys_common::backtrace::print::hce31971a380b167f Oct 12 01:40:44.269 INFO kablam! at libstd/sys_common/backtrace.rs:71 Oct 12 01:40:44.269 INFO kablam! at libstd/sys_common/backtrace.rs:59 Oct 12 01:40:44.269 INFO kablam! 2: 0x56198835cfed - std::panicking::default_hook::{{closure}}::h9590c1e09d0fb8eb Oct 12 01:40:44.271 INFO kablam! at libstd/panicking.rs:211 Oct 12 01:40:44.271 INFO kablam! 3: 0x56198835cd60 - std::panicking::default_hook::hb1838a6568eb05e3 Oct 12 01:40:44.271 INFO kablam! at libstd/panicking.rs:227 Oct 12 01:40:44.271 INFO kablam! 4: 0x56198835d69c - std::panicking::rust_panic_with_hook::h9ad880ee5fc38368 Oct 12 01:40:44.271 INFO kablam! at libstd/panicking.rs:476 Oct 12 01:40:44.271 INFO kablam! 5: 0x56198834616f - std::panicking::begin_panic::h516e2f215145d3b3 Oct 12 01:40:44.271 INFO kablam! at libstd/panicking.rs:410 Oct 12 01:40:44.271 INFO kablam! 6: 0x561988322f2d - desync::scheduler::JobQueue::dequeue::h9be9f87044fa929a Oct 12 01:40:44.271 INFO kablam! at src/scheduler.rs:179 Oct 12 01:40:44.271 INFO kablam! 7: 0x5619883230a9 - desync::scheduler::JobQueue::drain::heab86d1d52291f57 Oct 12 01:40:44.271 INFO kablam! at src/scheduler.rs:193 Oct 12 01:40:44.271 INFO kablam! 8: 0x561988324956 - desync::scheduler::Scheduler::schedule_thread::{{closure}}::h161f4e46facc8b18 Oct 12 01:40:44.271 INFO kablam! at src/scheduler.rs:381 Oct 12 01:40:44.271 INFO kablam! 9: 0x561988324481 - desync::scheduler::Scheduler::schedule_dormant::{{closure}}::h5fd580563758759d Oct 12 01:40:44.271 INFO kablam! at src/scheduler.rs:338 Oct 12 01:40:44.271 INFO kablam! 10: 0x56198831cf54 - as desync::job::ScheduledJob>::run::h70cfd343fac5a7ee Oct 12 01:40:44.272 INFO kablam! at src/job.rs:31 Oct 12 01:40:44.272 INFO kablam! 11: 0x56198831d3fa - desync::scheduler_thread::SchedulerThread::new::{{closure}}::h158118e060303ace Oct 12 01:40:44.272 INFO kablam! at src/scheduler_thread.rs:28 Oct 12 01:40:44.272 INFO kablam! 12: 0x56198832be4a - std::sys_common::backtrace::__rust_begin_short_backtrace::h1aa3a8f6d3c7ae78 Oct 12 01:40:44.272 INFO kablam! at libstd/sys_common/backtrace.rs:136 Oct 12 01:40:44.272 INFO kablam! 13: 0x5619883195dc - std::thread::Builder::spawn::{{closure}}::{{closure}}::h617dff839fb5645b Oct 12 01:40:44.272 INFO blam! test update_data_asynchronously ... ok Oct 12 01:40:44.300 INFO blam! test update_data_with_future ... ok Oct 12 01:40:44.328 INFO kablam! at libstd/thread/mod.rs:409 Oct 12 01:40:44.328 INFO kablam! 14: 0x56198831d5bc - as core::ops::function::FnOnce<()>>::call_once::h1261fcdc8116dc4e Oct 12 01:40:44.328 INFO kablam! at libstd/panic.rs:313 Oct 12 01:40:44.328 INFO kablam! 15: 0x56198831ba3f - std::panicking::try::do_call::h3566ba89b38d823d Oct 12 01:40:44.328 INFO kablam! at libstd/panicking.rs:310 Oct 12 01:40:44.328 INFO kablam! 16: 0x56198836d7b9 - __rust_maybe_catch_panic Oct 12 01:40:44.328 INFO kablam! at libpanic_unwind/lib.rs:102 Oct 12 01:40:44.328 INFO kablam! 17: 0x56198831b964 - std::panicking::try::h220074245bb4feaf Oct 12 01:40:44.328 INFO kablam! at libstd/panicking.rs:289 Oct 12 01:40:44.328 INFO kablam! 18: 0x56198831dfcc - std::panic::catch_unwind::h8ecb0645e7dbe0ce Oct 12 01:40:44.328 INFO kablam! at libstd/panic.rs:392 Oct 12 01:40:44.328 INFO kablam! 19: 0x5619883193f0 - std::thread::Builder::spawn::{{closure}}::h4990c306c5081b84 Oct 12 01:40:44.328 INFO kablam! at libstd/thread/mod.rs:408 Oct 12 01:40:44.328 INFO kablam! 20: 0x56198831af56 - >::call_box::hb04233d318277a04 Oct 12 01:40:44.329 INFO kablam! at liballoc/boxed.rs:672 Oct 12 01:40:44.329 INFO kablam! 21: 0x56198835b42a - std::sys_common::thread::start_thread::h49b8ca61f624f031 Oct 12 01:40:44.329 INFO kablam! at liballoc/boxed.rs:682 Oct 12 01:40:44.329 INFO kablam! at libstd/sys_common/thread.rs:24 Oct 12 01:40:44.329 INFO kablam! 22: 0x5619883505f5 - std::sys::unix::thread::Thread::new::thread_start::hc999bd1fb8f071a1 Oct 12 01:40:44.329 INFO kablam! at libstd/sys/unix/thread.rs:90 Oct 12 01:40:44.329 INFO kablam! 23: 0x7f46d50866b9 - start_thread Oct 12 01:40:44.329 INFO kablam! 24: 0x7f46d4ba641c - clone Oct 12 01:40:44.329 INFO kablam! 25: 0x0 - Oct 12 01:40:44.425 INFO blam! test wait_for_future ... ok Oct 12 01:40:44.996 INFO blam! test scheduler::async::async_only_runs_once ... FAILED Oct 12 01:40:45.024 INFO blam! test scheduler::thread_management::will_despawn_extra_threads ... ok Oct 12 01:40:48.581 INFO blam! test update_data_asynchronously_1000_times ... ok Oct 12 01:40:48.641 INFO blam! test scheduler::suspend::resume_before_suspend ... ok Oct 12 01:40:48.889 INFO blam! test update_data_with_future_1000_times ... ok Oct 12 01:40:49.053 INFO blam! test scheduler::async::async_runs_in_order_1000_iter ... ok Oct 12 01:40:53.643 INFO blam! test scheduler::suspend::suspend_queue ... ok Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO blam! failures: Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO blam! ---- scheduler::async::async_only_runs_once stdout ---- Oct 12 01:40:53.643 INFO blam! BBBIIIBBIIIII Pending queue count: 0 Oct 12 01:40:53.643 INFO blam! thread 'scheduler::async::async_only_runs_once' panicked at 'Timeout', tests/scheduler/timeout.rs:56:13 Oct 12 01:40:53.643 INFO blam! stack backtrace: Oct 12 01:40:53.643 INFO blam! 0: 0x56198835911e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h7fa1e90165a4b6b7 Oct 12 01:40:53.643 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 12 01:40:53.643 INFO blam! 1: 0x561988362296 - std::sys_common::backtrace::print::hce31971a380b167f Oct 12 01:40:53.643 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 12 01:40:53.643 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 12 01:40:53.643 INFO blam! 2: 0x56198835cfed - std::panicking::default_hook::{{closure}}::h9590c1e09d0fb8eb Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:211 Oct 12 01:40:53.643 INFO blam! 3: 0x56198835ccfb - std::panicking::default_hook::hb1838a6568eb05e3 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:221 Oct 12 01:40:53.643 INFO blam! 4: 0x56198835d69c - std::panicking::rust_panic_with_hook::h9ad880ee5fc38368 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:476 Oct 12 01:40:53.643 INFO blam! 5: 0x56198834616f - std::panicking::begin_panic::h516e2f215145d3b3 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:410 Oct 12 01:40:53.643 INFO blam! 6: 0x56198828d288 - desync::scheduler::timeout::timeout::h6cf07c0d32aec629 Oct 12 01:40:53.643 INFO blam! at tests/scheduler/timeout.rs:56 Oct 12 01:40:53.643 INFO blam! 7: 0x5619882c2d39 - desync::scheduler::async::async_only_runs_once::hc320f3d4897e8fe6 Oct 12 01:40:53.643 INFO blam! at tests/scheduler/async.rs:27 Oct 12 01:40:53.643 INFO blam! 8: 0x5619882c2c89 - desync::scheduler::async::async_only_runs_once::{{closure}}::hae8441475a6ba136 Oct 12 01:40:53.643 INFO blam! at tests/scheduler/async.rs:25 Oct 12 01:40:53.643 INFO blam! 9: 0x561988236fed - core::ops::function::FnOnce::call_once::h6f8d79a26a710ffb Oct 12 01:40:53.643 INFO blam! at libcore/ops/function.rs:238 Oct 12 01:40:53.643 INFO blam! 10: 0x5619882d294e - >::call_box::hae226a42c8d94b19 Oct 12 01:40:53.643 INFO blam! at libtest/lib.rs:1468 Oct 12 01:40:53.643 INFO blam! at libcore/ops/function.rs:238 Oct 12 01:40:53.643 INFO blam! at liballoc/boxed.rs:672 Oct 12 01:40:53.643 INFO blam! 11: 0x56198836d7b9 - __rust_maybe_catch_panic Oct 12 01:40:53.643 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 01:40:53.643 INFO blam! 12: 0x5619882f337d - std::sys_common::backtrace::__rust_begin_short_backtrace::ha5325a24fd096595 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:289 Oct 12 01:40:53.643 INFO blam! at libstd/panic.rs:392 Oct 12 01:40:53.643 INFO blam! at libtest/lib.rs:1423 Oct 12 01:40:53.643 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 12 01:40:53.643 INFO blam! 13: 0x5619882f3f24 - std::panicking::try::do_call::h2c632c17829d0621 Oct 12 01:40:53.643 INFO blam! at libstd/thread/mod.rs:409 Oct 12 01:40:53.643 INFO blam! at libstd/panic.rs:313 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:310 Oct 12 01:40:53.643 INFO blam! 14: 0x56198836d7b9 - __rust_maybe_catch_panic Oct 12 01:40:53.643 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 01:40:53.643 INFO blam! 15: 0x5619882e1566 - >::call_box::h1a0832645645f090 Oct 12 01:40:53.643 INFO blam! at libstd/panicking.rs:289 Oct 12 01:40:53.643 INFO blam! at libstd/panic.rs:392 Oct 12 01:40:53.643 INFO blam! at libstd/thread/mod.rs:408 Oct 12 01:40:53.643 INFO blam! at liballoc/boxed.rs:672 Oct 12 01:40:53.643 INFO blam! 16: 0x56198835b42a - std::sys_common::thread::start_thread::h49b8ca61f624f031 Oct 12 01:40:53.643 INFO blam! at liballoc/boxed.rs:682 Oct 12 01:40:53.643 INFO blam! at libstd/sys_common/thread.rs:24 Oct 12 01:40:53.643 INFO blam! 17: 0x5619883505f5 - std::sys::unix::thread::Thread::new::thread_start::hc999bd1fb8f071a1 Oct 12 01:40:53.643 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 12 01:40:53.643 INFO blam! 18: 0x7f46d50866b9 - start_thread Oct 12 01:40:53.643 INFO blam! 19: 0x7f46d4ba641c - clone Oct 12 01:40:53.643 INFO blam! 20: 0x0 - Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO blam! failures: Oct 12 01:40:53.643 INFO blam! scheduler::async::async_only_runs_once Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO blam! test result: FAILED. 26 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Oct 12 01:40:53.643 INFO blam! Oct 12 01:40:53.643 INFO kablam! error: test failed, to rerun pass '--test desync' Oct 12 01:40:53.644 INFO kablam! su: No module specific data is present Oct 12 01:40:54.271 INFO running `"docker" "rm" "-f" "98e9f4d186b67950c37be4a503943df93c668fb41ba7420481a58de08a56dc4e"` Oct 12 01:40:54.456 INFO blam! 98e9f4d186b67950c37be4a503943df93c668fb41ba7420481a58de08a56dc4e