[INFO] fetching crate sync_cow 0.1.1... [INFO] testing sync_cow-0.1.1 against master#9e136a30a965bf4e63f03095c57df7257bf96fd6 for pr-134530 [INFO] extracting crate sync_cow 0.1.1 into /workspace/builds/worker-2-tc1/source [INFO] validating manifest of crates.io crate sync_cow 0.1.1 on toolchain 9e136a30a965bf4e63f03095c57df7257bf96fd6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate sync_cow 0.1.1 [INFO] removed 0 missing examples [INFO] finished tweaking crates.io crate sync_cow 0.1.1 [INFO] tweaked toml for crates.io crate sync_cow 0.1.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml [INFO] crate crates.io crate sync_cow 0.1.1 already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] de7bf147978cc0d0570142ac128b52ed069af115496f417671a6687235e91415 [INFO] running `Command { std: "docker" "start" "-a" "de7bf147978cc0d0570142ac128b52ed069af115496f417671a6687235e91415", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "de7bf147978cc0d0570142ac128b52ed069af115496f417671a6687235e91415", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "de7bf147978cc0d0570142ac128b52ed069af115496f417671a6687235e91415", kill_on_drop: false }` [INFO] [stdout] de7bf147978cc0d0570142ac128b52ed069af115496f417671a6687235e91415 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 86eaff7490e56265485686e5c1223857c4a02ce67c8586fd00c7a0977ef4f750 [INFO] running `Command { std: "docker" "start" "-a" "86eaff7490e56265485686e5c1223857c4a02ce67c8586fd00c7a0977ef4f750", kill_on_drop: false }` [INFO] [stderr] Compiling sync_cow v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.05s [INFO] running `Command { std: "docker" "inspect" "86eaff7490e56265485686e5c1223857c4a02ce67c8586fd00c7a0977ef4f750", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "86eaff7490e56265485686e5c1223857c4a02ce67c8586fd00c7a0977ef4f750", kill_on_drop: false }` [INFO] [stdout] 86eaff7490e56265485686e5c1223857c4a02ce67c8586fd00c7a0977ef4f750 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] dc70a9b79bbae2e43989d8dfacfa892bbbaabd0e17669fc65db39da3c91805d7 [INFO] running `Command { std: "docker" "start" "-a" "dc70a9b79bbae2e43989d8dfacfa892bbbaabd0e17669fc65db39da3c91805d7", kill_on_drop: false }` [INFO] [stderr] Compiling sync_cow v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.32s [INFO] running `Command { std: "docker" "inspect" "dc70a9b79bbae2e43989d8dfacfa892bbbaabd0e17669fc65db39da3c91805d7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dc70a9b79bbae2e43989d8dfacfa892bbbaabd0e17669fc65db39da3c91805d7", kill_on_drop: false }` [INFO] [stdout] dc70a9b79bbae2e43989d8dfacfa892bbbaabd0e17669fc65db39da3c91805d7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 840adf7ddae6d60905a703d407370f84e437d135abc101a8e2985cc60f0888b7 [INFO] running `Command { std: "docker" "start" "-a" "840adf7ddae6d60905a703d407370f84e437d135abc101a8e2985cc60f0888b7", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sync_cow-912950d581c66bed) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test tests::cow_faster_than_rwlock_nosleep ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::cow_faster_than_rwlock_nosleep stdout ---- [INFO] [stdout] 9195 [INFO] [stdout] Got 98 last, but 100 then [INFO] [stdout] 9151 [INFO] [stdout] 9162 [INFO] [stdout] 9248 [INFO] [stdout] 9148 [INFO] [stdout] 9241 [INFO] [stdout] 9244 [INFO] [stdout] 9082 [INFO] [stdout] 9089 [INFO] [stdout] 9220 [INFO] [stdout] thread 'tests::cow_faster_than_rwlock_nosleep' panicked at src/tests.rs:128:9: [INFO] [stdout] The readers did not catch all writes sequentially, probably they were too slow [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x628f268ff3da - std::backtrace_rs::backtrace::libunwind::trace::h45f575fdcec9cdc1 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x628f268ff3da - std::backtrace_rs::backtrace::trace_unsynchronized::heed7aaaa28209ac3 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x628f268ff3da - std::sys::backtrace::_print_fmt::h47d1e3547844619c [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x628f268ff3da - ::fmt::h6c37de0361fa7462 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x628f26925c53 - core::fmt::rt::Argument::fmt::h6606d9b5304941e1 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/fmt/rt.rs:177:76 [INFO] [stdout] 5: 0x628f26925c53 - core::fmt::write::hf77fe0583268bf67 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/fmt/mod.rs:1437:21 [INFO] [stdout] 6: 0x628f268fc0d3 - std::io::Write::write_fmt::h672ff980e46892ac [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/io/mod.rs:1887:15 [INFO] [stdout] 7: 0x628f268ff222 - std::sys::backtrace::BacktraceLock::print::hce84519ec0e7588e [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x628f2690085e - std::panicking::default_hook::{{closure}}::h6e0433c33d1ed12b [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:284:22 [INFO] [stdout] 9: 0x628f26900667 - std::panicking::default_hook::h19d4e9e8d7fba9e2 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:308:9 [INFO] [stdout] 10: 0x628f268c9b38 - as core::ops::function::Fn>::call::hd2aa5731f018e269 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/alloc/src/boxed.rs:1984:9 [INFO] [stdout] 11: 0x628f268c9b38 - test::test_main::{{closure}}::h1ae6fb8f80aa49b6 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:134:21 [INFO] [stdout] 12: 0x628f26901078 - as core::ops::function::Fn>::call::h4f2af756974b90d5 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/alloc/src/boxed.rs:1984:9 [INFO] [stdout] 13: 0x628f26901078 - std::panicking::rust_panic_with_hook::hd21771b96ea64164 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:825:13 [INFO] [stdout] 14: 0x628f26900cf6 - std::panicking::begin_panic_handler::{{closure}}::h2e177f984fc72cbb [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:683:13 [INFO] [stdout] 15: 0x628f268ff8b9 - std::sys::backtrace::__rust_end_short_backtrace::ha7f0445003f40b38 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/backtrace.rs:168:18 [INFO] [stdout] 16: 0x628f269009bd - rust_begin_unwind [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:681:5 [INFO] [stdout] 17: 0x628f26924700 - core::panicking::panic_fmt::h85741df7bc4071b6 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/panicking.rs:75:14 [INFO] [stdout] 18: 0x628f2688859e - sync_cow::tests::write_and_read_alot::h73a5b2356af786aa [INFO] [stdout] at /opt/rustwide/workdir/src/tests.rs:128:9 [INFO] [stdout] 19: 0x628f2688a836 - sync_cow::tests::cow_faster_than_rwlock_nosleep::hdbfac40b3860c77b [INFO] [stdout] at /opt/rustwide/workdir/src/tests.rs:10:9 [INFO] [stdout] 20: 0x628f2688a7a7 - sync_cow::tests::cow_faster_than_rwlock_nosleep::{{closure}}::h7e093dd9c0e1c02e [INFO] [stdout] at /opt/rustwide/workdir/src/tests.rs:6:36 [INFO] [stdout] 21: 0x628f2688c046 - core::ops::function::FnOnce::call_once::h5e0762a563615dfe [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x628f268ce77b - core::ops::function::FnOnce::call_once::hc1e06e2a5640370a [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x628f268ce77b - test::__rust_begin_short_backtrace::heb5aa654e2797f5b [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:632:18 [INFO] [stdout] 24: 0x628f268ce089 - test::run_test_in_process::{{closure}}::h1329474a96646ad1 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:655:60 [INFO] [stdout] 25: 0x628f268ce089 - as core::ops::function::FnOnce<()>>::call_once::hc87a7a8694e6d844 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x628f268ce089 - std::panicking::try::do_call::h3b8456af8bacf88f [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:573:40 [INFO] [stdout] 27: 0x628f268ce089 - std::panicking::try::hc177f95c6592cea1 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:536:19 [INFO] [stdout] 28: 0x628f268ce089 - std::panic::catch_unwind::h847b2adf17f7da7a [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panic.rs:358:14 [INFO] [stdout] 29: 0x628f268ce089 - test::run_test_in_process::h824f7775d92af51e [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:655:27 [INFO] [stdout] 30: 0x628f268ce089 - test::run_test::{{closure}}::hdfaf20d18f7af8f6 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:576:43 [INFO] [stdout] 31: 0x628f26890e2a - test::run_test::{{closure}}::he83661a0a1c97403 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/test/src/lib.rs:606:41 [INFO] [stdout] 32: 0x628f26890e2a - std::sys::backtrace::__rust_begin_short_backtrace::hd3a78fd401685e9b [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/backtrace.rs:152:18 [INFO] [stdout] 33: 0x628f26894866 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h78d6a457dee8dc60 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/thread/mod.rs:561:17 [INFO] [stdout] 34: 0x628f26894866 - as core::ops::function::FnOnce<()>>::call_once::hc4069b3cc6600bde [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x628f26894866 - std::panicking::try::do_call::h0dde49ad0af85fa8 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:573:40 [INFO] [stdout] 36: 0x628f26894866 - std::panicking::try::h918d0d3a7e94727d [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panicking.rs:536:19 [INFO] [stdout] 37: 0x628f26894866 - std::panic::catch_unwind::h7016984cdcdd8b9e [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/panic.rs:358:14 [INFO] [stdout] 38: 0x628f26894866 - std::thread::Builder::spawn_unchecked_::{{closure}}::h2766f51195a4a65d [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/thread/mod.rs:559:30 [INFO] [stdout] 39: 0x628f26894866 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbd6e39e4ea63a011 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x628f26904f4b - as core::ops::function::FnOnce>::call_once::h34bec2a4cdedc102 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/alloc/src/boxed.rs:1970:9 [INFO] [stdout] 41: 0x628f26904f4b - as core::ops::function::FnOnce>::call_once::ha62c0d1861fdf293 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/alloc/src/boxed.rs:1970:9 [INFO] [stdout] 42: 0x628f26904f4b - std::sys::pal::unix::thread::Thread::new::thread_start::h71fe57097907b3f8 [INFO] [stdout] at /rustc/9e136a30a965bf4e63f03095c57df7257bf96fd6/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 43: 0x72134efdaac3 - [INFO] [stdout] 44: 0x72134f06ba04 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::cow_faster_than_rwlock_nosleep [INFO] [stdout] [INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.99s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "840adf7ddae6d60905a703d407370f84e437d135abc101a8e2985cc60f0888b7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "840adf7ddae6d60905a703d407370f84e437d135abc101a8e2985cc60f0888b7", kill_on_drop: false }` [INFO] [stdout] 840adf7ddae6d60905a703d407370f84e437d135abc101a8e2985cc60f0888b7