[INFO] cloning repository https://github.com/fxdmhtt/ReactiveCache
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/fxdmhtt/ReactiveCache" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffxdmhtt%2FReactiveCache", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffxdmhtt%2FReactiveCache'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 8ce1a117e14bae7dcda004a46d321a9a96696317
[INFO] testing fxdmhtt/ReactiveCache against master#350d0ef0ec0493e6d21cfb265cb8211a0e74d766 for pr-145330-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffxdmhtt%2FReactiveCache" "/workspace/builds/worker-5-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/fxdmhtt/ReactiveCache
[INFO] finished tweaking git repo https://github.com/fxdmhtt/ReactiveCache
[INFO] tweaked toml for git repo https://github.com/fxdmhtt/ReactiveCache written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/fxdmhtt/ReactiveCache on toolchain 350d0ef0ec0493e6d21cfb265cb8211a0e74d766
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/fxdmhtt/ReactiveCache 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" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded lru v0.16.0
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 59e92d587c7615e39ae4ec8ed42a6ec840992acf702b0789ed97c2fc2225cf04
[INFO] running `Command { std: "docker" "start" "-a" "59e92d587c7615e39ae4ec8ed42a6ec840992acf702b0789ed97c2fc2225cf04", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "59e92d587c7615e39ae4ec8ed42a6ec840992acf702b0789ed97c2fc2225cf04", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "59e92d587c7615e39ae4ec8ed42a6ec840992acf702b0789ed97c2fc2225cf04", kill_on_drop: false }`
[INFO] [stdout] 59e92d587c7615e39ae4ec8ed42a6ec840992acf702b0789ed97c2fc2225cf04
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 235a044c32900da730ec21c502416c76150ebaf4c132e633be3221e6ce0f96e2
[INFO] running `Command { std: "docker" "start" "-a" "235a044c32900da730ec21c502416c76150ebaf4c132e633be3221e6ce0f96e2", kill_on_drop: false }`
[INFO] [stderr]    Compiling lru v0.16.0
[INFO] [stderr]    Compiling reactive-macros v0.4.0-alpha.0 (/opt/rustwide/workdir/macros)
[INFO] [stderr]    Compiling reactive-cache v0.4.0-alpha.0 (/opt/rustwide/workdir/cache)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.65s
[INFO] running `Command { std: "docker" "inspect" "235a044c32900da730ec21c502416c76150ebaf4c132e633be3221e6ce0f96e2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "235a044c32900da730ec21c502416c76150ebaf4c132e633be3221e6ce0f96e2", kill_on_drop: false }`
[INFO] [stdout] 235a044c32900da730ec21c502416c76150ebaf4c132e633be3221e6ce0f96e2
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2eaafaa794440e95e78521eff3fd7e78e23a92e858060a3a550fcfdadbc092d4
[INFO] running `Command { std: "docker" "start" "-a" "2eaafaa794440e95e78521eff3fd7e78e23a92e858060a3a550fcfdadbc092d4", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.95
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.104
[INFO] [stderr]    Compiling reactive-macros v0.4.0-alpha.0 (/opt/rustwide/workdir/macros)
[INFO] [stderr]    Compiling reactive-cache v0.4.0-alpha.0 (/opt/rustwide/workdir/cache)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.12s
[INFO] running `Command { std: "docker" "inspect" "2eaafaa794440e95e78521eff3fd7e78e23a92e858060a3a550fcfdadbc092d4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2eaafaa794440e95e78521eff3fd7e78e23a92e858060a3a550fcfdadbc092d4", kill_on_drop: false }`
[INFO] [stdout] 2eaafaa794440e95e78521eff3fd7e78e23a92e858060a3a550fcfdadbc092d4
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1974f16a32dfe4caa161acb573b95d8074deea7582630462712e516df34aee8b
[INFO] running `Command { std: "docker" "start" "-a" "1974f16a32dfe4caa161acb573b95d8074deea7582630462712e516df34aee8b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/reactive_cache-fe02708b6afd6175)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/reactive_macros-a7c177d2f55277df)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/basic.rs (/opt/rustwide/target/debug/deps/basic-fa0700f6f4a09c40)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/dep.rs (/opt/rustwide/target/debug/deps/dep-071f5b8eaede5f77)
[INFO] [stdout] test basic_memoization_works ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/effect.rs (/opt/rustwide/target/debug/deps/effect-afffc2d0b1f85617)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test complex_dependency_memo_test ... ok
[INFO] [stdout] test signal_set_unchanged_test ... ok
[INFO] [stdout] test signal_set_value_test ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test switch_effect_test ... ok
[INFO] [stdout] test loop_effect_test ... FAILED
[INFO] [stderr] error: test failed, to rerun pass `-p reactive-macros --test effect`
[INFO] [stdout] test complex_dependency_effect_test ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- loop_effect_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'loop_effect_test' (34) panicked at cache/src/effect_stack.rs:28:5:
[INFO] [stdout] `effect_push` and `effect_pop` are called in pairs and should be the same effect.
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c59df83e272 - std::backtrace_rs::backtrace::libunwind::trace::h0fb8082ca5562a61
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c59df83e272 - std::backtrace_rs::backtrace::trace_unsynchronized::hfaa258994374a3cd
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c59df83e272 - std::sys::backtrace::_print_fmt::h11496fe34963a885
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5c59df83e272 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hff1a9408d012ee89
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5c59df86161f - core::fmt::rt::Argument::fmt::hf11195b61445f5d1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/fmt/rt.rs:173:76
[INFO] [stdout]    5:     0x5c59df86161f - core::fmt::write::h2f1fc679f228cc52
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/fmt/mod.rs:1468:25
[INFO] [stdout]    6:     0x5c59df82adb3 - std::io::default_write_fmt::h12bd533187bc883d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5c59df82adb3 - std::io::Write::write_fmt::h926a1fdfdcb90065
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/io/mod.rs:1954:13
[INFO] [stdout]    8:     0x5c59df83e0c2 - std::sys::backtrace::BacktraceLock::print::h28bfdbcac6d3828b
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    9:     0x5c59df8295dc - std::panicking::default_hook::{{closure}}::h1f968e3a24b0e3b4
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:301:27
[INFO] [stdout]   10:     0x5c59df829459 - std::panicking::default_hook::h43da101644971931
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:325:9
[INFO] [stdout]   11:     0x5c59df7c57ce - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4b2012949041da5d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   12:     0x5c59df7c57ce - test::test_main_with_exit_callback::{{closure}}::h53c9154cdc260357
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5c59df829be3 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h911f2e3e7e84b764
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   14:     0x5c59df829be3 - std::panicking::panic_with_hook::hb4e916c3e65eccec
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:842:13
[INFO] [stdout]   15:     0x5c59df83dc16 - std::panicking::panic_handler::{{closure}}::hbff715d0475cf1bd
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:700:13
[INFO] [stdout]   16:     0x5c59df83dba9 - std::sys::backtrace::__rust_end_short_backtrace::hd6f43484e45a4955
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:174:18
[INFO] [stdout]   17:     0x5c59df82a14d - __rustc[b29d3319de9a3146]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:698:5
[INFO] [stdout]   18:     0x5c59df8679d0 - core::panicking::panic_fmt::h5f8d281875be5ff1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panicking.rs:75:14
[INFO] [stdout]   19:     0x5c59df806923 - reactive_cache::effect_stack::effect_pop::hf45b19decc51f6ba
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/effect_stack.rs:28:5
[INFO] [stdout]   20:     0x5c59df7bbfaf - reactive_cache::effect::Effect::new::hc9201fa8f91f7707
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/effect.rs:143:9
[INFO] [stdout]   21:     0x5c59df7b2626 - effect::loop_effect_test::hea26c2b54f13ccf5
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:134:26
[INFO] [stdout]   22:     0x5c59df7b2657 - effect::loop_effect_test::{{closure}}::he12599cc8cace925
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:133:22
[INFO] [stdout]   23:     0x5c59df7b9b56 - core::ops::function::FnOnce::call_once::ha2562c92612cc140
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   24:     0x5c59df7cad0b - core::ops::function::FnOnce::call_once::haf4e03bb51ef1351
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   25:     0x5c59df7cad0b - test::__rust_begin_short_backtrace::h50f5566ee84f2f21
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5c59df7cdf05 - test::run_test_in_process::{{closure}}::heb3733b71d876eca
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5c59df7cdf05 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::he2fe41e1b161faa9
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   28:     0x5c59df7cdf05 - std::panicking::catch_unwind::do_call::h00aa383d8fd97a09
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:590:40
[INFO] [stdout]   29:     0x5c59df7cdf05 - std::panicking::catch_unwind::he482e1914bc21641
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:553:19
[INFO] [stdout]   30:     0x5c59df7cdf05 - std::panic::catch_unwind::h86ac9705098f4f22
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5c59df7cdf05 - test::run_test_in_process::h636a992821e3c6aa
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5c59df7cdf05 - test::run_test::{{closure}}::he454cc9a192df79f
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5c59df7f38b4 - test::run_test::{{closure}}::haec47455908dff86
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5c59df7f38b4 - std::sys::backtrace::__rust_begin_short_backtrace::h230785972524cdc1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:158:18
[INFO] [stdout]   35:     0x5c59df7da4ca - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::haa5a562db4cde838
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/thread/mod.rs:559:17
[INFO] [stdout]   36:     0x5c59df7da4ca - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h89254f37a55d0a87
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   37:     0x5c59df7da4ca - std::panicking::catch_unwind::do_call::h499f5a462f019d9d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:590:40
[INFO] [stdout]   38:     0x5c59df7da4ca - std::panicking::catch_unwind::h6736fbbb1a264383
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:553:19
[INFO] [stdout]   39:     0x5c59df7da4ca - std::panic::catch_unwind::h49ea7bdd1cbee572
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5c59df7da4ca - std::thread::Builder::spawn_unchecked_::{{closure}}::h653dfa62acf32f5a
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/thread/mod.rs:557:30
[INFO] [stdout]   41:     0x5c59df7da4ca - core::ops::function::FnOnce::call_once{{vtable.shim}}::h82582a626a045a45
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   42:     0x5c59df81481f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h4ab26c07513156cc
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1971:9
[INFO] [stdout]   43:     0x5c59df81481f - std::sys::pal::unix::thread::Thread::new::thread_start::h66ff1bba185a50cf
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/pal/unix/thread.rs:107:17
[INFO] [stdout]   44:     0x7f7ac9c16aa4 - <unknown>
[INFO] [stdout]   45:     0x7f7ac9ca3a34 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- complex_dependency_effect_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'complex_dependency_effect_test' (33) panicked at /opt/rustwide/workdir/cache/src/signal.rs:182:30:
[INFO] [stdout] RefCell already mutably borrowed
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c59df83e272 - std::backtrace_rs::backtrace::libunwind::trace::h0fb8082ca5562a61
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c59df83e272 - std::backtrace_rs::backtrace::trace_unsynchronized::hfaa258994374a3cd
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c59df83e272 - std::sys::backtrace::_print_fmt::h11496fe34963a885
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5c59df83e272 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hff1a9408d012ee89
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5c59df86161f - core::fmt::rt::Argument::fmt::hf11195b61445f5d1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/fmt/rt.rs:173:76
[INFO] [stdout]    5:     0x5c59df86161f - core::fmt::write::h2f1fc679f228cc52
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/fmt/mod.rs:1468:25
[INFO] [stdout]    6:     0x5c59df82adb3 - std::io::default_write_fmt::h12bd533187bc883d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5c59df82adb3 - std::io::Write::write_fmt::h926a1fdfdcb90065
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/io/mod.rs:1954:13
[INFO] [stdout]    8:     0x5c59df83e0c2 - std::sys::backtrace::BacktraceLock::print::h28bfdbcac6d3828b
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    9:     0x5c59df8295dc - std::panicking::default_hook::{{closure}}::h1f968e3a24b0e3b4
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:301:27
[INFO] [stdout]   10:     0x5c59df829459 - std::panicking::default_hook::h43da101644971931
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:325:9
[INFO] [stdout]   11:     0x5c59df7c57ce - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4b2012949041da5d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   12:     0x5c59df7c57ce - test::test_main_with_exit_callback::{{closure}}::h53c9154cdc260357
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5c59df829be3 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h911f2e3e7e84b764
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   14:     0x5c59df829be3 - std::panicking::panic_with_hook::hb4e916c3e65eccec
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:842:13
[INFO] [stdout]   15:     0x5c59df83dc4a - std::panicking::panic_handler::{{closure}}::hbff715d0475cf1bd
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:707:13
[INFO] [stdout]   16:     0x5c59df83dba9 - std::sys::backtrace::__rust_end_short_backtrace::hd6f43484e45a4955
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:174:18
[INFO] [stdout]   17:     0x5c59df82a14d - __rustc[b29d3319de9a3146]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:698:5
[INFO] [stdout]   18:     0x5c59df8679d0 - core::panicking::panic_fmt::h5f8d281875be5ff1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panicking.rs:75:14
[INFO] [stdout]   19:     0x5c59df868763 - core::cell::panic_already_mutably_borrowed::do_panic::runtime::h71c29c864ea6115d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic.rs:218:21
[INFO] [stdout]   20:     0x5c59df86880a - core::cell::panic_already_mutably_borrowed::do_panic::hfc0b3ac85742b777
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/intrinsics/mod.rs:2361:9
[INFO] [stdout]   21:     0x5c59df86880a - core::cell::panic_already_mutably_borrowed::h9738d7fc582dd1dc
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic.rs:223:9
[INFO] [stdout]   22:     0x5c59df7b06b2 - core::cell::RefCell<T>::borrow::hcf731f1a769c1721
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/cell.rs:991:25
[INFO] [stdout]   23:     0x5c59df7badee - reactive_cache::signal::Signal<T>::get::h5494c539c7ffd46d
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/signal.rs:182:30
[INFO] [stdout]   24:     0x5c59df7b27b1 - effect::source_a::hf72a514f83557415
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:26:10
[INFO] [stdout]   25:     0x5c59df7b3e35 - effect::derived_c::DERIVED_C::{{closure}}::{{closure}}::h81b66eebe28fd1ab
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:39:5
[INFO] [stdout]   26:     0x5c59df7ab1f6 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb4ee42b3460e6c20
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   27:     0x5c59df7aa56c - reactive_cache::memo::Memo<T>::get::h8ee343259a52433c
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/memo.rs:168:33
[INFO] [stdout]   28:     0x5c59df7b3f35 - effect::derived_c::hf467096909fadea6
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:35:1
[INFO] [stdout]   29:     0x5c59df7b3eed - effect::effect_e::h20055c492109cb42
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:52:5
[INFO] [stdout]   30:     0x5c59df7b992e - core::ops::function::Fn::call::h8a6eeaafb121e3af
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:80:5
[INFO] [stdout]   31:     0x5c59df8081b6 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hd1d4467d511af560
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]   32:     0x5c59df808f6c - reactive_cache::effect::Effect::run::h38bc090b37b06104
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/effect.rs:247:9
[INFO] [stdout]   33:     0x5c59df808dea - reactive_cache::effect::run_untracked::hd70202861a3f4ec9
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/effect.rs:255:7
[INFO] [stdout]   34:     0x5c59df7ba93a - reactive_cache::signal::Signal<T>::flush_effects::{{closure}}::h9e7e665f87ab3317
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/signal.rs:92:17
[INFO] [stdout]   35:     0x5c59df7aee96 - alloc::vec::Vec<T,A>::retain::{{closure}}::h2d224eb85f7bba3d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/vec/mod.rs:2254:32
[INFO] [stdout]   36:     0x5c59df7af357 - alloc::vec::Vec<T,A>::retain_mut::process_loop::h0e0470798ee430ab
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/vec/mod.rs:2340:21
[INFO] [stdout]   37:     0x5c59df7afbdf - alloc::vec::Vec<T,A>::retain_mut::h8c7a19f243962eb6
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/vec/mod.rs:2366:9
[INFO] [stdout]   38:     0x5c59df7af0a3 - alloc::vec::Vec<T,A>::retain::hb14a5a48a37c9465
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/vec/mod.rs:2254:14
[INFO] [stdout]   39:     0x5c59df7ba57a - reactive_cache::signal::Signal<T>::flush_effects::h748549ebbe6869a6
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/signal.rs:90:35
[INFO] [stdout]   40:     0x5c59df7bb36a - reactive_cache::signal::Signal<T>::OnPropertyChanged::he2a80cf6d3670923
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/signal.rs:104:14
[INFO] [stdout]   41:     0x5c59df7bb305 - reactive_cache::signal::Signal<T>::set::h376ed1ce2e0f6bb5
[INFO] [stdout]                                at /opt/rustwide/workdir/cache/src/signal.rs:219:14
[INFO] [stdout]   42:     0x5c59df7b37eb - effect::complex_dependency_effect_test::h42f4b5485d4da84b
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:108:9
[INFO] [stdout]   43:     0x5c59df7b4167 - effect::complex_dependency_effect_test::{{closure}}::h3c4e94af6b0028d3
[INFO] [stdout]                                at /opt/rustwide/workdir/macros/tests/effect.rs:68:36
[INFO] [stdout]   44:     0x5c59df7b9126 - core::ops::function::FnOnce::call_once::h7e585e7a51c1b25a
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   45:     0x5c59df7cad0b - core::ops::function::FnOnce::call_once::haf4e03bb51ef1351
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   46:     0x5c59df7cad0b - test::__rust_begin_short_backtrace::h50f5566ee84f2f21
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:663:18
[INFO] [stdout]   47:     0x5c59df7cdf05 - test::run_test_in_process::{{closure}}::heb3733b71d876eca
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:686:74
[INFO] [stdout]   48:     0x5c59df7cdf05 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::he2fe41e1b161faa9
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   49:     0x5c59df7cdf05 - std::panicking::catch_unwind::do_call::h00aa383d8fd97a09
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:590:40
[INFO] [stdout]   50:     0x5c59df7cdf05 - std::panicking::catch_unwind::he482e1914bc21641
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:553:19
[INFO] [stdout]   51:     0x5c59df7cdf05 - std::panic::catch_unwind::h86ac9705098f4f22
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x5c59df7cdf05 - test::run_test_in_process::h636a992821e3c6aa
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:686:27
[INFO] [stdout]   53:     0x5c59df7cdf05 - test::run_test::{{closure}}::he454cc9a192df79f
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:607:43
[INFO] [stdout]   54:     0x5c59df7f38b4 - test::run_test::{{closure}}::haec47455908dff86
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/test/src/lib.rs:637:41
[INFO] [stdout]   55:     0x5c59df7f38b4 - std::sys::backtrace::__rust_begin_short_backtrace::h230785972524cdc1
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/backtrace.rs:158:18
[INFO] [stdout]   56:     0x5c59df7da4ca - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::haa5a562db4cde838
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/thread/mod.rs:559:17
[INFO] [stdout]   57:     0x5c59df7da4ca - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h89254f37a55d0a87
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   58:     0x5c59df7da4ca - std::panicking::catch_unwind::do_call::h499f5a462f019d9d
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:590:40
[INFO] [stdout]   59:     0x5c59df7da4ca - std::panicking::catch_unwind::h6736fbbb1a264383
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panicking.rs:553:19
[INFO] [stdout]   60:     0x5c59df7da4ca - std::panic::catch_unwind::h49ea7bdd1cbee572
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/panic.rs:359:14
[INFO] [stdout]   61:     0x5c59df7da4ca - std::thread::Builder::spawn_unchecked_::{{closure}}::h653dfa62acf32f5a
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/thread/mod.rs:557:30
[INFO] [stdout]   62:     0x5c59df7da4ca - core::ops::function::FnOnce::call_once{{vtable.shim}}::h82582a626a045a45
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   63:     0x5c59df81481f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h4ab26c07513156cc
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/alloc/src/boxed.rs:1971:9
[INFO] [stdout]   64:     0x5c59df81481f - std::sys::pal::unix::thread::Thread::new::thread_start::h66ff1bba185a50cf
[INFO] [stdout]                                at /rustc/350d0ef0ec0493e6d21cfb265cb8211a0e74d766/library/std/src/sys/pal/unix/thread.rs:107:17
[INFO] [stdout]   65:     0x7f7ac9c16aa4 - <unknown>
[INFO] [stdout]   66:     0x7f7ac9ca3a34 - clone
[INFO] [stdout]   67:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     complex_dependency_effect_test
[INFO] [stdout]     loop_effect_test
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1974f16a32dfe4caa161acb573b95d8074deea7582630462712e516df34aee8b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1974f16a32dfe4caa161acb573b95d8074deea7582630462712e516df34aee8b", kill_on_drop: false }`
[INFO] [stdout] 1974f16a32dfe4caa161acb573b95d8074deea7582630462712e516df34aee8b