[INFO] fetching crate app-world 0.3.0... [INFO] testing app-world-0.3.0 against master#d7bd9cd469ff6871420007f091ef52fc32d2ca99 for pr-121417 [INFO] extracting crate app-world 0.3.0 into /workspace/builds/worker-5-tc1/source [INFO] validating manifest of crates.io crate app-world 0.3.0 on toolchain d7bd9cd469ff6871420007f091ef52fc32d2ca99 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate app-world 0.3.0 [INFO] finished tweaking crates.io crate app-world 0.3.0 [INFO] tweaked toml for crates.io crate app-world 0.3.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 1f9bf70039421b8414c2834c6949a043b5cacc18e48197ffe22b298f063b0ee4 [INFO] running `Command { std: "docker" "start" "-a" "1f9bf70039421b8414c2834c6949a043b5cacc18e48197ffe22b298f063b0ee4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "1f9bf70039421b8414c2834c6949a043b5cacc18e48197ffe22b298f063b0ee4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1f9bf70039421b8414c2834c6949a043b5cacc18e48197ffe22b298f063b0ee4", kill_on_drop: false }` [INFO] [stdout] 1f9bf70039421b8414c2834c6949a043b5cacc18e48197ffe22b298f063b0ee4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 35a9f5b7bce2b1bf28739569575e0e69ef3a3931b38e330a2ba0cd7bd5b8b4a3 [INFO] running `Command { std: "docker" "start" "-a" "35a9f5b7bce2b1bf28739569575e0e69ef3a3931b38e330a2ba0cd7bd5b8b4a3", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.04s [INFO] running `Command { std: "docker" "inspect" "35a9f5b7bce2b1bf28739569575e0e69ef3a3931b38e330a2ba0cd7bd5b8b4a3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "35a9f5b7bce2b1bf28739569575e0e69ef3a3931b38e330a2ba0cd7bd5b8b4a3", kill_on_drop: false }` [INFO] [stdout] 35a9f5b7bce2b1bf28739569575e0e69ef3a3931b38e330a2ba0cd7bd5b8b4a3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 24681d4e4621a0c5d38250305c8e1ff6b3b5b93a6c6fc696f06b48b1ce8d1640 [INFO] running `Command { std: "docker" "start" "-a" "24681d4e4621a0c5d38250305c8e1ff6b3b5b93a6c6fc696f06b48b1ce8d1640", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s [INFO] running `Command { std: "docker" "inspect" "24681d4e4621a0c5d38250305c8e1ff6b3b5b93a6c6fc696f06b48b1ce8d1640", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "24681d4e4621a0c5d38250305c8e1ff6b3b5b93a6c6fc696f06b48b1ce8d1640", kill_on_drop: false }` [INFO] [stdout] 24681d4e4621a0c5d38250305c8e1ff6b3b5b93a6c6fc696f06b48b1ce8d1640 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 2587c49e97cb0a42afe49967efb19086eb446e65daa6ab6467dc3905921c082f [INFO] running `Command { std: "docker" "start" "-a" "2587c49e97cb0a42afe49967efb19086eb446e65daa6ab6467dc3905921c082f", 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/app_world-829f9c17097e4028) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test tests::two_non_colliding_reads ... ok [INFO] [stdout] test tests::deadlock_prevention_same_thread_double_read_another_thread_write - should panic ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::deadlock_prevention_same_thread_double_read_another_thread_write stdout ---- [INFO] [stdout] thread '' panicked at src/lib.rs:147:17: [INFO] [stdout] Thread already holds read guard [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55ffc303b266 - std::backtrace_rs::backtrace::libunwind::trace::hf12510db56099dfb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55ffc303b266 - std::backtrace_rs::backtrace::trace_unsynchronized::h94bf70956aef5b0b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55ffc303b266 - std::sys_common::backtrace::_print_fmt::ha7d7986858ac8c0a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55ffc303b266 - ::fmt::h372c1ec7a663a1ab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55ffc3062b3c - core::fmt::rt::Argument::fmt::hecbea7268359d058 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55ffc3062b3c - core::fmt::write::h4912438cc1e3f359 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55ffc3038b1f - std::io::Write::write_fmt::hf3498c39593ae16b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55ffc303b014 - std::sys_common::backtrace::_print::h37e973c7e1a1b050 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55ffc303b014 - std::sys_common::backtrace::print::h07b06dbedbb17b31 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55ffc303cd0b - std::panicking::default_hook::{{closure}}::he72022d4e9537bcf [INFO] [stdout] 10: 0x55ffc303c9dd - std::panicking::default_hook::h04cdc89992c8479b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55ffc300c8a7 - as core::ops::function::Fn>::call::h815f3e5bf035c1a1 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55ffc300c8a7 - test::test_main::{{closure}}::hc6d17bd58ba26ad4 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55ffc303d340 - as core::ops::function::Fn>::call::hec33fe851e726d9b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55ffc303d340 - std::panicking::rust_panic_with_hook::hf07491bf03f90571 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55ffc303d049 - std::panicking::begin_panic_handler::{{closure}}::h92e879536c12f298 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:652:13 [INFO] [stdout] 16: 0x55ffc303b746 - std::sys_common::backtrace::__rust_end_short_backtrace::h3800ba1351aa8eea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55ffc303cdb4 - rust_begin_unwind [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55ffc2fcc875 - core::panicking::panic_fmt::h83446605a04b4380 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55ffc2fd625b - app_world::AppWorldWrapper::read::{{closure}}::h5d153b8b9411cf71 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:147:17 [INFO] [stdout] 20: 0x55ffc2fd2b2e - std::thread::local::LocalKey::try_with::h7c17213ac3fb9f46 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/local.rs:286:16 [INFO] [stdout] 21: 0x55ffc2fd2a0e - std::thread::local::LocalKey::with::h56921b84fe7b5f44 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/local.rs:262:9 [INFO] [stdout] 22: 0x55ffc2fd60e3 - app_world::AppWorldWrapper::read::h768466ce51886412 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:143:9 [INFO] [stdout] 23: 0x55ffc2fd1ab2 - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::{{closure}}::hffc249bab6f3925b [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:245:27 [INFO] [stdout] 24: 0x55ffc2fcd013 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6d5e8d86368918f [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 25: 0x55ffc2fd4ef3 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h6489a2c3eea09e12 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 26: 0x55ffc2fccfd3 - as core::ops::function::FnOnce<()>>::call_once::h993554c597ac5768 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x55ffc2fce460 - std::panicking::try::do_call::ha4118f5182832f99 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 28: 0x55ffc2fd1e7b - __rust_try [INFO] [stdout] 29: 0x55ffc2fce2be - std::panicking::try::h6ec83ee681a8cef3 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 30: 0x55ffc2fd4d2c - std::panic::catch_unwind::h711e197cf583faab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 31: 0x55ffc2fd4d2c - std::thread::Builder::spawn_unchecked_::{{closure}}::h92b75bad76d05572 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 32: 0x55ffc2fce91e - core::ops::function::FnOnce::call_once{{vtable.shim}}::ha729a3adcd663c12 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 33: 0x55ffc30415a5 - as core::ops::function::FnOnce>::call_once::hce6bcef84364f9de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 34: 0x55ffc30415a5 - as core::ops::function::FnOnce>::call_once::h5656bb08f6ae0836 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 35: 0x55ffc30415a5 - std::sys::pal::unix::thread::Thread::new::thread_start::h42aad656803ef398 [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 36: 0x7ffb516ddac3 - [INFO] [stdout] 37: 0x7ffb5176ebf4 - __clone [INFO] [stdout] 38: 0x0 - [INFO] [stdout] thread 'tests::deadlock_prevention_same_thread_double_read_another_thread_write' panicked at src/lib.rs:253:9: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: false [INFO] [stdout] right: true [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55ffc303b266 - std::backtrace_rs::backtrace::libunwind::trace::hf12510db56099dfb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55ffc303b266 - std::backtrace_rs::backtrace::trace_unsynchronized::h94bf70956aef5b0b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55ffc303b266 - std::sys_common::backtrace::_print_fmt::ha7d7986858ac8c0a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55ffc303b266 - ::fmt::h372c1ec7a663a1ab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55ffc3062b3c - core::fmt::rt::Argument::fmt::hecbea7268359d058 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55ffc3062b3c - core::fmt::write::h4912438cc1e3f359 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55ffc3038b1f - std::io::Write::write_fmt::hf3498c39593ae16b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55ffc303b014 - std::sys_common::backtrace::_print::h37e973c7e1a1b050 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55ffc303b014 - std::sys_common::backtrace::print::h07b06dbedbb17b31 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55ffc303cd0b - std::panicking::default_hook::{{closure}}::he72022d4e9537bcf [INFO] [stdout] 10: 0x55ffc303c9dd - std::panicking::default_hook::h04cdc89992c8479b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55ffc300c8a7 - as core::ops::function::Fn>::call::h815f3e5bf035c1a1 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55ffc300c8a7 - test::test_main::{{closure}}::hc6d17bd58ba26ad4 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55ffc303d340 - as core::ops::function::Fn>::call::hec33fe851e726d9b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55ffc303d340 - std::panicking::rust_panic_with_hook::hf07491bf03f90571 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55ffc303d082 - std::panicking::begin_panic_handler::{{closure}}::h92e879536c12f298 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55ffc303b746 - std::sys_common::backtrace::__rust_end_short_backtrace::h3800ba1351aa8eea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55ffc303cdb4 - rust_begin_unwind [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55ffc2fcc875 - core::panicking::panic_fmt::h83446605a04b4380 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55ffc2fccbbb - core::panicking::assert_failed_inner::h4eefd8988f8695de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:342:17 [INFO] [stdout] 20: 0x55ffc2fd0a7e - core::panicking::assert_failed::h5f91856a48302abf [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:297:5 [INFO] [stdout] 21: 0x55ffc2fd182c - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::h4eb639596d83f18f [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:253:9 [INFO] [stdout] 22: 0x55ffc2fd1597 - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::{{closure}}::h795dd0edeead4964 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:231:74 [INFO] [stdout] 23: 0x55ffc2fcea96 - core::ops::function::FnOnce::call_once::hf4d002a968c44072 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x55ffc301204f - core::ops::function::FnOnce::call_once::h2088a64ea35e9f55 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x55ffc301204f - test::__rust_begin_short_backtrace::h3e3262ca517e0f6a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:620:18 [INFO] [stdout] 26: 0x55ffc3010c20 - test::run_test_in_process::{{closure}}::h6b4258191636288b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:60 [INFO] [stdout] 27: 0x55ffc3010c20 - as core::ops::function::FnOnce<()>>::call_once::h017d06cfb8bed9ea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x55ffc3010c20 - std::panicking::try::do_call::hd5a0dc4bb374d709 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 29: 0x55ffc3010c20 - std::panicking::try::h3168a4ce5bf4579d [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 30: 0x55ffc3010c20 - std::panic::catch_unwind::hb64c62facfdc3e4a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 31: 0x55ffc3010c20 - test::run_test_in_process::h127b8bcabd50528a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:27 [INFO] [stdout] 32: 0x55ffc3010c20 - test::run_test::{{closure}}::h0fdd7a3e6b40f361 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:566:43 [INFO] [stdout] 33: 0x55ffc2fd8fb3 - test::run_test::{{closure}}::h48b321fe4dddd4b5 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:594:41 [INFO] [stdout] 34: 0x55ffc2fd8fb3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hc21ce17eb804db15 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 35: 0x55ffc2fddcdf - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h09231cb1ff02341f [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 36: 0x55ffc2fddcdf - as core::ops::function::FnOnce<()>>::call_once::h39609956c133d940 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x55ffc2fddcdf - std::panicking::try::do_call::hc39de059392fc838 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 38: 0x55ffc2fddcdf - std::panicking::try::h8e296b7f146034ae [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 39: 0x55ffc2fddcdf - std::panic::catch_unwind::hbd1bff6c3e457dcd [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 40: 0x55ffc2fddcdf - std::thread::Builder::spawn_unchecked_::{{closure}}::h879be7a53fe7d1d6 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 41: 0x55ffc2fddcdf - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0fa08594624076ec [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x55ffc30415a5 - as core::ops::function::FnOnce>::call_once::hce6bcef84364f9de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 43: 0x55ffc30415a5 - as core::ops::function::FnOnce>::call_once::h5656bb08f6ae0836 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 44: 0x55ffc30415a5 - std::sys::pal::unix::thread::Thread::new::thread_start::h42aad656803ef398 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 45: 0x7ffb516ddac3 - [INFO] [stdout] 46: 0x7ffb5176ebf4 - __clone [INFO] [stdout] 47: 0x0 - [INFO] [stdout] note: panic did not contain expected string [INFO] [stdout] panic message: `"assertion `left == right` failed\n left: false\n right: true"`, [INFO] [stdout] expected substring: `"Second read attempt panicked"` [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::deadlock_prevention_same_thread_double_read_another_thread_write [INFO] [stdout] [INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "2587c49e97cb0a42afe49967efb19086eb446e65daa6ab6467dc3905921c082f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2587c49e97cb0a42afe49967efb19086eb446e65daa6ab6467dc3905921c082f", kill_on_drop: false }` [INFO] [stdout] 2587c49e97cb0a42afe49967efb19086eb446e65daa6ab6467dc3905921c082f [INFO] testing app-world-0.3.0 against try#393ef12c970fbc7f294cd96c35cb76f9591bc1d6 for pr-121417 [INFO] extracting crate app-world 0.3.0 into /workspace/builds/worker-5-tc2/source [INFO] validating manifest of crates.io crate app-world 0.3.0 on toolchain 393ef12c970fbc7f294cd96c35cb76f9591bc1d6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate app-world 0.3.0 [INFO] finished tweaking crates.io crate app-world 0.3.0 [INFO] tweaked toml for crates.io crate app-world 0.3.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] f0439e1002a195157d0c6d25c6997209e608b9b3a063c9d22f3ef5d1023151a5 [INFO] running `Command { std: "docker" "start" "-a" "f0439e1002a195157d0c6d25c6997209e608b9b3a063c9d22f3ef5d1023151a5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "f0439e1002a195157d0c6d25c6997209e608b9b3a063c9d22f3ef5d1023151a5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f0439e1002a195157d0c6d25c6997209e608b9b3a063c9d22f3ef5d1023151a5", kill_on_drop: false }` [INFO] [stdout] f0439e1002a195157d0c6d25c6997209e608b9b3a063c9d22f3ef5d1023151a5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d77302a962101f84daaeeec67e5a9bbd880bbc9cbf71c6a0d9134dc2bcb0e3d7 [INFO] running `Command { std: "docker" "start" "-a" "d77302a962101f84daaeeec67e5a9bbd880bbc9cbf71c6a0d9134dc2bcb0e3d7", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.10s [INFO] running `Command { std: "docker" "inspect" "d77302a962101f84daaeeec67e5a9bbd880bbc9cbf71c6a0d9134dc2bcb0e3d7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d77302a962101f84daaeeec67e5a9bbd880bbc9cbf71c6a0d9134dc2bcb0e3d7", kill_on_drop: false }` [INFO] [stdout] d77302a962101f84daaeeec67e5a9bbd880bbc9cbf71c6a0d9134dc2bcb0e3d7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5f212b69568fb793cb408cab21b4b8e59e3cfe3e139d01f92547454ae1fa8180 [INFO] running `Command { std: "docker" "start" "-a" "5f212b69568fb793cb408cab21b4b8e59e3cfe3e139d01f92547454ae1fa8180", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.52s [INFO] running `Command { std: "docker" "inspect" "5f212b69568fb793cb408cab21b4b8e59e3cfe3e139d01f92547454ae1fa8180", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5f212b69568fb793cb408cab21b4b8e59e3cfe3e139d01f92547454ae1fa8180", kill_on_drop: false }` [INFO] [stdout] 5f212b69568fb793cb408cab21b4b8e59e3cfe3e139d01f92547454ae1fa8180 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4e80e079525268693a19b52bfbda96081c8d1890f995b603d7f09d88db303a13 [INFO] running `Command { std: "docker" "start" "-a" "4e80e079525268693a19b52bfbda96081c8d1890f995b603d7f09d88db303a13", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/app_world-829f9c17097e4028) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test tests::two_non_colliding_reads ... ok [INFO] [stdout] test tests::deadlock_prevention_same_thread_double_read_another_thread_write - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s [INFO] [stdout] [INFO] [stderr] Doc-tests app-world [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test src/lib.rs - AppWorld::Message (line 99) ... ok [INFO] [stdout] test src/lib.rs - (line 10) ... ok [INFO] [stdout] test src/lib.rs - (line 39) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "4e80e079525268693a19b52bfbda96081c8d1890f995b603d7f09d88db303a13", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4e80e079525268693a19b52bfbda96081c8d1890f995b603d7f09d88db303a13", kill_on_drop: false }` [INFO] [stdout] 4e80e079525268693a19b52bfbda96081c8d1890f995b603d7f09d88db303a13