[INFO] cloning repository https://github.com/ajesipow/cached [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ajesipow/cached" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fajesipow%2Fcached", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fajesipow%2Fcached'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 041cb48bf1c1a02f8dd3a65efd9b1cfa232566d8 [INFO] testing ajesipow/cached against master#c5842b0be783dffa5a49693541acb79115c7eeef for pr-102570 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fajesipow%2Fcached" "/workspace/builds/worker-0/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-0/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/ajesipow/cached on toolchain c5842b0be783dffa5a49693541acb79115c7eeef [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/ajesipow/cached [INFO] finished tweaking git repo https://github.com/ajesipow/cached [INFO] tweaked toml for git repo https://github.com/ajesipow/cached written to /workspace/builds/worker-0/source/Cargo.toml [INFO] crate git repo https://github.com/ajesipow/cached already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 74dd8167e89bda06dac4ee7e780170712a70513c8aca85d51d18f8d86592a3f4 [INFO] running `Command { std: "docker" "start" "-a" "74dd8167e89bda06dac4ee7e780170712a70513c8aca85d51d18f8d86592a3f4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "74dd8167e89bda06dac4ee7e780170712a70513c8aca85d51d18f8d86592a3f4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "74dd8167e89bda06dac4ee7e780170712a70513c8aca85d51d18f8d86592a3f4", kill_on_drop: false }` [INFO] [stdout] 74dd8167e89bda06dac4ee7e780170712a70513c8aca85d51d18f8d86592a3f4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] a5d87bd88716693c3f9bb6adb27fd1f144cfa011253aa43670e2c2d84b342546 [INFO] running `Command { std: "docker" "start" "-a" "a5d87bd88716693c3f9bb6adb27fd1f144cfa011253aa43670e2c2d84b342546", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.37 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling syn v1.0.91 [INFO] [stderr] Compiling libc v0.2.123 [INFO] [stderr] Compiling log v0.4.16 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling async-trait v0.1.53 [INFO] [stderr] Compiling tracing-core v0.1.26 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling quote v1.0.18 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling mio v0.8.2 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling tokio-macros v1.8.0 [INFO] [stderr] Compiling tracing-attributes v0.1.21 [INFO] [stderr] Compiling tokio v1.19.2 [INFO] [stderr] Compiling tracing v0.1.34 [INFO] [stderr] Compiling cached v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 23.28s [INFO] running `Command { std: "docker" "inspect" "a5d87bd88716693c3f9bb6adb27fd1f144cfa011253aa43670e2c2d84b342546", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a5d87bd88716693c3f9bb6adb27fd1f144cfa011253aa43670e2c2d84b342546", kill_on_drop: false }` [INFO] [stdout] a5d87bd88716693c3f9bb6adb27fd1f144cfa011253aa43670e2c2d84b342546 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c6613a86f89782151023b709f3715c7f8cdae0f7368d2ba3303df9909a6d4ca0 [INFO] running `Command { std: "docker" "start" "-a" "c6613a86f89782151023b709f3715c7f8cdae0f7368d2ba3303df9909a6d4ca0", kill_on_drop: false }` [INFO] [stderr] Compiling semver v1.0.9 [INFO] [stderr] Compiling rustc_version v0.4.0 [INFO] [stderr] Compiling rstest v0.12.0 [INFO] [stderr] Compiling cached v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 12.24s [INFO] running `Command { std: "docker" "inspect" "c6613a86f89782151023b709f3715c7f8cdae0f7368d2ba3303df9909a6d4ca0", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c6613a86f89782151023b709f3715c7f8cdae0f7368d2ba3303df9909a6d4ca0", kill_on_drop: false }` [INFO] [stdout] c6613a86f89782151023b709f3715c7f8cdae0f7368d2ba3303df9909a6d4ca0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 0a3b0bdffe95110ad0bcac75c37f9909b851738c37dc90d4049cc034606d4fa6 [INFO] running `Command { std: "docker" "start" "-a" "0a3b0bdffe95110ad0bcac75c37f9909b851738c37dc90d4049cc034606d4fa6", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.05s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cached-60baa9a15f98be4c) [INFO] [stdout] running 68 tests [INFO] [stdout] test db::test::test_clearing_db_works_main_db ... ok [INFO] [stdout] test db::test::test_ttl_in_past_does_not_store_value ... ok [INFO] [stdout] test frame::header::test::test_parsing_request_header_with_too_long_key_fails ... ok [INFO] [stdout] test db::test::test_clearing_db_works ... ok [INFO] [stdout] test db::test::test_removing_key_is_also_removed_from_ttl_set_main_db ... ok [INFO] [stdout] test db::test::test_contains_key_works ... ok [INFO] [stdout] test db::test::test_ttl_in_future_returns_value_main_db ... ok [INFO] [stdout] test db::test::test_ttl_elapsed_does_not_return_value_from_main_db ... ok [INFO] [stdout] test db::test::test_ttl_elapsed_does_not_return_value_from_db ... ok [INFO] [stdout] test frame::header::test::test_parsing_request_header_with_valid_long_key_works ... ok [INFO] [stdout] test frame::header::test::test_parsing_request_header_with_too_long_value_fails ... ok [INFO] [stdout] test frame::header::test::test_parsing_request_header_with_valid_long_value_works ... ok [INFO] [stdout] test frame::header::test::test_parsing_response_header_with_valid_long_key_works ... ok [INFO] [stdout] test frame::header::test::test_parsing_response_header_with_valid_long_value_works ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialisation_works ... ok [INFO] [stdout] test frame::header::test::test_parsing_response_header_with_too_long_value_fails ... ok [INFO] [stdout] test db::test::test_ttl_in_future_returns_value_db ... FAILED [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_2 ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_3 ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_6 ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_7 ... ok [INFO] [stdout] test primitives::test::test_op_code_serialisation ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_2 ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_1 ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_3 ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_5 ... ok [INFO] [stdout] test frame::header::test::test_parsing_response_header_with_too_long_key_fails ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_4 ... ok [INFO] [stdout] test primitives::test::test_op_code_deserialization_fails_for_wrong_codes::case_1 ... ok [INFO] [stdout] test frame::header::test::test_parsing_request_header_with_valid_long_key_and_value_works ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_5 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_01 ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_works ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_02 ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_7 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_03 ... ok [INFO] [stdout] test primitives::test::test_status_code_serialization ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_6 ... ok [INFO] [stdout] test primitives::test::test_status_code_deserialization_fails_for_wrong_codes::case_4 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_04 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_05 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_08 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_06 ... ok [INFO] [stdout] test request::test::test_conversion_from_valid_request_frame_to_request_works::case_2 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_11 ... ok [INFO] [stdout] test request::test::test_conversion_from_valid_request_frame_to_request_works::case_3 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_01 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_10 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_02 ... ok [INFO] [stdout] test request::test::test_conversion_from_valid_request_frame_to_request_works::case_1 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_07 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_05 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_06 ... ok [INFO] [stdout] test request::test::test_conversion_from_invalid_request_frame_to_request_fails::case_09 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_07 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_03 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_08 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_10 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_04 ... ok [INFO] [stdout] test request::test::test_conversion_from_valid_request_frame_to_request_works::case_4 ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_11 ... ok [INFO] [stdout] test response::test::test_conversion_from_valid_response_frame_to_response_works::case_1 ... ok [INFO] [stdout] test frame::header::test::test_parsing_response_header_with_valid_long_key_and_value_works ... ok [INFO] [stdout] test response::test::test_conversion_from_invalid_response_frame_to_response_fails::case_09 ... ok [INFO] [stdout] test response::test::test_conversion_from_valid_response_frame_to_response_works::case_2 ... ok [INFO] [stdout] test response::test::test_conversion_from_valid_response_frame_to_response_works::case_3 ... ok [INFO] [stdout] test response::test::test_conversion_from_valid_response_frame_to_response_works::case_4 ... ok [INFO] [stdout] test response::test::test_conversion_from_valid_response_frame_to_response_works::case_5 ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- db::test::test_ttl_in_future_returns_value_db stdout ---- [INFO] [stdout] thread 'db::test::test_ttl_in_future_returns_value_db' panicked at 'assertion failed: db.get(key).await.is_some()', src/db.rs:309:9 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5627fd5b7b60 - std::backtrace_rs::backtrace::libunwind::trace::he04b6d40112d6639 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x5627fd5b7b60 - std::backtrace_rs::backtrace::trace_unsynchronized::h93bb5736cc0446d6 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5627fd5b7b60 - std::sys_common::backtrace::_print_fmt::h194608c93e335f88 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x5627fd5b7b60 - ::fmt::hcd25e852ea89b4f5 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x5627fd5db96e - core::fmt::write::h5fe0ed2793f92a83 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/fmt/mod.rs:1209:17 [INFO] [stdout] 5: 0x5627fd5b4625 - std::io::Write::write_fmt::h3774d34429f36763 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x5627fd5b7925 - std::sys_common::backtrace::_print::h137275aa061b9520 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x5627fd5b7925 - std::sys_common::backtrace::print::h0abc92ad99a2e9ea [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x5627fd5b97cf - std::panicking::default_hook::{{closure}}::had7442d4100942fb [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x5627fd5b948e - std::panicking::default_hook::he29a4b4ac8c0db9a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x5627fd519dc7 - as core::ops::function::Fn>::call::h91b99717c0adaf29 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2032:9 [INFO] [stdout] 11: 0x5627fd519dc7 - test::test_main::{{closure}}::h70ceb3143bf6e9f2 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x5627fd5b9eed - as core::ops::function::Fn>::call::h12d6a9e51a6fcc61 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2032:9 [INFO] [stdout] 13: 0x5627fd5b9eed - std::panicking::rust_panic_with_hook::hb248569adc7e19ef [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:692:13 [INFO] [stdout] 14: 0x5627fd5b9c21 - std::panicking::begin_panic_handler::{{closure}}::h8fbe136907bbc5ab [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:577:13 [INFO] [stdout] 15: 0x5627fd5b800c - std::sys_common::backtrace::__rust_end_short_backtrace::hb7948ddc456dafc4 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 16: 0x5627fd5b9982 - rust_begin_unwind [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:575:5 [INFO] [stdout] 17: 0x5627fd49b7a3 - core::panicking::panic_fmt::h1c2fe0c2fa8ba0a1 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/panicking.rs:65:14 [INFO] [stdout] 18: 0x5627fd49b87d - core::panicking::panic::ha992c9801af8951c [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/panicking.rs:115:5 [INFO] [stdout] 19: 0x5627fd4d2841 - cached::db::test::test_ttl_in_future_returns_value_db::{{closure}}::h53dba56d5204d36b [INFO] [stdout] at /opt/rustwide/workdir/src/db.rs:309:9 [INFO] [stdout] 20: 0x5627fd4b6dcc - as core::future::future::Future>::poll::he9d45805a426c4f2 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/future/mod.rs:91:19 [INFO] [stdout] 21: 0x5627fd4b5cdf - as core::future::future::Future>::poll::he7490d3443a30870 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/future/future.rs:124:9 [INFO] [stdout] 22: 0x5627fd4aef30 - tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hcbb0cb7640d463fe [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:508:48 [INFO] [stdout] 23: 0x5627fd4db620 - tokio::coop::with_budget::{{closure}}::h986fa44e90c3bbfc [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/coop.rs:102:9 [INFO] [stdout] 24: 0x5627fd4c0ba3 - std::thread::local::LocalKey::try_with::h19055b3dc0018869 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/local.rs:446:16 [INFO] [stdout] 25: 0x5627fd4bffbd - std::thread::local::LocalKey::with::hd9e7f60da76f7e70 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/local.rs:422:9 [INFO] [stdout] 26: 0x5627fd4aeb06 - tokio::coop::with_budget::h2109b6aa1a6fd51f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/coop.rs:95:5 [INFO] [stdout] 27: 0x5627fd4aeb06 - tokio::coop::budget::h08d1470764f3e794 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/coop.rs:72:5 [INFO] [stdout] 28: 0x5627fd4aeb06 - tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::hc77990efde8c02bd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:508:25 [INFO] [stdout] 29: 0x5627fd4a4d92 - tokio::runtime::basic_scheduler::Context::enter::h9e3b900d3c55d4b0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:362:19 [INFO] [stdout] 30: 0x5627fd4a9eba - tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h6a327acd764fc39f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:507:36 [INFO] [stdout] 31: 0x5627fd4a979b - tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h18c2b80f45d101db [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:565:57 [INFO] [stdout] 32: 0x5627fd4dcec7 - tokio::macros::scoped_tls::ScopedKey::set::h6f53c42d28c82f35 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/macros/scoped_tls.rs:61:9 [INFO] [stdout] 33: 0x5627fd4a85d7 - tokio::runtime::basic_scheduler::CoreGuard::enter::h914db0e0fa47a1b5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:565:27 [INFO] [stdout] 34: 0x5627fd4a9be0 - tokio::runtime::basic_scheduler::CoreGuard::block_on::hd0dff8bf97ea845d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:498:9 [INFO] [stdout] 35: 0x5627fd49fba6 - tokio::runtime::basic_scheduler::BasicScheduler::block_on::h06adf8a159b6027a [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/basic_scheduler.rs:174:24 [INFO] [stdout] 36: 0x5627fd4de14d - tokio::runtime::Runtime::block_on::h0e6e2fbbdaabcb68 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.19.2/src/runtime/mod.rs:480:46 [INFO] [stdout] 37: 0x5627fd4d2089 - cached::db::test::test_ttl_in_future_returns_value_db::h50a2308ba53cc71c [INFO] [stdout] at /opt/rustwide/workdir/src/db.rs:309:9 [INFO] [stdout] 38: 0x5627fd4d1f47 - cached::db::test::test_ttl_in_future_returns_value_db::{{closure}}::h31676daa6382115b [INFO] [stdout] at /opt/rustwide/workdir/src/db.rs:296:11 [INFO] [stdout] 39: 0x5627fd4c5065 - core::ops::function::FnOnce::call_once::he785ecf3cc96f53d [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 40: 0x5627fd51f0cf - core::ops::function::FnOnce::call_once::h5dde45172d790a9d [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 41: 0x5627fd51f0cf - test::__rust_begin_short_backtrace::h78e3fbe0504ae84c [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:637:18 [INFO] [stdout] 42: 0x5627fd4f079c - test::run_test::{{closure}}::hecbec538c508056c [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:628:30 [INFO] [stdout] 43: 0x5627fd4f079c - core::ops::function::FnOnce::call_once{{vtable.shim}}::hc84a1fb3f0e67ee4 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 44: 0x5627fd51e0f8 - as core::ops::function::FnOnce>::call_once::h2deece96542e3e9a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 45: 0x5627fd51e0f8 - as core::ops::function::FnOnce<()>>::call_once::ha9befd17c806a3b0 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 46: 0x5627fd51e0f8 - std::panicking::try::do_call::h17336b94b336bff1 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:483:40 [INFO] [stdout] 47: 0x5627fd51e0f8 - std::panicking::try::h701ebc92939c4760 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:447:19 [INFO] [stdout] 48: 0x5627fd51e0f8 - std::panic::catch_unwind::h93b113531d625eab [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panic.rs:137:14 [INFO] [stdout] 49: 0x5627fd51e0f8 - test::run_test_in_process::h08e9f627920c2a2a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:660:27 [INFO] [stdout] 50: 0x5627fd51e0f8 - test::run_test::run_test_inner::{{closure}}::h70b581814cc230ea [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:554:39 [INFO] [stdout] 51: 0x5627fd4eae14 - test::run_test::run_test_inner::{{closure}}::h1acbf81bd02dba18 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:581:37 [INFO] [stdout] 52: 0x5627fd4eae14 - std::sys_common::backtrace::__rust_begin_short_backtrace::h59c80156bae07f63 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 53: 0x5627fd4f0665 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h80e22f541b422f07 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/mod.rs:551:17 [INFO] [stdout] 54: 0x5627fd4f0665 - as core::ops::function::FnOnce<()>>::call_once::he3336d4cf440d04a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 55: 0x5627fd4f0665 - std::panicking::try::do_call::hd4403a300ceb7231 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:483:40 [INFO] [stdout] 56: 0x5627fd4f0665 - std::panicking::try::h21b14e2ec444d3e6 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:447:19 [INFO] [stdout] 57: 0x5627fd4f0665 - std::panic::catch_unwind::he2bb948f3383cd51 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panic.rs:137:14 [INFO] [stdout] 58: 0x5627fd4f0665 - std::thread::Builder::spawn_unchecked_::{{closure}}::h9093214177135071 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/mod.rs:550:30 [INFO] [stdout] 59: 0x5627fd4f0665 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbd75c44bc5dabf20 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 60: 0x5627fd5be3e3 - as core::ops::function::FnOnce>::call_once::hdc09af53979ab66f [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 61: 0x5627fd5be3e3 - as core::ops::function::FnOnce>::call_once::h5cef88e3fa63b616 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 62: 0x5627fd5be3e3 - std::sys::unix::thread::Thread::new::thread_start::h7ae30531e3efd4f6 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 63: 0x7f52ae056609 - start_thread [INFO] [stdout] 64: 0x7f52ade26133 - clone [INFO] [stdout] 65: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] db::test::test_ttl_in_future_returns_value_db [INFO] [stdout] [INFO] [stdout] test result: FAILED. 67 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "0a3b0bdffe95110ad0bcac75c37f9909b851738c37dc90d4049cc034606d4fa6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0a3b0bdffe95110ad0bcac75c37f9909b851738c37dc90d4049cc034606d4fa6", kill_on_drop: false }` [INFO] [stdout] 0a3b0bdffe95110ad0bcac75c37f9909b851738c37dc90d4049cc034606d4fa6