[INFO] cloning repository https://github.com/vigoo/test-r
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/vigoo/test-r" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvigoo%2Ftest-r", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvigoo%2Ftest-r'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] bfd59484592258cde1127c60626fe440b354aacb
[INFO] testing vigoo/test-r against beta-2024-12-08 for beta-1.84.0-4-retry
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvigoo%2Ftest-r" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/vigoo/test-r on toolchain beta-2024-12-08
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-12-08" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/vigoo/test-r
[INFO] finished tweaking git repo https://github.com/vigoo/test-r
[INFO] tweaked toml for git repo https://github.com/vigoo/test-r written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] crate git repo https://github.com/vigoo/test-r 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" "+beta-2024-12-08" "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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-12-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 938587163e7a3faf10376465a09068143ae6b284d86aea0ffa779ef017576ce3
[INFO] running `Command { std: "docker" "start" "-a" "938587163e7a3faf10376465a09068143ae6b284d86aea0ffa779ef017576ce3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "938587163e7a3faf10376465a09068143ae6b284d86aea0ffa779ef017576ce3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "938587163e7a3faf10376465a09068143ae6b284d86aea0ffa779ef017576ce3", kill_on_drop: false }`
[INFO] [stdout] 938587163e7a3faf10376465a09068143ae6b284d86aea0ffa779ef017576ce3
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-12-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 956c6a4c453f31363b1badd32d400e58d2b476602321f06849bbfd436f796a5a
[INFO] running `Command { std: "docker" "start" "-a" "956c6a4c453f31363b1badd32d400e58d2b476602321f06849bbfd436f796a5a", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.92
[INFO] [stderr]    Compiling unicode-ident v1.0.14
[INFO] [stderr]    Compiling autocfg v1.4.0
[INFO] [stderr]    Compiling libc v0.2.168
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.15
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling smallvec v1.13.2
[INFO] [stderr]    Compiling bytes v1.9.0
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling anstyle-query v1.1.1
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling anstream v0.6.15
[INFO] [stderr]    Compiling virtue v0.0.13
[INFO] [stderr]    Compiling clap_lex v0.7.4
[INFO] [stderr]    Compiling doctest-file v1.0.0
[INFO] [stderr]    Compiling syn v2.0.90
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling clap_builder v4.5.23
[INFO] [stderr]    Compiling anstyle-wincon v3.0.4
[INFO] [stderr]    Compiling quick-xml v0.36.2
[INFO] [stderr]    Compiling escape8259 v0.5.3
[INFO] [stderr]    Compiling topological-sort v0.2.2
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling serde v1.0.216
[INFO] [stderr]    Compiling bincode_derive v2.0.0-rc.3
[INFO] [stderr]    Compiling once_cell v1.20.2
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling tokio v1.42.0
[INFO] [stderr]    Compiling uuid v1.11.0
[INFO] [stderr]    Compiling bincode v2.0.0-rc.3
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rustversion v1.0.18
[INFO] [stderr]    Compiling itoa v1.0.14
[INFO] [stderr]    Compiling log v0.4.22
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling serde_json v1.0.133
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling ryu v1.0.18
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling merge_derive v0.1.0
[INFO] [stderr]    Compiling merge v0.1.0
[INFO] [stderr]    Compiling darling_core v0.20.10
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling clap_derive v4.5.18
[INFO] [stderr]    Compiling tokio-macros v2.4.0
[INFO] [stderr]    Compiling serde_derive v1.0.216
[INFO] [stderr]    Compiling ctor v0.2.9
[INFO] [stderr]    Compiling derive_more-impl v1.0.0
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling interprocess v2.2.2
[INFO] [stderr]    Compiling clap v4.5.23
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling derive_more v1.0.0
[INFO] [stderr]    Compiling async-trait v0.1.83
[INFO] [stderr]    Compiling strum_macros v0.26.4
[INFO] [stderr]    Compiling darling v0.20.10
[INFO] [stderr]    Compiling derive_setters v0.1.6
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling test-r-core v1.2.0 (/opt/rustwide/workdir/test-r-core)
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling test-r-macro v1.1.1 (/opt/rustwide/workdir/test-r-macro)
[INFO] [stderr]    Compiling indexmap v2.7.0
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling test-r v1.2.0 (/opt/rustwide/workdir/test-r)
[INFO] [stderr]    Compiling test-r-example-tokio v0.0.1 (/opt/rustwide/workdir/example-tokio)
[INFO] [stderr]    Compiling test-r-example v0.0.1 (/opt/rustwide/workdir/example)
[INFO] [stderr]    Compiling gh-workflow v0.2.1
[INFO] [stderr]    Compiling test-r-github v0.0.1 (/opt/rustwide/workdir/github)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 55s
[INFO] running `Command { std: "docker" "inspect" "956c6a4c453f31363b1badd32d400e58d2b476602321f06849bbfd436f796a5a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "956c6a4c453f31363b1badd32d400e58d2b476602321f06849bbfd436f796a5a", kill_on_drop: false }`
[INFO] [stdout] 956c6a4c453f31363b1badd32d400e58d2b476602321f06849bbfd436f796a5a
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-12-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5ad08368752e25b8f175440ac797695b5fc9b72fe8f67f9391319f5466381a0b
[INFO] running `Command { std: "docker" "start" "-a" "5ad08368752e25b8f175440ac797695b5fc9b72fe8f67f9391319f5466381a0b", kill_on_drop: false }`
[INFO] [stderr]    Compiling test-r-macro v1.1.1 (/opt/rustwide/workdir/test-r-macro)
[INFO] [stderr]    Compiling test-r-core v1.2.0 (/opt/rustwide/workdir/test-r-core)
[INFO] [stderr]    Compiling test-r-github v0.0.1 (/opt/rustwide/workdir/github)
[INFO] [stderr]    Compiling test-r v1.2.0 (/opt/rustwide/workdir/test-r)
[INFO] [stderr]    Compiling test-r-example-tokio v0.0.1 (/opt/rustwide/workdir/example-tokio)
[INFO] [stderr]    Compiling test-r-example v0.0.1 (/opt/rustwide/workdir/example)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 16.44s
[INFO] running `Command { std: "docker" "inspect" "5ad08368752e25b8f175440ac797695b5fc9b72fe8f67f9391319f5466381a0b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5ad08368752e25b8f175440ac797695b5fc9b72fe8f67f9391319f5466381a0b", kill_on_drop: false }`
[INFO] [stdout] 5ad08368752e25b8f175440ac797695b5fc9b72fe8f67f9391319f5466381a0b
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-12-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2801829099bf22a256cc1e65822da7e6805f1f0467d752fb134d68694f6c4929
[INFO] running `Command { std: "docker" "start" "-a" "2801829099bf22a256cc1e65822da7e6805f1f0467d752fb134d68694f6c4929", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.24s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r-7fe0f7a1948c61b1)
[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/tests.rs (/opt/rustwide/target/debug/deps/tests-47cf47053136910f)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 44.62s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example-427ab7109011ecb3)
[INFO] [stdout] Running 1 tests
[INFO] [stdout] 
[INFO] [stdout] [1/1] Running test: test_r_example::tests::it_does_work
[INFO] [stdout] test sync_output_capturing_works ... ok
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 44.59s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example-427ab7109011ecb3)
[INFO] [stderr] [33mCannot run tests in parallel when tests have shared dependencies and output capturing is on. Using a single thread.[0m
[INFO] [stdout] Running 15 tests
[INFO] [stdout] 
[INFO] [stdout] [ 1/15] Running test: test_r_example::other::tests::result_based_test_ok
[INFO] [stdout] [ 1/15] Finished test: test_r_example::other::tests::result_based_test_ok     [PASSED]
[INFO] [stdout] [ 2/15] Running test: test_r_example::other::tests::non_flaky_test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] [1/1] Finished test: test_r_example::tests::it_does_work [FAILED]
[INFO] [stdout] ---- test_r_example::tests::it_does_work stdout/err ----
[INFO] [stdout] thread '<unnamed>' panicked at example/src/lib.rs:15:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: 4
[INFO] [stdout]  right: 5
[INFO] [stdout] stack backtrace:
[INFO] [stdout] Creating Dep1 for bench2
[INFO] [stdout] Print from 'it_does_work'
[INFO] [stdout]    0:     0x64d3c5a7acba - std::backtrace_rs::backtrace::libunwind::trace::h8810477743020387
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x64d3c5a7acba - std::backtrace_rs::backtrace::trace_unsynchronized::h3aa55355dd6c54a6
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x64d3c5a7acba - std::sys::backtrace::_print_fmt::h8588b0f62410823f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x64d3c5a7acba - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h98c26ac25ffe89bb
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x64d3c5aa0463 - core::fmt::rt::Argument::fmt::hf95377f4aebd453b
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x64d3c5aa0463 - core::fmt::write::h0dd5f6e2238c7982
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/mod.rs:1189:21
[INFO] [stdout]    6:     0x64d3c5a77903 - std::io::Write::write_fmt::hc00bff0a88e08857
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/io/mod.rs:1884:15
[INFO] [stdout]    7:     0x64d3c5a7ab02 - std::sys::backtrace::BacktraceLock::print::h5c19c1c038ee186d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x64d3c5a7bc0c - std::panicking::default_hook::{{closure}}::h77172f079a1fcb13
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x64d3c5a7ba52 - std::panicking::default_hook::he5f8e3b203ccddba
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:295:9
[INFO] [stdout]   10:     0x64d3c5a7c1e7 - std::panicking::rust_panic_with_hook::h53863d4e9018df39
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:801:13
[INFO] [stdout]   11:     0x64d3c5a7c07a - std::panicking::begin_panic_handler::{{closure}}::h67280e6fa0757873
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:674:13
[INFO] [stdout]   12:     0x64d3c5a7b199 - std::sys::backtrace::__rust_end_short_backtrace::h1dac3f54d6fbbeb2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   13:     0x64d3c5a7bd0c - rust_begin_unwind
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:665:5
[INFO] [stdout]   14:     0x64d3c581bc00 - core::panicking::panic_fmt::h6792bd1b2bf01041
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panicking.rs:76:14
[INFO] [stdout]   15:     0x64d3c581bfcb - core::panicking::assert_failed_inner::hf2c17e6d30928787
[INFO] [stdout]   16:     0x64d3c58f7ec6 - core::panicking::assert_failed::h8a45bfd0aa6c133f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panicking.rs:373:5
[INFO] [stdout]   17:     0x64d3c5821078 - test_r_example::tests::it_does_work::h82e988d998cfaa32
[INFO] [stdout]                                at /opt/rustwide/workdir/example/src/lib.rs:15:9
[INFO] [stdout]   18:     0x64d3c5820f28 - test_r_example::tests::test_r_register_it_does_work::{{closure}}::h5f97df549452fc1b
[INFO] [stdout]                                at /opt/rustwide/workdir/example/src/lib.rs:10:5
[INFO] [stdout]   19:     0x64d3c584c98f - test_r_core::sync::run_sync_test_function::{{closure}}::{{closure}}::hdb26ca6d0df57ffc
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:279:38
[INFO] [stdout]   20:     0x64d3c5843160 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h46760e99c269fa3f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   21:     0x64d3c58a42e1 - std::panicking::try::do_call::hf2f5618d79a496a9
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   22:     0x64d3c585a3db - __rust_try
[INFO] [stdout]   23:     0x64d3c5854262 - std::panicking::try::h8f65824c64110c19
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   24:     0x64d3c5854262 - std::panic::catch_unwind::hbc476f65a78e885a
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   25:     0x64d3c584c8ac - test_r_core::sync::run_sync_test_function::{{closure}}::h5a0a07e30641807e
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:278:21
[INFO] [stdout]   26:     0x64d3c584bc5b - test_r_core::sync::run_with_flakiness_control::hf35f23250795ff36
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:219:13
[INFO] [stdout]   27:     0x64d3c584c344 - test_r_core::sync::run_sync_test_function::hb865336d9cf2660e
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:275:17
[INFO] [stdout]   28:     0x64d3c584ace5 - test_r_core::sync::test_thread::h8e86394d479b26d7
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:151:21
[INFO] [stdout]   29:     0x64d3c5849fa5 - test_r_core::sync::test_runner::{{closure}}::hb37e4760566205d4
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:74:17
[INFO] [stdout]   30:     0x64d3c58b0681 - std::sys::backtrace::__rust_begin_short_backtrace::h15ed5b65745da3ff
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   31:     0x64d3c58937ce - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h8fcd0461aa3a68d9
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:561:17
[INFO] [stdout]   32:     0x64d3c584319e - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h6e69ecb3c6928f0b
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   33:     0x64d3c58a4341 - std::panicking::try::do_call::hfc131bc8c29c0411
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   34:     0x64d3c5896c8b - __rust_try
[INFO] [stdout]   35:     0x64d3c5892740 - std::panicking::try::h8c59384f0c49b248
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   36:     0x64d3c5892740 - std::panic::catch_unwind::h9dd3686b7af232e0
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   37:     0x64d3c5892740 - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e2e491634392ead
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:559:30
[INFO] [stdout]   38:     0x64d3c5873a4f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h6556d2fd5e490c4d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   39:     0x64d3c5a7ff5b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h42f0ba71002a1b2c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   40:     0x64d3c5a7ff5b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::had9e47d7a3dcecb8
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   41:     0x64d3c5a7ff5b - std::sys::pal::unix::thread::Thread::new::thread_start::h48cf765408f6f5f2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   42:     0x70cb51186ac3 - <unknown>
[INFO] [stdout]   43:     0x70cb51217a04 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED; 0 passed; 1 failed; 0 ignored; 0 measured; 16 filtered out; finished in 0.125s
[INFO] [stdout] 
[INFO] [stdout] Failed tests:
[INFO] [stdout]  - test_r_example::tests::it_does_work (assertion `left == right` failed
[INFO] [stdout]   left: 4
[INFO] [stdout]  right: 5)
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] test exit_code_is_101_on_failure ... ok
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] 95be489c-1427-428b-9586-6eece3033ff7
[INFO] [stdout] [ 2/15] Finished test: test_r_example::other::tests::non_flaky_test           [PASSED]
[INFO] [stdout] [ 3/15] Running test: test_r_example::other::tests::flaky_test
[INFO] [stdout] [ 3/15] Finished test: test_r_example::other::tests::flaky_test               [PASSED]
[INFO] [stdout] [ 4/15] Running test: test_r_example::other::tests::other_module_test_works
[INFO] [stdout] [ 4/15] Finished test: test_r_example::other::tests::other_module_test_works  [PASSED]
[INFO] [stdout] [ 5/15] Running test: test_r_example::tests::this_too
[INFO] [stdout] [ 5/15] Finished test: test_r_example::tests::this_too                        [PASSED]
[INFO] [stdout] [ 6/15] Running test: test_r_example::tests::bench1
[INFO] [stdout] [ 6/15] Finished test: test_r_example::tests::bench1                          [BENCH]                  87.97 ns/iter (+/- 138.42)
[INFO] [stdout] [ 7/15] Running test: test_r_example::tests::bench2
[INFO] [stdout] [ 7/15] Finished test: test_r_example::tests::bench2                          [BENCH]                  95.70 ns/iter (+/- 159.23)
[INFO] [stdout] [ 8/15] Running test: test_r_example::generic_deps::test_with_deps
[INFO] [stdout] [ 8/15] Finished test: test_r_example::generic_deps::test_with_deps           [PASSED]
[INFO] [stdout] [ 9/15] Running test: test_r_example::inner::slow::tests::sleeping_test_1
[INFO] [stdout] test async_output_capturing_works has been running for over 60 seconds
[INFO] [stdout] test can_run_async_examples has been running for over 60 seconds
[INFO] [stdout] test can_run_sync_examples has been running for over 60 seconds
[INFO] [stdout] test timeout_works has been running for over 60 seconds
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio-778d0030fc3b5b05)
[INFO] [stdout] Generating some tests in a sync generator
[INFO] [stdout] Generating some tests in an async generator
[INFO] [stdout] Generating some tests with dependencies in a sync generator
[INFO] [stdout] Generating some async tests with dependencies in a sync generator
[INFO] [stderr] [33mCannot run tests in parallel when tests have shared dependencies and output capturing is on. Using a single thread.[0m
[INFO] [stdout] Running 57 tests
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 02s
[INFO] [stdout] [ 1/57] Running test: test_r_example_tokio::deps::tests::dep_test_works
[INFO] [stdout] [ 1/57] Finished test: test_r_example_tokio::deps::tests::dep_test_works                [PASSED]
[INFO] [stdout] [ 2/57] Running test: test_r_example_tokio::deps::tests::sleeping_test_3
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio-778d0030fc3b5b05)
[INFO] [stdout] Generating some tests in a sync generator
[INFO] [stdout] Generating some tests in an async generator
[INFO] [stdout] Generating some tests with dependencies in a sync generator
[INFO] [stdout] Generating some async tests with dependencies in a sync generator
[INFO] [stdout] Running 1 tests
[INFO] [stdout] 
[INFO] [stdout] [1/1] Running test: test_r_example_tokio::inner::tests::sleeping_test_3_timeout
[INFO] [stdout] test async_output_capturing_works ... ok
[INFO] [stdout] [ 9/15] Finished test: test_r_example::inner::slow::tests::sleeping_test_1    [PASSED]
[INFO] [stdout] [10/15] Running test: test_r_example::inner::slow::tests::sleeping_test_2
[INFO] [stdout] [1/1] Finished test: test_r_example_tokio::inner::tests::sleeping_test_3_timeout [FAILED]
[INFO] [stdout] ---- test_r_example_tokio::inner::tests::sleeping_test_3_timeout stdout/err ----
[INFO] [stdout] Generating some tests in a sync generator
[INFO] [stdout] Generating some tests in an async generator
[INFO] [stdout] Generating some tests with dependencies in a sync generator
[INFO] [stdout] Generating some async tests with dependencies in a sync generator
[INFO] [stdout] Creating Dep2 20
[INFO] [stdout] Creating Dep1 10
[INFO] [stdout] 2024-12-15T00:22:50.449020Z  INFO test_r_example_tokio::deps::tests: Initialized tracing
[INFO] [stdout] Dropping Dep2 20
[INFO] [stdout] Dropping Dep1 10
[INFO] [stdout] Start sleeping in sleeping test 3
[INFO] [stdout] thread 'tokio-runtime-worker' panicked at test-r-core/src/tokio.rs:343:51:
[INFO] [stdout] Test timed out
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5632d002014a - std::backtrace_rs::backtrace::libunwind::trace::h8810477743020387
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x5632d002014a - std::backtrace_rs::backtrace::trace_unsynchronized::h3aa55355dd6c54a6
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5632d002014a - std::sys::backtrace::_print_fmt::h8588b0f62410823f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5632d002014a - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h98c26ac25ffe89bb
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5632d0046623 - core::fmt::rt::Argument::fmt::hf95377f4aebd453b
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x5632d0046623 - core::fmt::write::h0dd5f6e2238c7982
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/mod.rs:1189:21
[INFO] [stdout]    6:     0x5632d001cc23 - std::io::Write::write_fmt::hc00bff0a88e08857
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/io/mod.rs:1884:15
[INFO] [stdout]    7:     0x5632d001ff92 - std::sys::backtrace::BacktraceLock::print::h5c19c1c038ee186d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x5632d002109c - std::panicking::default_hook::{{closure}}::h77172f079a1fcb13
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x5632d0020ee2 - std::panicking::default_hook::he5f8e3b203ccddba
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:295:9
[INFO] [stdout]   10:     0x5632d0021677 - std::panicking::rust_panic_with_hook::h53863d4e9018df39
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:801:13
[INFO] [stdout]   11:     0x5632d00214d6 - std::panicking::begin_panic_handler::{{closure}}::h67280e6fa0757873
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:667:13
[INFO] [stdout]   12:     0x5632d0020629 - std::sys::backtrace::__rust_end_short_backtrace::h1dac3f54d6fbbeb2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   13:     0x5632d002119c - rust_begin_unwind
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:665:5
[INFO] [stdout]   14:     0x5632cfc26e20 - core::panicking::panic_fmt::h6792bd1b2bf01041
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panicking.rs:76:14
[INFO] [stdout]   15:     0x5632cfcd98c5 - test_r_core::tokio::run_test::{{closure}}::{{closure}}::{{closure}}::{{closure}}::hfde0251f760ad342
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:343:51
[INFO] [stdout]   16:     0x5632cfd490ff - <core::pin::Pin<P> as core::future::future::Future>::poll::hb20917028379250d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/future/future.rs:124:9
[INFO] [stdout]   17:     0x5632cfd35fb3 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::future::future::Future>::poll::h5600f2f91a2691c5
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:297:9
[INFO] [stdout]   18:     0x5632cfd721e1 - <futures_util::future::future::catch_unwind::CatchUnwind<Fut> as core::future::future::Future>::poll::{{closure}}::h1b7d0143b137c59c
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.31/src/future/future/catch_unwind.rs:37:42
[INFO] [stdout]   19:     0x5632cfd369b4 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h91ca44033a71dcee
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   20:     0x5632cfd8c587 - std::panicking::try::do_call::h13672fd46929e199
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   21:     0x5632cfda829b - __rust_try
[INFO] [stdout]   22:     0x5632cfd9bdd4 - std::panicking::try::h254179d2fc62bec3
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   23:     0x5632cfd9bdd4 - std::panic::catch_unwind::h17eddb6f3eace597
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   24:     0x5632cfd72141 - <futures_util::future::future::catch_unwind::CatchUnwind<Fut> as core::future::future::Future>::poll::he895723a505487d3
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.31/src/future/future/catch_unwind.rs:37:9
[INFO] [stdout]   25:     0x5632cfcd8f74 - test_r_core::tokio::run_test::{{closure}}::{{closure}}::{{closure}}::hef7b05ebabc3433e
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:362:26
[INFO] [stdout]   26:     0x5632cfd48ee0 - <core::pin::Pin<P> as core::future::future::Future>::poll::h18fe735ff46610bd
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/future/future.rs:124:9
[INFO] [stdout]   27:     0x5632cfcd6909 - test_r_core::tokio::run_with_flakiness_control::{{closure}}::h645c568b1033b21b
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:248:25
[INFO] [stdout]   28:     0x5632cfcd7fa9 - test_r_core::tokio::run_test::{{closure}}::h4f496a25124a1415
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:365:18
[INFO] [stdout]   29:     0x5632cfcd4a35 - test_r_core::tokio::test_thread::{{closure}}::h8837a52aa65b5b8a
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:185:18
[INFO] [stdout]   30:     0x5632cfd4903f - <core::pin::Pin<P> as core::future::future::Future>::poll::h7cf7a76b4cfc7f46
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/future/future.rs:124:9
[INFO] [stdout]   31:     0x5632cfd2ae01 - tokio::runtime::park::CachedParkThread::block_on::{{closure}}::h2a38b540c3afe0ed
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/park.rs:281:63
[INFO] [stdout]   32:     0x5632cfd2ac42 - tokio::runtime::coop::with_budget::h90df7b2dfbe8d331
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/coop.rs:107:5
[INFO] [stdout]   33:     0x5632cfd2ac42 - tokio::runtime::coop::budget::hd8c50f853f27edeb
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/coop.rs:73:5
[INFO] [stdout]   34:     0x5632cfd2ac42 - tokio::runtime::park::CachedParkThread::block_on::hfd98a9aaca28f446
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/park.rs:281:31
[INFO] [stdout]   35:     0x5632cfd6f544 - tokio::runtime::context::blocking::BlockingRegionGuard::block_on::h1bed3c0e6f12c5ec
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/context/blocking.rs:66:9
[INFO] [stdout]   36:     0x5632cfd2b01f - tokio::runtime::handle::Handle::block_on_inner::{{closure}}::h93fcb8c5b94b6729
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/handle.rs:327:13
[INFO] [stdout]   37:     0x5632cfd2b778 - tokio::runtime::context::runtime::enter_runtime::h57f7457d0df0082d
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   38:     0x5632cfd2af7e - tokio::runtime::handle::Handle::block_on_inner::hcba3832dd9f8860a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/handle.rs:326:9
[INFO] [stdout]   39:     0x5632cfd2b65e - tokio::runtime::handle::Handle::block_on::h246449980148b8ed
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/handle.rs:303:13
[INFO] [stdout]   40:     0x5632cfcd2dd1 - test_r_core::tokio::async_test_runner::{{closure}}::{{closure}}::hf86d1c1fa4f26bb4
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:93:17
[INFO] [stdout]   41:     0x5632cfcee60a - <tokio::runtime::blocking::task::BlockingTask<T> as core::future::future::Future>::poll::h65b0823b3203dd99
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/blocking/task.rs:42:21
[INFO] [stdout]   42:     0x5632cfdb54a3 - tokio::runtime::task::core::Core<T,S>::poll::{{closure}}::h103b3b6aac97c4c8
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/core.rs:331:17
[INFO] [stdout]   43:     0x5632cfdb49c1 - tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut::h758100e95a82c827
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/loom/std/unsafe_cell.rs:16:9
[INFO] [stdout]   44:     0x5632cfdb49c1 - tokio::runtime::task::core::Core<T,S>::poll::h806e79da33bd5f1b
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/core.rs:320:13
[INFO] [stdout]   45:     0x5632cfd0855e - tokio::runtime::task::harness::poll_future::{{closure}}::h9fe0713f976805f7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/harness.rs:499:19
[INFO] [stdout]   46:     0x5632cfd36b71 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb0b7db357cc87001
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   47:     0x5632cfd8dcd9 - std::panicking::try::do_call::hfb067a68241b0cfc
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   48:     0x5632cfda829b - __rust_try
[INFO] [stdout]   49:     0x5632cfd9df96 - std::panicking::try::h96eb15dfb1435491
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   50:     0x5632cfd9df96 - std::panic::catch_unwind::hb2564d578e3de574
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   51:     0x5632cfd07020 - tokio::runtime::task::harness::poll_future::hc343ecc8b3417c69
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/harness.rs:487:18
[INFO] [stdout]   52:     0x5632cfd0937e - tokio::runtime::task::harness::Harness<T,S>::poll_inner::h68d5b21ad1a44fad
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/harness.rs:209:27
[INFO] [stdout]   53:     0x5632cfd0cd27 - tokio::runtime::task::harness::Harness<T,S>::poll::hf99a6228a88c1fa0
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/harness.rs:154:15
[INFO] [stdout]   54:     0x5632cfd770fd - tokio::runtime::task::raw::poll::hbddcac90d93ad909
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/raw.rs:271:5
[INFO] [stdout]   55:     0x5632cfdf2fd7 - tokio::runtime::task::raw::RawTask::poll::h2519b63c58f10291
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/raw.rs:201:18
[INFO] [stdout]   56:     0x5632cfddfd67 - tokio::runtime::task::UnownedTask<S>::run::h7f8aef1688f0f939
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/task/mod.rs:472:9
[INFO] [stdout]   57:     0x5632cfde00b7 - tokio::runtime::blocking::pool::Task::run::h45271425d1a4a14a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/blocking/pool.rs:161:9
[INFO] [stdout]   58:     0x5632cfde5c6f - tokio::runtime::blocking::pool::Inner::run::h77a88ea99eef5e04
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/blocking/pool.rs:511:17
[INFO] [stdout]   59:     0x5632cfde5984 - tokio::runtime::blocking::pool::Spawner::spawn_thread::{{closure}}::hd33060a07d931aea
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.42.0/src/runtime/blocking/pool.rs:469:13
[INFO] [stdout]   60:     0x5632cfdf4636 - std::sys::backtrace::__rust_begin_short_backtrace::h1f8426833b6d3577
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   61:     0x5632cfe2b1e2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h90d700667bea845e
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:561:17
[INFO] [stdout]   62:     0x5632cfe37f31 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc6bdefdf995127ba
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   63:     0x5632cfe22161 - std::panicking::try::do_call::ha88f63f06b3d7e4f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   64:     0x5632cfe32a9b - __rust_try
[INFO] [stdout]   65:     0x5632cfe2abd1 - std::panicking::try::h5f8e3793b4c2070a
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   66:     0x5632cfe2abd1 - std::panic::catch_unwind::h9e5fc19855e02750
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   67:     0x5632cfe2abd1 - std::thread::Builder::spawn_unchecked_::{{closure}}::h1d78ab803ae05620
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:559:30
[INFO] [stdout]   68:     0x5632cfe1450f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h13e94817c15274dd
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   69:     0x5632d002569b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h42f0ba71002a1b2c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   70:     0x5632d002569b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::had9e47d7a3dcecb8
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   71:     0x5632d002569b - std::sys::pal::unix::thread::Thread::new::thread_start::h48cf765408f6f5f2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   72:     0x7d4dbb3fbac3 - <unknown>
[INFO] [stdout]   73:     0x7d4dbb48ca04 - clone
[INFO] [stdout]   74:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED; 0 passed; 1 failed; 0 ignored; 0 measured; 60 filtered out; finished in 3.110s
[INFO] [stdout] 
[INFO] [stdout] Failed tests:
[INFO] [stdout]  - test_r_example_tokio::inner::tests::sleeping_test_3_timeout (Test timed out)
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] test timeout_works ... FAILED
[INFO] [stdout] [ 2/57] Finished test: test_r_example_tokio::deps::tests::sleeping_test_3               [PASSED]
[INFO] [stdout] [ 3/57] Running test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_3
[INFO] [stdout] [ 3/57] Finished test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_3 [PASSED]
[INFO] [stdout] [ 4/57] Running test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_2
[INFO] [stdout] [ 4/57] Finished test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_2 [PASSED]
[INFO] [stdout] [ 5/57] Running test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_1
[INFO] [stdout] [ 5/57] Finished test: test_r_example_tokio::deps::tests::inner::dep_test_inner_works_1 [PASSED]
[INFO] [stdout] [ 6/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_0
[INFO] [stdout] [ 6/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_0        [PASSED]
[INFO] [stdout] [ 7/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_1
[INFO] [stdout] [ 7/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_1        [PASSED]
[INFO] [stdout] [ 8/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_2
[INFO] [stdout] [ 8/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_2        [PASSED]
[INFO] [stdout] [ 9/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_3
[INFO] [stdout] [ 9/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_3        [PASSED]
[INFO] [stdout] [10/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_4
[INFO] [stdout] [10/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_4        [PASSED]
[INFO] [stdout] [11/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_5
[INFO] [stdout] [11/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_5        [PASSED]
[INFO] [stdout] [12/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_6
[INFO] [stdout] [12/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_6        [PASSED]
[INFO] [stdout] [13/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_7
[INFO] [stdout] [13/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_7        [PASSED]
[INFO] [stdout] [14/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_8
[INFO] [stdout] [14/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_8        [PASSED]
[INFO] [stdout] [15/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_9
[INFO] [stdout] [15/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_9        [PASSED]
[INFO] [stdout] [16/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_0
[INFO] [stdout] [16/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_0        [PASSED]
[INFO] [stdout] [17/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_1
[INFO] [stdout] [17/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_1        [PASSED]
[INFO] [stdout] [18/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_2
[INFO] [stdout] [18/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_2        [PASSED]
[INFO] [stdout] [19/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_3
[INFO] [stdout] [19/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_3        [PASSED]
[INFO] [stdout] [20/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_4
[INFO] [stdout] [20/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_4        [PASSED]
[INFO] [stdout] [21/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_5
[INFO] [stdout] [21/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_5        [PASSED]
[INFO] [stdout] [22/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_6
[INFO] [stdout] [22/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_6        [PASSED]
[INFO] [stdout] [23/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_7
[INFO] [stdout] [23/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_7        [PASSED]
[INFO] [stdout] [24/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_8
[INFO] [stdout] [24/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_8        [PASSED]
[INFO] [stdout] [25/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_9
[INFO] [stdout] [25/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_9        [PASSED]
[INFO] [stdout] [26/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_0
[INFO] [stdout] [26/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_0        [PASSED]
[INFO] [stdout] [27/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_1
[INFO] [stdout] [27/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_1        [PASSED]
[INFO] [stdout] [28/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_2
[INFO] [stdout] [28/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_2        [PASSED]
[INFO] [stdout] [29/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_3
[INFO] [stdout] [29/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_3        [PASSED]
[INFO] [stdout] [30/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_4
[INFO] [stdout] [30/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_4        [PASSED]
[INFO] [stdout] [31/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_5
[INFO] [stdout] [31/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_5        [PASSED]
[INFO] [stdout] [32/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_6
[INFO] [stdout] [32/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_6        [PASSED]
[INFO] [stdout] [33/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_7
[INFO] [stdout] [33/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_7        [PASSED]
[INFO] [stdout] [34/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_8
[INFO] [stdout] [34/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_8        [PASSED]
[INFO] [stdout] [35/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_9
[INFO] [stdout] [35/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_9        [PASSED]
[INFO] [stdout] [36/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_0
[INFO] [stdout] [36/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_0        [PASSED]
[INFO] [stdout] [37/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_1
[INFO] [stdout] [37/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_1        [PASSED]
[INFO] [stdout] [38/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_2
[INFO] [stdout] [38/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_2        [PASSED]
[INFO] [stdout] [39/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_3
[INFO] [stdout] [39/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_3        [PASSED]
[INFO] [stdout] [40/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_4
[INFO] [stdout] [40/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_4        [PASSED]
[INFO] [stdout] [41/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_5
[INFO] [stdout] [41/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_5        [PASSED]
[INFO] [stdout] [42/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_6
[INFO] [stdout] [42/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_6        [PASSED]
[INFO] [stdout] [43/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_7
[INFO] [stdout] [43/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_7        [PASSED]
[INFO] [stdout] [44/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_8
[INFO] [stdout] [44/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_8        [PASSED]
[INFO] [stdout] [45/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_9
[INFO] [stdout] [45/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_9        [PASSED]
[INFO] [stdout] [46/57] Running test: test_r_example_tokio::benches::bench1
[INFO] [stdout] [46/57] Finished test: test_r_example_tokio::benches::bench1                            [BENCH]                 138.23 ns/iter (+/- 62.00)
[INFO] [stdout] [47/57] Running test: test_r_example_tokio::benches::bench2
[INFO] [stdout] [10/15] Finished test: test_r_example::inner::slow::tests::sleeping_test_2    [PASSED]
[INFO] [stdout] [11/15] Running test: test_r_example::inner::slow::tests::sleeping_test_3
[INFO] [stdout] [47/57] Finished test: test_r_example_tokio::benches::bench2                            [BENCH]                 171.22 ns/iter (+/- 72.47)
[INFO] [stdout] [48/57] Running test: test_r_example_tokio::flakiness::flaky_test
[INFO] [stdout] [48/57] Finished test: test_r_example_tokio::flakiness::flaky_test                      [PASSED]
[INFO] [stdout] [49/57] Running test: test_r_example_tokio::flakiness::non_flaky_test
[INFO] [stdout] [49/57] Finished test: test_r_example_tokio::flakiness::non_flaky_test                  [PASSED]
[INFO] [stdout] [50/57] Running test: test_r_example_tokio::tests::this_too
[INFO] [stdout] [50/57] Finished test: test_r_example_tokio::tests::this_too                            [PASSED]
[INFO] [stdout] [51/57] Running test: test_r_example_tokio::tests::panic_test_1
[INFO] [stdout] [51/57] Finished test: test_r_example_tokio::tests::panic_test_1                        [PASSED]
[INFO] [stdout] [52/57] Running test: test_r_example_tokio::tests::panic_test_2a
[INFO] [stdout] [52/57] Finished test: test_r_example_tokio::tests::panic_test_2a                       [PASSED]
[INFO] [stdout] [53/57] Running test: test_r_example_tokio::tests::result_based_test_ok
[INFO] [stdout] [53/57] Finished test: test_r_example_tokio::tests::result_based_test_ok                [PASSED]
[INFO] [stdout] [54/57] Running test: test_r_example_tokio::inner::tests::inner_test_works
[INFO] [stdout] [54/57] Finished test: test_r_example_tokio::inner::tests::inner_test_works             [PASSED]
[INFO] [stdout] [55/57] Running test: test_r_example_tokio::inner::tests::ignored_inner_test_works
[INFO] [stdout] [55/57] Finished test: test_r_example_tokio::inner::tests::ignored_inner_test_works     [IGNORED]
[INFO] [stdout] [56/57] Running test: test_r_example_tokio::inner::tests::sleeping_test_1
[INFO] [stdout] [11/15] Finished test: test_r_example::inner::slow::tests::sleeping_test_3    [PASSED]
[INFO] [stdout] [12/15] Running test: test_r_example::inner::slow::tests::sleeping_test_4
[INFO] [stdout] [56/57] Finished test: test_r_example_tokio::inner::tests::sleeping_test_1              [PASSED]
[INFO] [stdout] [57/57] Running test: test_r_example_tokio::inner::tests::sleeping_test_2
[INFO] [stdout] [12/15] Finished test: test_r_example::inner::slow::tests::sleeping_test_4    [PASSED]
[INFO] [stdout] [13/15] Running test: test_r_example::inner::slow::tests::sleeping_test_5
[INFO] [stdout] [13/15] Finished test: test_r_example::inner::slow::tests::sleeping_test_5    [PASSED]
[INFO] [stdout] [14/15] Running test: test_r_example::inner::tests::inner_test_works
[INFO] [stdout] [14/15] Finished test: test_r_example::inner::tests::inner_test_works         [PASSED]
[INFO] [stdout] [15/15] Running test: test_r_example::inner::tests::ignored_inner_test_works
[INFO] [stdout] [15/15] Finished test: test_r_example::inner::tests::ignored_inner_test_works [IGNORED]
[INFO] [stdout] 
[INFO] [stdout] test result: ok; 12 passed; 0 failed; 1 ignored; 2 measured; 2 filtered out; finished in 51.075s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/test_r_example_bin-9b80664b94c4f48d)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-949b79d291dfa482)
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] Running 1 tests
[INFO] [stdout] 
[INFO] [stdout] [1/1] Running test: tests::it_works
[INFO] [stdout] [1/1] Finished test: tests::it_works [PASSED]
[INFO] [stdout] 
[INFO] [stdout] test result: ok; 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.059s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests test_r_example
[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] [stdout] test can_run_sync_examples ... ok
[INFO] [stdout] [57/57] Finished test: test_r_example_tokio::inner::tests::sleeping_test_2              [PASSED]
[INFO] [stdout] 
[INFO] [stdout] test result: ok; 54 passed; 0 failed; 1 ignored; 2 measured; 4 filtered out; finished in 36.356s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio_bin-5af33e0da6577d63)
[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/tests.rs (/opt/rustwide/target/debug/deps/tests-15a05cd5d08bfdfd)
[INFO] [stdout] Running 1 tests
[INFO] [stdout] 
[INFO] [stdout] [1/1] Running test: tests::it_works
[INFO] [stdout] [1/1] Finished test: tests::it_works [PASSED]
[INFO] [stdout] 
[INFO] [stdout] test result: ok; 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.120s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests test_r_example_tokio
[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] error: test failed, to rerun pass `-p test-r --test tests`
[INFO] [stdout] test can_run_async_examples ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- timeout_works stdout ----
[INFO] [stdout] thread 'timeout_works' panicked at test-r/tests/tests.rs:74:5:
[INFO] [stdout] assertion failed: elapsed < Duration::from_secs(5)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5a84e41b07ea - std::backtrace_rs::backtrace::libunwind::trace::h8810477743020387
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x5a84e41b07ea - std::backtrace_rs::backtrace::trace_unsynchronized::h3aa55355dd6c54a6
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5a84e41b07ea - std::sys::backtrace::_print_fmt::h8588b0f62410823f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5a84e41b07ea - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h98c26ac25ffe89bb
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5a84e41d6423 - core::fmt::rt::Argument::fmt::hf95377f4aebd453b
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x5a84e41d6423 - core::fmt::write::h0dd5f6e2238c7982
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/fmt/mod.rs:1189:21
[INFO] [stdout]    6:     0x5a84e41ad663 - std::io::Write::write_fmt::h03bb0ca26e6faf8c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/io/mod.rs:1884:15
[INFO] [stdout]    7:     0x5a84e41b0632 - std::sys::backtrace::BacktraceLock::print::h5c19c1c038ee186d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x5a84e41b1c6d - std::panicking::default_hook::{{closure}}::h77172f079a1fcb13
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x5a84e41b1a77 - std::panicking::default_hook::he5f8e3b203ccddba
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x5a84e417b037 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h7a3c7b4a1ae77f2f
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1986:9
[INFO] [stdout]   11:     0x5a84e417b037 - test::test_main::{{closure}}::hf0d3b69ae9b353d1
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:134:21
[INFO] [stdout]   12:     0x5a84e41b2318 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h6332e82e7b189abd
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1986:9
[INFO] [stdout]   13:     0x5a84e41b2318 - std::panicking::rust_panic_with_hook::h53863d4e9018df39
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x5a84e41b20a6 - std::panicking::begin_panic_handler::{{closure}}::h67280e6fa0757873
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:667:13
[INFO] [stdout]   15:     0x5a84e41b0cc9 - std::sys::backtrace::__rust_end_short_backtrace::h1dac3f54d6fbbeb2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x5a84e41b1d6c - rust_begin_unwind
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x5a84e410e010 - core::panicking::panic_fmt::h6792bd1b2bf01041
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panicking.rs:76:14
[INFO] [stdout]   18:     0x5a84e410e09c - core::panicking::panic::hbb5c236a846c507c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panicking.rs:148:5
[INFO] [stdout]   19:     0x5a84e4113c42 - tests::timeout_works::h9ae84e91c919b2de
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r/tests/tests.rs:74:5
[INFO] [stdout]   20:     0x5a84e4113787 - tests::timeout_works::{{closure}}::hac102fe6296a4279
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r/tests/tests.rs:61:19
[INFO] [stdout]   21:     0x5a84e410fa26 - core::ops::function::FnOnce::call_once::haa66573fa24dcffa
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5a84e417fb5b - core::ops::function::FnOnce::call_once::h871bf86719ec9aa4
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5a84e417fb5b - test::__rust_begin_short_backtrace::h2167df1e2b7d76fd
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:632:18
[INFO] [stdout]   24:     0x5a84e417f548 - test::run_test_in_process::{{closure}}::h15d92f75a3adfb79
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:655:60
[INFO] [stdout]   25:     0x5a84e417f548 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb6c8955835a8dbbb
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   26:     0x5a84e417f548 - std::panicking::try::do_call::hc79d0fc888a0bd30
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   27:     0x5a84e417f548 - std::panicking::try::hb8a7878e3a8589d6
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   28:     0x5a84e417f548 - std::panic::catch_unwind::hb8e4600ff0ff5cfa
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   29:     0x5a84e417f548 - test::run_test_in_process::hc2900b0b18688dd6
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:655:27
[INFO] [stdout]   30:     0x5a84e417f548 - test::run_test::{{closure}}::hac2a9cb07be3d48d
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:576:43
[INFO] [stdout]   31:     0x5a84e4142fea - test::run_test::{{closure}}::h4a5b729e66a38cd8
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/test/src/lib.rs:606:41
[INFO] [stdout]   32:     0x5a84e4142fea - std::sys::backtrace::__rust_begin_short_backtrace::h0496fb742492f573
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   33:     0x5a84e4146964 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hbb6d257c550a54bd
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:561:17
[INFO] [stdout]   34:     0x5a84e4146964 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h46696fe4a6832328
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   35:     0x5a84e4146964 - std::panicking::try::do_call::h2625a51b667bfb64
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:557:40
[INFO] [stdout]   36:     0x5a84e4146964 - std::panicking::try::hb956a59a1973aa4c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panicking.rs:520:19
[INFO] [stdout]   37:     0x5a84e4146964 - std::panic::catch_unwind::h64f3b9d1067ef6a0
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/panic.rs:358:14
[INFO] [stdout]   38:     0x5a84e4146964 - std::thread::Builder::spawn_unchecked_::{{closure}}::h42b2841295b6c0b2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/thread/mod.rs:559:30
[INFO] [stdout]   39:     0x5a84e4146964 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h6127de71b8124639
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5a84e41b63cb - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h42f0ba71002a1b2c
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   41:     0x5a84e41b63cb - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::had9e47d7a3dcecb8
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/alloc/src/boxed.rs:1972:9
[INFO] [stdout]   42:     0x5a84e41b63cb - std::sys::pal::unix::thread::Thread::new::thread_start::h48cf765408f6f5f2
[INFO] [stdout]                                at /rustc/202008a1b8de96d2e5b6bc02d379db03a877d34d/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   43:     0x75cb5c00bac3 - <unknown>
[INFO] [stdout]   44:     0x75cb5c09ca04 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     timeout_works
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 99.73s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "2801829099bf22a256cc1e65822da7e6805f1f0467d752fb134d68694f6c4929", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2801829099bf22a256cc1e65822da7e6805f1f0467d752fb134d68694f6c4929", kill_on_drop: false }`
[INFO] [stdout] 2801829099bf22a256cc1e65822da7e6805f1f0467d752fb134d68694f6c4929
