[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 1.83.0 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-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/vigoo/test-r on toolchain 1.83.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.83.0" "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-tc1/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" "+1.83.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded merge v0.1.0
[INFO] [stderr]   Downloaded escape8259 v0.5.3
[INFO] [stderr]   Downloaded merge_derive v0.1.0
[INFO] [stderr]   Downloaded gh-workflow v0.2.1
[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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+1.83.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9fd3141c45003b31b53be229a8475d3c43c3279a347801dbad178ef0fa3ab199
[INFO] running `Command { std: "docker" "start" "-a" "9fd3141c45003b31b53be229a8475d3c43c3279a347801dbad178ef0fa3ab199", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9fd3141c45003b31b53be229a8475d3c43c3279a347801dbad178ef0fa3ab199", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9fd3141c45003b31b53be229a8475d3c43c3279a347801dbad178ef0fa3ab199", kill_on_drop: false }`
[INFO] [stdout] 9fd3141c45003b31b53be229a8475d3c43c3279a347801dbad178ef0fa3ab199
[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=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" "+1.83.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 785d4a4108a8a9584257780606ae4f8d3200ef9797808d2088229a0799dfa624
[INFO] running `Command { std: "docker" "start" "-a" "785d4a4108a8a9584257780606ae4f8d3200ef9797808d2088229a0799dfa624", 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 pin-project-lite v0.2.15
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling smallvec v1.13.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling bytes v1.9.0
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling byteorder v1.5.0
[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 futures-io v0.3.31
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling anstyle-query v1.1.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling clap_lex v0.7.4
[INFO] [stderr]    Compiling anstream v0.6.15
[INFO] [stderr]    Compiling virtue v0.0.13
[INFO] [stderr]    Compiling doctest-file v1.0.0
[INFO] [stderr]    Compiling quick-xml v0.36.2
[INFO] [stderr]    Compiling anstyle-wincon v3.0.4
[INFO] [stderr]    Compiling topological-sort v0.2.2
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling clap_builder v4.5.23
[INFO] [stderr]    Compiling syn v2.0.90
[INFO] [stderr]    Compiling escape8259 v0.5.3
[INFO] [stderr]    Compiling bincode_derive v2.0.0-rc.3
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling once_cell v1.20.2
[INFO] [stderr]    Compiling serde v1.0.216
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling tokio v1.42.0
[INFO] [stderr]    Compiling bincode v2.0.0-rc.3
[INFO] [stderr]    Compiling uuid v1.11.0
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rustversion v1.0.18
[INFO] [stderr]    Compiling ryu v1.0.18
[INFO] [stderr]    Compiling itoa v1.0.14
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling lazy_static v1.5.0
[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 log v0.4.22
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[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 darling_core v0.20.10
[INFO] [stderr]    Compiling merge v0.1.0
[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 tracing-attributes v0.1.28
[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 interprocess v2.2.2
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling clap v4.5.23
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling derive_more-impl v1.0.0
[INFO] [stderr]    Compiling tracing v0.1.41
[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 derive_more v1.0.0
[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 indexmap v2.7.0
[INFO] [stderr]    Compiling test-r-macro v1.1.1 (/opt/rustwide/workdir/test-r-macro)
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling gh-workflow v0.2.1
[INFO] [stderr]    Compiling test-r v1.2.0 (/opt/rustwide/workdir/test-r)
[INFO] [stderr]    Compiling test-r-example v0.0.1 (/opt/rustwide/workdir/example)
[INFO] [stderr]    Compiling test-r-example-tokio v0.0.1 (/opt/rustwide/workdir/example-tokio)
[INFO] [stderr]    Compiling test-r-github v0.0.1 (/opt/rustwide/workdir/github)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 51s
[INFO] running `Command { std: "docker" "inspect" "785d4a4108a8a9584257780606ae4f8d3200ef9797808d2088229a0799dfa624", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "785d4a4108a8a9584257780606ae4f8d3200ef9797808d2088229a0799dfa624", kill_on_drop: false }`
[INFO] [stdout] 785d4a4108a8a9584257780606ae4f8d3200ef9797808d2088229a0799dfa624
[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=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" "+1.83.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f2af9854c5decdad3a1711de5eb827f043bb169fc8e273db34264224e5564e4b
[INFO] running `Command { std: "docker" "start" "-a" "f2af9854c5decdad3a1711de5eb827f043bb169fc8e273db34264224e5564e4b", 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 15.01s
[INFO] running `Command { std: "docker" "inspect" "f2af9854c5decdad3a1711de5eb827f043bb169fc8e273db34264224e5564e4b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2af9854c5decdad3a1711de5eb827f043bb169fc8e273db34264224e5564e4b", kill_on_drop: false }`
[INFO] [stdout] f2af9854c5decdad3a1711de5eb827f043bb169fc8e273db34264224e5564e4b
[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=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" "+1.83.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 78928d0c6e4dfcc66b809b1364761e0c25991d8635124fae7eb55244b6376f8b
[INFO] running `Command { std: "docker" "start" "-a" "78928d0c6e4dfcc66b809b1364761e0c25991d8635124fae7eb55244b6376f8b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r-db29f45bd51d8a75)
[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-825e5689025fd49d)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stderr]    Compiling    Blocking waiting for file lock on build directory
[INFO] [stderr]  test-r-core v1.2.0 (/opt/rustwide/workdir/test-r-core)
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]     Blocking waiting for file lock on build directory
[INFO] [stderr]    Compiling test-r-macro v1.1.1 (/opt/rustwide/workdir/test-r-macro)
[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]     Finished `test` profile [unoptimized + debuginfo] target(s) in 16.66s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio-1ca7efa7905f6231)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 16.79s
[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]      Running[33m unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio-1ca7efa7905f6231)
[INFO] [stderr] Cannot 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] [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/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]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling syn v2.0.90
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling bytes v1.9.0
[INFO] [stderr]    Compiling interprocess v2.2.2
[INFO] [stdout] [1/1] Running test: test_r_example_tokio::inner::tests::sleeping_test_3_timeout
[INFO] [stderr]    Compiling futures-channel v0.3.31
[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 Dep1 10
[INFO] [stdout] 2024-12-15T00:17:56.756113Z  INFO test_r_example_tokio::deps::tests: Initialized tracing
[INFO] [stdout] Creating Dep2 20
[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:     0x5b1229ddfdfa - std::backtrace_rs::backtrace::libunwind::trace::h5a5b8284f2d0c266
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x5b1229ddfdfa - std::backtrace_rs::backtrace::trace_unsynchronized::h76d4f1c9b0b875e3
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5b1229ddfdfa - std::sys::backtrace::_print_fmt::hc4546b8364a537c6
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5b1229ddfdfa - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h5b6bd5631a6d1f6b
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5b1229e05c63 - core::fmt::rt::Argument::fmt::h270f6602a2b96f62
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x5b1229e05c63 - core::fmt::write::h7550c97b06c86515
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/mod.rs:1186:21
[INFO] [stdout]    6:     0x5b1229ddc903 - std::io::Write::write_fmt::h7b09c64fe0be9c84
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x5b1229ddfc42 - std::sys::backtrace::BacktraceLock::print::h2395ccd2c84ba3aa
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x5b1229de0d2c - std::panicking::default_hook::{{closure}}::he19d4c7230e07961
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x5b1229de0b72 - std::panicking::default_hook::hf614597d3c67bbdb
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:295:9
[INFO] [stdout]   10:     0x5b1229de1307 - std::panicking::rust_panic_with_hook::h8942133a8b252070
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:801:13
[INFO] [stdout]   11:     0x5b1229de1166 - std::panicking::begin_panic_handler::{{closure}}::hb5f5963570096b29
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:667:13
[INFO] [stdout]   12:     0x5b1229de02d9 - std::sys::backtrace::__rust_end_short_backtrace::h6208cedc1922feda
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   13:     0x5b1229de0e2c - rust_begin_unwind
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:665:5
[INFO] [stdout]   14:     0x5b12299f6e00 - core::panicking::panic_fmt::h0c3082644d1bf418
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panicking.rs:74:14
[INFO] [stdout]   15:     0x5b1229b575c5 - test_r_core::tokio::run_test::{{closure}}::{{closure}}::{{closure}}::{{closure}}::hb62f1ba5a08a48ac
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:343:51
[INFO] [stdout]   16:     0x5b1229ae3f9f - <core::pin::Pin<P> as core::future::future::Future>::poll::h3cd540761164a441
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/future/future.rs:123:9
[INFO] [stdout]   17:     0x5b1229ae4af3 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::future::future::Future>::poll::h9f999ebe969dfeb1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:297:9
[INFO] [stdout]   18:     0x5b1229b3e5f1 - <futures_util::future::future::catch_unwind::CatchUnwind<Fut> as core::future::future::Future>::poll::{{closure}}::he65af66e472bf9b9
[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:     0x5b1229ae5af4 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfe25d7fe483d19d4
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   20:     0x5b1229afa307 - std::panicking::try::do_call::h31ee484ffb632288
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   21:     0x5b1229b48e4b - __rust_try
[INFO] [stdout]   22:     0x5b1229b43634 - std::panicking::try::hcf05e1aaacdf451d
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   23:     0x5b1229b43634 - std::panic::catch_unwind::hfc672ca814d578ee
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   24:     0x5b1229b3e511 - <futures_util::future::future::catch_unwind::CatchUnwind<Fut> as core::future::future::Future>::poll::ha1616be9424204b5
[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:     0x5b1229b56c74 - test_r_core::tokio::run_test::{{closure}}::{{closure}}::{{closure}}::hfa56cd87638b9cce
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:362:26
[INFO] [stdout]   26:     0x5b1229ae4040 - <core::pin::Pin<P> as core::future::future::Future>::poll::h98fcac921b1ab429
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/future/future.rs:123:9
[INFO] [stdout]   27:     0x5b1229b54609 - test_r_core::tokio::run_with_flakiness_control::{{closure}}::hc1dc9145a8fa970c
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:248:25
[INFO] [stdout]   28:     0x5b1229b55ca9 - test_r_core::tokio::run_test::{{closure}}::hc5b281e403938605
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:365:18
[INFO] [stdout]   29:     0x5b1229b52737 - test_r_core::tokio::test_thread::{{closure}}::h55761662087eca9b
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:185:18
[INFO] [stdout]   30:     0x5b1229ae3f5f - <core::pin::Pin<P> as core::future::future::Future>::poll::h398de7b6a19bce08
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/future/future.rs:123:9
[INFO] [stdout]   31:     0x5b1229b67751 - tokio::runtime::park::CachedParkThread::block_on::{{closure}}::h0b15ef76ebef9a48
[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:     0x5b1229b67552 - tokio::runtime::coop::with_budget::hd5a59627924f75d7
[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:     0x5b1229b67552 - tokio::runtime::coop::budget::h199d460eb8e16683
[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:     0x5b1229b67552 - tokio::runtime::park::CachedParkThread::block_on::hd6ce95aab772348c
[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:     0x5b1229ae38e4 - tokio::runtime::context::blocking::BlockingRegionGuard::block_on::h2d8feae775094e97
[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:     0x5b1229b4b65f - tokio::runtime::handle::Handle::block_on_inner::{{closure}}::h76d57848fb42baa3
[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:     0x5b1229b14a28 - tokio::runtime::context::runtime::enter_runtime::h0760db8b5dcd061e
[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:     0x5b1229b4b56e - tokio::runtime::handle::Handle::block_on_inner::h783ef7f59b7e3ccd
[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:     0x5b1229b4bc9e - tokio::runtime::handle::Handle::block_on::h6fe8f9efbdf82cb1
[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:     0x5b1229b50ae1 - test_r_core::tokio::async_test_runner::{{closure}}::{{closure}}::h100e2be1a6c2a2a8
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/tokio.rs:93:17
[INFO] [stdout]   41:     0x5b1229af5dea - <tokio::runtime::blocking::task::BlockingTask<T> as core::future::future::Future>::poll::h13e4ff2228141aeb
[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:     0x5b1229adffb3 - tokio::runtime::task::core::Core<T,S>::poll::{{closure}}::hde4a6941da246967
[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:     0x5b1229adecd1 - tokio::loom::std::unsafe_cell::UnsafeCell<T>::with_mut::h0097e809370a9781
[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:     0x5b1229adecd1 - tokio::runtime::task::core::Core<T,S>::poll::h7eec82010d68838f
[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:     0x5b1229aec03e - tokio::runtime::task::harness::poll_future::{{closure}}::h3db381b9a95faa8b
[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:     0x5b1229ae59a1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::he72d9807c70fd0aa
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   47:     0x5b1229afa549 - std::panicking::try::do_call::h4a39f5e7f3dff808
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   48:     0x5b1229b48e4b - __rust_try
[INFO] [stdout]   49:     0x5b1229b40cc6 - std::panicking::try::h219579c2f5978bbd
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   50:     0x5b1229b40cc6 - std::panic::catch_unwind::h4b5665dd120fbbae
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   51:     0x5b1229aea900 - tokio::runtime::task::harness::poll_future::h74633182f83a6f43
[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:     0x5b1229aed68e - tokio::runtime::task::harness::Harness<T,S>::poll_inner::h3a4cc6b843714749
[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:     0x5b1229af0b97 - tokio::runtime::task::harness::Harness<T,S>::poll::ha7f79ae116bec794
[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:     0x5b1229abc2ad - tokio::runtime::task::raw::poll::h15555f115648fefb
[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:     0x5b1229bc45a7 - tokio::runtime::task::raw::RawTask::poll::hf3ac9f30d4d6a804
[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:     0x5b1229c2f987 - tokio::runtime::task::UnownedTask<S>::run::h61f203581149cbc1
[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:     0x5b1229bc5787 - tokio::runtime::blocking::pool::Task::run::h5ccb7f57a83321e6
[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:     0x5b1229bcb31e - tokio::runtime::blocking::pool::Inner::run::h7109b6d85ba8194a
[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:     0x5b1229bcb034 - tokio::runtime::blocking::pool::Spawner::spawn_thread::{{closure}}::h272920435869829e
[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:     0x5b1229bd4f66 - std::sys::backtrace::__rust_begin_short_backtrace::hfbc52ce5e9d09505
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   61:     0x5b1229bb96f2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hd34944173300a2a5
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:538:17
[INFO] [stdout]   62:     0x5b1229c20d52 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hdf85e27cb1bb7a01
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   63:     0x5b1229be2713 - std::panicking::try::do_call::h52ade3f913a987f6
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   64:     0x5b1229bc004b - __rust_try
[INFO] [stdout]   65:     0x5b1229bb9303 - std::panicking::try::h7a4d65abe646ba80
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   66:     0x5b1229bb9303 - std::panic::catch_unwind::h3250801a917217d9
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   67:     0x5b1229bb9303 - std::thread::Builder::spawn_unchecked_::{{closure}}::h44f36e9c6a37f3ba
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:537:30
[INFO] [stdout]   68:     0x5b1229c1188f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e10f171c86e95fe
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   69:     0x5b1229de512b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf75717d9f28faebf
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   70:     0x5b1229de512b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h7bd883a5f3c5f3c1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   71:     0x5b1229de512b - std::sys::pal::unix::thread::Thread::new::thread_start::hcc78f3943333fa94
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   72:     0x76cdd7464ac3 - <unknown>
[INFO] [stdout]   73:     0x76cdd74f5a04 - 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.364s
[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] [stderr]    Compiling tokio v1.42.0
[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::benches::bench1
[INFO] [stdout] [ 6/57] Finished test: test_r_example_tokio::benches::bench1                            [BENCH]                 480.36 ns/iter (+/- 974.09)
[INFO] [stdout] [ 7/57] Running test: test_r_example_tokio::benches::bench2
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling clap_derive v4.5.18
[INFO] [stderr]    Compiling ctor v0.2.9
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stdout] [ 7/57] Finished test: test_r_example_tokio::benches::bench2                            [BENCH]                 742.18 ns/iter (+/- 789.75)
[INFO] [stdout] [ 8/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_0
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stdout] [ 8/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_0        [PASSED]
[INFO] [stdout] [ 9/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_1
[INFO] [stdout] [ 9/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_1        [PASSED]
[INFO] [stdout] [10/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_2
[INFO] [stdout] [10/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_2        [PASSED]
[INFO] [stdout] [11/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_3
[INFO] [stdout] [11/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_3        [PASSED]
[INFO] [stdout] [12/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_4
[INFO] [stdout] [12/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_4        [PASSED]
[INFO] [stdout] [13/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_5
[INFO] [stdout] [13/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_5        [PASSED]
[INFO] [stdout] [14/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_6
[INFO] [stdout] [14/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_6        [PASSED]
[INFO] [stdout] [15/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_7
[INFO] [stdout] [15/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_7        [PASSED]
[INFO] [stdout] [16/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_8
[INFO] [stdout] [16/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_8        [PASSED]
[INFO] [stdout] [17/57] Running test: test_r_example_tokio::generated::generate_tests_1::test_9
[INFO] [stdout] [17/57] Finished test: test_r_example_tokio::generated::generate_tests_1::test_9        [PASSED]
[INFO] [stdout] [18/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_0
[INFO] [stdout] [18/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_0        [PASSED]
[INFO] [stdout] [19/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_1
[INFO] [stdout] [19/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_1        [PASSED]
[INFO] [stdout] [20/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_2
[INFO] [stdout] [20/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_2        [PASSED]
[INFO] [stdout] [21/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_3
[INFO] [stdout] [21/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_3        [PASSED]
[INFO] [stdout] [22/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_4
[INFO] [stdout] [22/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_4        [PASSED]
[INFO] [stdout] [23/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_5
[INFO] [stdout] [23/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_5        [PASSED]
[INFO] [stdout] [24/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_6
[INFO] [stdout] [24/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_6        [PASSED]
[INFO] [stdout] [25/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_7
[INFO] [stdout] [25/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_7        [PASSED]
[INFO] [stdout] [26/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_8
[INFO] [stdout] [26/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_8        [PASSED]
[INFO] [stdout] [27/57] Running test: test_r_example_tokio::generated::generate_tests_2::test_9
[INFO] [stdout] [27/57] Finished test: test_r_example_tokio::generated::generate_tests_2::test_9        [PASSED]
[INFO] [stdout] [28/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_0
[INFO] [stdout] [28/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_0        [PASSED]
[INFO] [stdout] [29/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_1
[INFO] [stdout] [29/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_1        [PASSED]
[INFO] [stdout] [30/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_2
[INFO] [stdout] [30/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_2        [PASSED]
[INFO] [stdout] [31/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_3
[INFO] [stdout] [31/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_3        [PASSED]
[INFO] [stdout] [32/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_4
[INFO] [stdout] [32/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_4        [PASSED]
[INFO] [stdout] [33/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_5
[INFO] [stdout] [33/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_5        [PASSED]
[INFO] [stdout] [34/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_6
[INFO] [stdout] [34/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_6        [PASSED]
[INFO] [stdout] [35/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_7
[INFO] [stdout] [35/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_7        [PASSED]
[INFO] [stdout] [36/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_8
[INFO] [stdout] [36/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_8        [PASSED]
[INFO] [stdout] [37/57] Running test: test_r_example_tokio::generated::generate_tests_3::test_9
[INFO] [stdout] [37/57] Finished test: test_r_example_tokio::generated::generate_tests_3::test_9        [PASSED]
[INFO] [stdout] [38/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_0
[INFO] [stdout] [38/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_0        [PASSED]
[INFO] [stdout] [39/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_1
[INFO] [stdout] [39/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_1        [PASSED]
[INFO] [stdout] [40/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_2
[INFO] [stdout] [40/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_2        [PASSED]
[INFO] [stdout] [41/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_3
[INFO] [stdout] [41/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_3        [PASSED]
[INFO] [stdout] [42/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_4
[INFO] [stdout] [42/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_4        [PASSED]
[INFO] [stdout] [43/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_5
[INFO] [stdout] [43/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_5        [PASSED]
[INFO] [stdout] [44/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_6
[INFO] [stdout] [44/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_6        [PASSED]
[INFO] [stdout] [45/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_7
[INFO] [stdout] [45/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_7        [PASSED]
[INFO] [stdout] [46/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_8
[INFO] [stdout] [46/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_8        [PASSED]
[INFO] [stdout] [47/57] Running test: test_r_example_tokio::generated::generate_tests_4::test_9
[INFO] [stdout] [47/57] Finished test: test_r_example_tokio::generated::generate_tests_4::test_9        [PASSED]
[INFO] [stdout] [48/57] Running test: test_r_example_tokio::tests::this_too
[INFO] [stdout] [48/57] Finished test: test_r_example_tokio::tests::this_too                            [PASSED]
[INFO] [stdout] [49/57] Running test: test_r_example_tokio::tests::panic_test_1
[INFO] [stdout] [49/57] Finished test: test_r_example_tokio::tests::panic_test_1                        [PASSED]
[INFO] [stdout] [50/57] Running test: test_r_example_tokio::tests::panic_test_2a
[INFO] [stdout] [50/57] Finished test: test_r_example_tokio::tests::panic_test_2a                       [PASSED]
[INFO] [stdout] [51/57] Running test: test_r_example_tokio::tests::result_based_test_ok
[INFO] [stdout] [51/57] Finished test: test_r_example_tokio::tests::result_based_test_ok                [PASSED]
[INFO] [stdout] [52/57] Running test: test_r_example_tokio::flakiness::flaky_test
[INFO] [stdout] [52/57] Finished test: test_r_example_tokio::flakiness::flaky_test                      [PASSED]
[INFO] [stdout] [53/57] Running test: test_r_example_tokio::flakiness::non_flaky_test
[INFO] [stdout] [53/57] Finished test: test_r_example_tokio::flakiness::non_flaky_test                  [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] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling clap v4.5.23
[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] [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 test-r-macro v1.1.1 (/opt/rustwide/workdir/test-r-macro)
[INFO] [stdout] [57/57] Finished test: test_r_example_tokio::inner::tests::sleeping_test_2              [PASSED]
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/test_r_example_tokio_bin-b726b7c9815aff2d)
[INFO] [stderr]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-8f9e0ecdec3f1d33)
[INFO] [stdout] test result: ok; 54 passed; 0 failed; 1 ignored; 2 measured; 4 filtered out; finished in 39.422s
[INFO] [stdout] 
[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] 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.129s
[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] [stdout] test can_run_async_examples ... ok
[INFO] [stderr]    Compiling test-r v1.2.0 (/opt/rustwide/workdir/test-r)
[INFO] [stderr]    Compiling test-r-example v0.0.1 (/opt/rustwide/workdir/example)
[INFO] [stdout] test async_output_capturing_works has been running for over 60 seconds
[INFO] [stdout] test can_run_sync_examples has been running for over 60 seconds
[INFO] [stdout] test exit_code_is_101_on_failure has been running for over 60 seconds
[INFO] [stdout] test sync_output_capturing_works has been running for over 60 seconds
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 00s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example-f1d040bc6e6d30a0)
[INFO] [stderr] [33m    Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 00s
[INFO] [stderr] Cannot 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] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_r_example-f1d040bc6e6d30a0)
[INFO] [stdout] Running 1 tests
[INFO] [stdout] 
[INFO] [stdout] [1/1] Running test: test_r_example::tests::it_does_work
[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:     0x5a2518d4daaa - std::backtrace_rs::backtrace::libunwind::trace::h5a5b8284f2d0c266
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x5a2518d4daaa - std::backtrace_rs::backtrace::trace_unsynchronized::h76d4f1c9b0b875e3
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5a2518d4daaa - std::sys::backtrace::_print_fmt::hc4546b8364a537c6
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5a2518d4daaa - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h5b6bd5631a6d1f6b
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5a2518d72be3 - core::fmt::rt::Argument::fmt::h270f6602a2b96f62
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x5a2518d72be3 - core::fmt::write::h7550c97b06c86515
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/mod.rs:1186:21
[INFO] [stdout]    6:     0x5a2518d4a723 - std::io::Write::write_fmt::h7b09c64fe0be9c84
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x5a2518d4d8f2 - std::sys::backtrace::BacktraceLock::print::h2395ccd2c84ba3aa
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x5a2518d4e9dc - std::panicking::default_hook::{{closure}}::he19d4c7230e07961
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x5a2518d4e822 - std::panicking::default_hook::hf614597d3c67bbdb
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:295:9
[INFO] [stdout]   10:     0x5a2518d4efb7 - std::panicking::rust_panic_with_hook::h8942133a8b252070
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:801:13
[INFO] [stdout]   11:     0x5a2518d4ee4a - std::panicking::begin_panic_handler::{{closure}}::hb5f5963570096b29
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:674:13
[INFO] [stdout]   12:     0x5a2518d4df89 - std::sys::backtrace::__rust_end_short_backtrace::h6208cedc1922feda
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   13:     0x5a2518d4eadc - rust_begin_unwind
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:665:5
[INFO] [stdout]   14:     0x5a2518af7be0 - core::panicking::panic_fmt::h0c3082644d1bf418
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panicking.rs:74:14
[INFO] [stdout]   15:     0x5a2518af7fab - core::panicking::assert_failed_inner::h2ddf1fcc067fa858
[INFO] [stdout]   16:     0x5a2518bbc51c - core::panicking::assert_failed::hab3ada464064253c
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panicking.rs:367:5
[INFO] [stdout]   17:     0x5a2518afcfb8 - test_r_example::tests::it_does_work::h732ca8c146874b57
[INFO] [stdout]                                at /opt/rustwide/workdir/example/src/lib.rs:15:9
[INFO] [stdout]   18:     0x5a2518afce68 - test_r_example::tests::test_r_register_it_does_work::{{closure}}::hef4213181fe5714c
[INFO] [stdout]                                at /opt/rustwide/workdir/example/src/lib.rs:10:5
[INFO] [stdout]   19:     0x5a2518b4809f - test_r_core::sync::run_sync_test_function::{{closure}}::{{closure}}::h60061b2293cac344
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:279:38
[INFO] [stdout]   20:     0x5a2518b687b0 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h29d9dd265ed11cd2
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   21:     0x5a2518b4fd71 - std::panicking::try::do_call::h258e84955122ff8f
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   22:     0x5a2518b70a4b - __rust_try
[INFO] [stdout]   23:     0x5a2518b6c0e2 - std::panicking::try::h2166b1af4119ee2a
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   24:     0x5a2518b6c0e2 - std::panic::catch_unwind::h6791502938d58b68
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   25:     0x5a2518b47fbc - test_r_core::sync::run_sync_test_function::{{closure}}::h5eed6eb3197ff40d
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:278:21
[INFO] [stdout]   26:     0x5a2518b4736b - test_r_core::sync::run_with_flakiness_control::h4c9c2452e9a49a1f
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:219:13
[INFO] [stdout]   27:     0x5a2518b47a54 - test_r_core::sync::run_sync_test_function::h2611e4369db376c8
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:275:17
[INFO] [stdout]   28:     0x5a2518b46407 - test_r_core::sync::test_thread::h3d64f7caa1fa2771
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:151:21
[INFO] [stdout]   29:     0x5a2518b456d5 - test_r_core::sync::test_runner::{{closure}}::h8a1fb330173129f4
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r-core/src/sync.rs:74:17
[INFO] [stdout]   30:     0x5a2518b1dd61 - std::sys::backtrace::__rust_begin_short_backtrace::he4943b4e04a8e33c
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   31:     0x5a2518b653ce - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h5e9cb4d55c0cd9ae
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:538:17
[INFO] [stdout]   32:     0x5a2518b6883e - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h8486d25e1ccd3130
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   33:     0x5a2518b4fdd1 - std::panicking::try::do_call::h634c015ed67d085b
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   34:     0x5a2518b6874b - __rust_try
[INFO] [stdout]   35:     0x5a2518b643c2 - std::panicking::try::h04e27526b89d89bd
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   36:     0x5a2518b643c2 - std::panic::catch_unwind::he59c7320209a377c
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   37:     0x5a2518b643c2 - std::thread::Builder::spawn_unchecked_::{{closure}}::h5651b319e251a760
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:537:30
[INFO] [stdout]   38:     0x5a2518b95dff - core::ops::function::FnOnce::call_once{{vtable.shim}}::had429af20ad772ba
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   39:     0x5a2518d52b2b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf75717d9f28faebf
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   40:     0x5a2518d52b2b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h7bd883a5f3c5f3c1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   41:     0x5a2518d52b2b - std::sys::pal::unix::thread::Thread::new::thread_start::hcc78f3943333fa94
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   42:     0x7eb8f1ab8ac3 - <unknown>
[INFO] [stdout]   43:     0x7eb8f1b49a04 - 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.091s
[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] test exit_code_is_101_on_failure ... ok
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] test sync_output_capturing_works ... ok
[INFO] [stdout] Print from non_flaky test
[INFO] [stdout] test async_output_capturing_works ... 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] b99ec2df-ae7d-4ff6-b8d4-58071f1d3838
[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]                  17.35 ns/iter (+/- 8.37)
[INFO] [stdout] [ 7/15] Running test: test_r_example::tests::bench2
[INFO] [stdout] [ 7/15] Finished test: test_r_example::tests::bench2                          [BENCH]                  16.79 ns/iter (+/- 6.46)
[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] [ 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] [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] [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] [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] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/test_r_example_bin-0ad510da6000a0aa)
[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 48.821s
[INFO] [stdout] 
[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-ee231f91cc688c95)
[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.065s
[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] [stderr] error: test failed, to rerun pass `-p test-r --test tests`
[INFO] [stdout] test can_run_sync_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:     0x6354b6edfdfa - std::backtrace_rs::backtrace::libunwind::trace::h5a5b8284f2d0c266
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x6354b6edfdfa - std::backtrace_rs::backtrace::trace_unsynchronized::h76d4f1c9b0b875e3
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x6354b6edfdfa - std::sys::backtrace::_print_fmt::hc4546b8364a537c6
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x6354b6edfdfa - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h5b6bd5631a6d1f6b
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x6354b6f053b3 - core::fmt::rt::Argument::fmt::h270f6602a2b96f62
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x6354b6f053b3 - core::fmt::write::h7550c97b06c86515
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/fmt/mod.rs:1186:21
[INFO] [stdout]    6:     0x6354b6edcca3 - std::io::Write::write_fmt::h509ff860e5956e5e
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x6354b6edfc42 - std::sys::backtrace::BacktraceLock::print::h2395ccd2c84ba3aa
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x6354b6ee125a - std::panicking::default_hook::{{closure}}::he19d4c7230e07961
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x6354b6ee1064 - std::panicking::default_hook::hf614597d3c67bbdb
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x6354b6eac5d9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h39b51ce4599c23d1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x6354b6eac5d9 - test::test_main::{{closure}}::he977ae839a582784
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x6354b6ee18f8 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h9455091b6b06f9fb
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x6354b6ee18f8 - std::panicking::rust_panic_with_hook::h8942133a8b252070
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x6354b6ee1686 - std::panicking::begin_panic_handler::{{closure}}::hb5f5963570096b29
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:667:13
[INFO] [stdout]   15:     0x6354b6ee02d9 - std::sys::backtrace::__rust_end_short_backtrace::h6208cedc1922feda
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x6354b6ee134c - rust_begin_unwind
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x6354b6e40fd0 - core::panicking::panic_fmt::h0c3082644d1bf418
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panicking.rs:74:14
[INFO] [stdout]   18:     0x6354b6e4105c - core::panicking::panic::h957f98c65a3b3074
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panicking.rs:148:5
[INFO] [stdout]   19:     0x6354b6e46e12 - tests::timeout_works::h2fb6cdb5f4823df4
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r/tests/tests.rs:74:5
[INFO] [stdout]   20:     0x6354b6e46957 - tests::timeout_works::{{closure}}::h132b6c8e0d7be3fd
[INFO] [stdout]                                at /opt/rustwide/workdir/test-r/tests/tests.rs:61:19
[INFO] [stdout]   21:     0x6354b6e429d6 - core::ops::function::FnOnce::call_once::h676c88b856d80287
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x6354b6eb0f9b - core::ops::function::FnOnce::call_once::h109cf8051a59b34e
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x6354b6eb0f9b - test::__rust_begin_short_backtrace::hf4fff89d377446ba
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:621:18
[INFO] [stdout]   24:     0x6354b6eb07d7 - test::run_test_in_process::{{closure}}::h0e39181dd7ee5a43
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:644:60
[INFO] [stdout]   25:     0x6354b6eb07d7 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc5a66a30f0bdfd1f
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   26:     0x6354b6eb07d7 - std::panicking::try::do_call::h873b7cf639554a5e
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   27:     0x6354b6eb07d7 - std::panicking::try::hdb19862fad9c8bd1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   28:     0x6354b6eb07d7 - std::panic::catch_unwind::h281fe732d236d260
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   29:     0x6354b6eb07d7 - test::run_test_in_process::h949b6df8457ed338
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:644:27
[INFO] [stdout]   30:     0x6354b6eb07d7 - test::run_test::{{closure}}::h52c556a6d4740e10
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:565:43
[INFO] [stdout]   31:     0x6354b6e75ab4 - test::run_test::{{closure}}::h4237f6381c45ccb9
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/test/src/lib.rs:595:41
[INFO] [stdout]   32:     0x6354b6e75ab4 - std::sys::backtrace::__rust_begin_short_backtrace::h505c97a579eca628
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   33:     0x6354b6e7917e - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h9e86b2e2bc107ea3
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:538:17
[INFO] [stdout]   34:     0x6354b6e7917e - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h8b06ddb275ff4a25
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   35:     0x6354b6e7917e - std::panicking::try::do_call::hb26ecd7cd9a9355d
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:557:40
[INFO] [stdout]   36:     0x6354b6e7917e - std::panicking::try::h8ddeca9d705f9069
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panicking.rs:520:19
[INFO] [stdout]   37:     0x6354b6e7917e - std::panic::catch_unwind::h49e1c33078844c46
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/panic.rs:358:14
[INFO] [stdout]   38:     0x6354b6e7917e - std::thread::Builder::spawn_unchecked_::{{closure}}::h6073db6ff56ad35d
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/thread/mod.rs:537:30
[INFO] [stdout]   39:     0x6354b6e7917e - core::ops::function::FnOnce::call_once{{vtable.shim}}::h2c5a5f3779c774f1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x6354b6ee57ab - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf75717d9f28faebf
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   41:     0x6354b6ee57ab - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h7bd883a5f3c5f3c1
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x6354b6ee57ab - std::sys::pal::unix::thread::Thread::new::thread_start::hcc78f3943333fa94
[INFO] [stdout]                                at /rustc/90b35a6239c3d8bdabc530a6a0816f7ff89a0aaf/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   43:     0x75e5cf6b6ac3 - <unknown>
[INFO] [stdout]   44:     0x75e5cf747a04 - 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 109.73s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "78928d0c6e4dfcc66b809b1364761e0c25991d8635124fae7eb55244b6376f8b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "78928d0c6e4dfcc66b809b1364761e0c25991d8635124fae7eb55244b6376f8b", kill_on_drop: false }`
[INFO] [stdout] 78928d0c6e4dfcc66b809b1364761e0c25991d8635124fae7eb55244b6376f8b
