[INFO] cloning repository https://github.com/chinedufn/app-world [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/chinedufn/app-world" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchinedufn%2Fapp-world", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchinedufn%2Fapp-world'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] d82713e9f96ed816532ceb144e5ef447308c3424 [INFO] testing chinedufn/app-world against master#ff2c56344c764af598ad33027e9c7a48881808ef for pr-118825 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchinedufn%2Fapp-world" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/chinedufn/app-world on toolchain ff2c56344c764af598ad33027e9c7a48881808ef [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/chinedufn/app-world [INFO] finished tweaking git repo https://github.com/chinedufn/app-world [INFO] tweaked toml for git repo https://github.com/chinedufn/app-world written to /workspace/builds/worker-4-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 8e4edc38b50333dd18c40d0f8db7f9743925d90b41b61aa8c6012288e7cd608c [INFO] running `Command { std: "docker" "start" "-a" "8e4edc38b50333dd18c40d0f8db7f9743925d90b41b61aa8c6012288e7cd608c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "8e4edc38b50333dd18c40d0f8db7f9743925d90b41b61aa8c6012288e7cd608c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8e4edc38b50333dd18c40d0f8db7f9743925d90b41b61aa8c6012288e7cd608c", kill_on_drop: false }` [INFO] [stdout] 8e4edc38b50333dd18c40d0f8db7f9743925d90b41b61aa8c6012288e7cd608c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0efecc73fbd273712682674e513891c2c11168416a36a1121d2f50caa802a976 [INFO] running `Command { std: "docker" "start" "-a" "0efecc73fbd273712682674e513891c2c11168416a36a1121d2f50caa802a976", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.05s [INFO] running `Command { std: "docker" "inspect" "0efecc73fbd273712682674e513891c2c11168416a36a1121d2f50caa802a976", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0efecc73fbd273712682674e513891c2c11168416a36a1121d2f50caa802a976", kill_on_drop: false }` [INFO] [stdout] 0efecc73fbd273712682674e513891c2c11168416a36a1121d2f50caa802a976 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d7949dcaea7130e40bd9c7e2e13140698a5ca9540313d7dfd36cdbdd4be70956 [INFO] running `Command { std: "docker" "start" "-a" "d7949dcaea7130e40bd9c7e2e13140698a5ca9540313d7dfd36cdbdd4be70956", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.28s [INFO] running `Command { std: "docker" "inspect" "d7949dcaea7130e40bd9c7e2e13140698a5ca9540313d7dfd36cdbdd4be70956", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d7949dcaea7130e40bd9c7e2e13140698a5ca9540313d7dfd36cdbdd4be70956", kill_on_drop: false }` [INFO] [stdout] d7949dcaea7130e40bd9c7e2e13140698a5ca9540313d7dfd36cdbdd4be70956 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 6bf67541707e22738ead1e0d571eb40264fb8aa16b0cf713f7dba22f6905c3a1 [INFO] running `Command { std: "docker" "start" "-a" "6bf67541707e22738ead1e0d571eb40264fb8aa16b0cf713f7dba22f6905c3a1", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.00s [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.06s [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.16s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "6bf67541707e22738ead1e0d571eb40264fb8aa16b0cf713f7dba22f6905c3a1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6bf67541707e22738ead1e0d571eb40264fb8aa16b0cf713f7dba22f6905c3a1", kill_on_drop: false }` [INFO] [stdout] 6bf67541707e22738ead1e0d571eb40264fb8aa16b0cf713f7dba22f6905c3a1 [INFO] testing chinedufn/app-world against try#5e4273472e1b2f223618a9b3def795f6224f241d for pr-118825 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchinedufn%2Fapp-world" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/chinedufn/app-world on toolchain 5e4273472e1b2f223618a9b3def795f6224f241d [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/chinedufn/app-world [INFO] finished tweaking git repo https://github.com/chinedufn/app-world [INFO] tweaked toml for git repo https://github.com/chinedufn/app-world written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 45197f67668b094492610daaf785786100afb5becb9107abf6776e4187f6f988 [INFO] running `Command { std: "docker" "start" "-a" "45197f67668b094492610daaf785786100afb5becb9107abf6776e4187f6f988", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "45197f67668b094492610daaf785786100afb5becb9107abf6776e4187f6f988", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "45197f67668b094492610daaf785786100afb5becb9107abf6776e4187f6f988", kill_on_drop: false }` [INFO] [stdout] 45197f67668b094492610daaf785786100afb5becb9107abf6776e4187f6f988 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4a35b10ba89c0723057e63091d3a498dec1a27c46b8e7ed2c45b5f443398c9a2 [INFO] running `Command { std: "docker" "start" "-a" "4a35b10ba89c0723057e63091d3a498dec1a27c46b8e7ed2c45b5f443398c9a2", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.07s [INFO] running `Command { std: "docker" "inspect" "4a35b10ba89c0723057e63091d3a498dec1a27c46b8e7ed2c45b5f443398c9a2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4a35b10ba89c0723057e63091d3a498dec1a27c46b8e7ed2c45b5f443398c9a2", kill_on_drop: false }` [INFO] [stdout] 4a35b10ba89c0723057e63091d3a498dec1a27c46b8e7ed2c45b5f443398c9a2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 980793abffceefca99cafd8796fad7607571f3be7dc596fcf7430facf175080c [INFO] running `Command { std: "docker" "start" "-a" "980793abffceefca99cafd8796fad7607571f3be7dc596fcf7430facf175080c", kill_on_drop: false }` [INFO] [stderr] Compiling app-world v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.27s [INFO] running `Command { std: "docker" "inspect" "980793abffceefca99cafd8796fad7607571f3be7dc596fcf7430facf175080c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "980793abffceefca99cafd8796fad7607571f3be7dc596fcf7430facf175080c", kill_on_drop: false }` [INFO] [stdout] 980793abffceefca99cafd8796fad7607571f3be7dc596fcf7430facf175080c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] fffcb54b45ab0c6f571ce3c79b511670fb70a2c9c860d3a8bd3974ccd731b267 [INFO] running `Command { std: "docker" "start" "-a" "fffcb54b45ab0c6f571ce3c79b511670fb70a2c9c860d3a8bd3974ccd731b267", kill_on_drop: false }` [INFO] [stderr] Finished test [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: 0x557c12474b5c - std::backtrace_rs::backtrace::libunwind::trace::he911f9c907837597 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x557c12474b5c - std::backtrace_rs::backtrace::trace_unsynchronized::hb30926ceb4a5ace8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x557c12474b5c - std::sys_common::backtrace::_print_fmt::h20b4c3cb4b8e01de [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x557c12474b5c - ::fmt::hcb347474accdbb50 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x557c1249e7a0 - core::fmt::rt::Argument::fmt::h0959df1f48ddd7a2 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x557c1249e7a0 - core::fmt::write::hb02961b4380cf4e7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x557c1247262f - std::io::Write::write_fmt::h529bf90db5fd4c64 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/io/mod.rs:1810:15 [INFO] [stdout] 7: 0x557c12474944 - std::sys_common::backtrace::_print::h9f31dc4772e05e46 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x557c12474944 - std::sys_common::backtrace::print::he1974c4a8ca14219 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x557c12476677 - std::panicking::default_hook::{{closure}}::h9663c9f0f27e933c [INFO] [stdout] 10: 0x557c12476363 - std::panicking::default_hook::h232155e937189ed1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x557c12446c97 - as core::ops::function::Fn>::call::h30a8b128ee88d398 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 12: 0x557c12446c97 - test::test_main::{{closure}}::hcafb25d50378ac2e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:138:21 [INFO] [stdout] 13: 0x557c12476c88 - as core::ops::function::Fn>::call::hb0500737c93e9fc8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 14: 0x557c12476c88 - std::panicking::rust_panic_with_hook::hc8764d1694e821a7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:783:13 [INFO] [stdout] 15: 0x557c124769a9 - std::panicking::begin_panic_handler::{{closure}}::hf3ed629468815f4d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:649:13 [INFO] [stdout] 16: 0x557c12475026 - std::sys_common::backtrace::__rust_end_short_backtrace::h4e08af68b8432e1a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x557c12476742 - rust_begin_unwind [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:645:5 [INFO] [stdout] 18: 0x557c12406cf5 - core::panicking::panic_fmt::hd274135c92cc1bf8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x557c1240f83b - app_world::AppWorldWrapper::read::{{closure}}::hd5c81171d1d24dfe [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:147:17 [INFO] [stdout] 20: 0x557c1240c910 - std::thread::local::LocalKey::try_with::h4535133907169e96 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/local.rs:270:16 [INFO] [stdout] 21: 0x557c1240c7ae - std::thread::local::LocalKey::with::h18a8e82073e13a22 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/local.rs:246:9 [INFO] [stdout] 22: 0x557c1240f743 - app_world::AppWorldWrapper::read::hbb41db1d58b528a1 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:143:9 [INFO] [stdout] 23: 0x557c1240b5d9 - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::{{closure}}::h2adcda887b0151b9 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:245:27 [INFO] [stdout] 24: 0x557c124075f3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hc765341171313657 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 25: 0x557c12411cd3 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h88d3052029e2cda3 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 26: 0x557c12407593 - as core::ops::function::FnOnce<()>>::call_once::hccea91b4ec26a80e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x557c12408750 - std::panicking::try::do_call::hcb14c73e2f535c48 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:552:40 [INFO] [stdout] 28: 0x557c1240b8db - __rust_try [INFO] [stdout] 29: 0x557c124086de - std::panicking::try::hb0753034099d8a77 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:516:19 [INFO] [stdout] 30: 0x557c12411b10 - std::panic::catch_unwind::h17a4b5f5ced143f8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panic.rs:142:14 [INFO] [stdout] 31: 0x557c12411b10 - std::thread::Builder::spawn_unchecked_::{{closure}}::hf42a3d76441381e1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 32: 0x557c12408e0e - core::ops::function::FnOnce::call_once{{vtable.shim}}::h2e4540440c9ad2ed [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 33: 0x557c1247b6e5 - as core::ops::function::FnOnce>::call_once::h6cea6bfb156e0019 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 34: 0x557c1247b6e5 - as core::ops::function::FnOnce>::call_once::h3c499441c8da3187 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 35: 0x557c1247b6e5 - std::sys::unix::thread::Thread::new::thread_start::h45692980afba103a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 36: 0x7f7c7bbdfac3 - [INFO] [stdout] 37: 0x7f7c7bc70bf4 - __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: 0x557c12474b5c - std::backtrace_rs::backtrace::libunwind::trace::he911f9c907837597 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x557c12474b5c - std::backtrace_rs::backtrace::trace_unsynchronized::hb30926ceb4a5ace8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x557c12474b5c - std::sys_common::backtrace::_print_fmt::h20b4c3cb4b8e01de [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x557c12474b5c - ::fmt::hcb347474accdbb50 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x557c1249e7a0 - core::fmt::rt::Argument::fmt::h0959df1f48ddd7a2 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x557c1249e7a0 - core::fmt::write::hb02961b4380cf4e7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x557c1247262f - std::io::Write::write_fmt::h529bf90db5fd4c64 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/io/mod.rs:1810:15 [INFO] [stdout] 7: 0x557c12474944 - std::sys_common::backtrace::_print::h9f31dc4772e05e46 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x557c12474944 - std::sys_common::backtrace::print::he1974c4a8ca14219 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x557c12476677 - std::panicking::default_hook::{{closure}}::h9663c9f0f27e933c [INFO] [stdout] 10: 0x557c12476363 - std::panicking::default_hook::h232155e937189ed1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x557c12446c97 - as core::ops::function::Fn>::call::h30a8b128ee88d398 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 12: 0x557c12446c97 - test::test_main::{{closure}}::hcafb25d50378ac2e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:138:21 [INFO] [stdout] 13: 0x557c12476c88 - as core::ops::function::Fn>::call::hb0500737c93e9fc8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 14: 0x557c12476c88 - std::panicking::rust_panic_with_hook::hc8764d1694e821a7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:783:13 [INFO] [stdout] 15: 0x557c124769de - std::panicking::begin_panic_handler::{{closure}}::hf3ed629468815f4d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:657:13 [INFO] [stdout] 16: 0x557c12475026 - std::sys_common::backtrace::__rust_end_short_backtrace::h4e08af68b8432e1a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x557c12476742 - rust_begin_unwind [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:645:5 [INFO] [stdout] 18: 0x557c12406cf5 - core::panicking::panic_fmt::hd274135c92cc1bf8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x557c1240703b - core::panicking::assert_failed_inner::h0e7060ac34fab196 [INFO] [stdout] 20: 0x557c1240a2ee - core::panicking::assert_failed::h09240c9a0179066a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panicking.rs:297:5 [INFO] [stdout] 21: 0x557c1240b34c - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::h754e1f1969a806bb [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:253:9 [INFO] [stdout] 22: 0x557c1240b0b7 - app_world::tests::deadlock_prevention_same_thread_double_read_another_thread_write::{{closure}}::he17983a5aee3fb11 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:231:74 [INFO] [stdout] 23: 0x557c12408fc6 - core::ops::function::FnOnce::call_once::he6f7ca8073332957 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x557c1244c38f - core::ops::function::FnOnce::call_once::he101e5838844b848 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x557c1244c38f - test::__rust_begin_short_backtrace::hed21417c1aa71a94 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:627:18 [INFO] [stdout] 26: 0x557c1244b305 - test::run_test_in_process::{{closure}}::h46df11f5a11dd433 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:650:60 [INFO] [stdout] 27: 0x557c1244b305 - as core::ops::function::FnOnce<()>>::call_once::ha2e64525433f53a7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x557c1244b305 - std::panicking::try::do_call::hb1d734fe97663a7f [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:552:40 [INFO] [stdout] 29: 0x557c1244b305 - std::panicking::try::h9d066276df30f924 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:516:19 [INFO] [stdout] 30: 0x557c1244b305 - std::panic::catch_unwind::heaada02e29cd999e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panic.rs:142:14 [INFO] [stdout] 31: 0x557c1244b305 - test::run_test_in_process::hcb05ee14075e628d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:650:27 [INFO] [stdout] 32: 0x557c1244b305 - test::run_test::{{closure}}::hf9413b0f448cd684 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:573:43 [INFO] [stdout] 33: 0x557c12413546 - test::run_test::{{closure}}::h1bb15d2e8774a33f [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:601:41 [INFO] [stdout] 34: 0x557c12413546 - std::sys_common::backtrace::__rust_begin_short_backtrace::h530f23a50d8639c1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 35: 0x557c12418727 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h37b8455d57d25f70 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 36: 0x557c12418727 - as core::ops::function::FnOnce<()>>::call_once::h3867791011bf949d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x557c12418727 - std::panicking::try::do_call::h1cac0f1203e6219b [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:552:40 [INFO] [stdout] 38: 0x557c12418727 - std::panicking::try::h82de9f14e7a5f0ca [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:516:19 [INFO] [stdout] 39: 0x557c12418727 - std::panic::catch_unwind::hcea1081d521eaa80 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panic.rs:142:14 [INFO] [stdout] 40: 0x557c12418727 - std::thread::Builder::spawn_unchecked_::{{closure}}::ha7aa8f4eaae79bb5 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 41: 0x557c12418727 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h5c1a4829e968af7c [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x557c1247b6e5 - as core::ops::function::FnOnce>::call_once::h6cea6bfb156e0019 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 43: 0x557c1247b6e5 - as core::ops::function::FnOnce>::call_once::h3c499441c8da3187 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 44: 0x557c1247b6e5 - std::sys::unix::thread::Thread::new::thread_start::h45692980afba103a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 45: 0x7f7c7bbdfac3 - [INFO] [stdout] 46: 0x7f7c7bc70bf4 - __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] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "fffcb54b45ab0c6f571ce3c79b511670fb70a2c9c860d3a8bd3974ccd731b267", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fffcb54b45ab0c6f571ce3c79b511670fb70a2c9c860d3a8bd3974ccd731b267", kill_on_drop: false }` [INFO] [stdout] fffcb54b45ab0c6f571ce3c79b511670fb70a2c9c860d3a8bd3974ccd731b267