[INFO] fetching crate redis-sdk 0.1.0...
[INFO] testing redis-sdk-0.1.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate redis-sdk 0.1.0 into /workspace/builds/worker-1-tc2/source
[INFO] started tweaking crates.io crate redis-sdk 0.1.0
[INFO] finished tweaking crates.io crate redis-sdk 0.1.0
[INFO] tweaked toml for crates.io crate redis-sdk 0.1.0 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate redis-sdk 0.1.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 112 packages to highest compatible versions
[INFO] [stderr]       Adding bb8 v0.8.6 (available: v0.9.1)
[INFO] [stderr]       Adding bb8-redis v0.17.0 (available: v0.26.0)
[INFO] [stderr]       Adding lru v0.12.5 (available: v0.18.5)
[INFO] [stderr]       Adding redis v0.27.6 (available: v1.7.1)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:44:12
[INFO] [stderr]    |
[INFO] [stderr] 44 | homepage = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 49 | repository = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9
[INFO] running `Command { std: "docker" "start" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:44:12
[INFO] [stderr]    |
[INFO] [stderr] 44 | homepage = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 49 | repository = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling async-trait v0.1.92
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling tokio v1.53.3
[INFO] [stderr]    Compiling num-integer v0.1.47
[INFO] [stderr]    Compiling arc-swap v1.9.3
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling dotenv v0.15.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling num-bigint v0.4.8
[INFO] [stderr]    Compiling lru v0.12.5
[INFO] [stderr]    Compiling futures-executor v0.3.34
[INFO] [stderr]    Compiling futures v0.3.34
[INFO] [stderr]    Compiling tokio-util v0.7.20
[INFO] [stderr]    Compiling bb8 v0.8.6
[INFO] [stderr]    Compiling combine v4.6.9
[INFO] [stderr]    Compiling redis v0.27.6
[INFO] [stderr]    Compiling bb8-redis v0.17.0
[INFO] [stderr]    Compiling redis-sdk v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `dotenv`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.dotenv]
[INFO] [stderr]    |               ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `dotenv`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 41.00s
[INFO] running `Command { std: "docker" "inspect" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:44:12
[INFO] [stderr]    |
[INFO] [stderr] 44 | homepage = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 49 | repository = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr]    Compiling redis-sdk v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `dotenv`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.dotenv]
[INFO] [stderr]    |               ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `dotenv`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 2.56s
[INFO] running `Command { std: "docker" "inspect" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:44:12
[INFO] [stderr]    |
[INFO] [stderr] 44 | homepage = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 49 | repository = "https://github.com/rooct/redis-sdk.git"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `dotenv`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.dotenv]
[INFO] [stderr]    |               ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `dotenv`
[INFO] [stderr] warning: `redis-sdk` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/redis-sdk/623664063e12a0b8/out/redis_sdk-623664063e12a0b8)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test tests::test_keys_pattern ... FAILED
[INFO] [stdout] test tests::test_set_and_get_json ... FAILED
[INFO] [stdout] test tests::test_set_and_get ... FAILED
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] test tests::test_set_ex_and_expire ... FAILED
[INFO] [stdout] test tests::test_exists ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_keys_pattern stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_keys_pattern' (525) panicked at src/lib.rs:254:42:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to get Redis connection from pool: Timed out in bb8
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <redis_sdk::CacheClient>::get_conn::{closure#0}::{closure#0}
[INFO] [stdout]    2: <redis_sdk::CacheClient>::get_conn::{closure#0}
[INFO] [stdout]    3: <redis_sdk::CacheClient>::set::{closure#0}
[INFO] [stdout]    4: redis_sdk::tests::test_keys_pattern::{closure#0}
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    6: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    7: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    8: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    9: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]   10: <redis_sdk::tests::test_keys_pattern::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   11: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   12: test::run_test::{closure#0}
[INFO] [stdout]   13: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   14: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   15: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   16: <unknown>
[INFO] [stdout]   17: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x591848432f11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x59184844960a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x591848437d2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x59184840e096 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x59184842af39 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x591848337df0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x59184842b262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x59184840e142 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5918484067e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x59184840f04d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x591848449d6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x591848449b42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x59184830fbb8 - redis_sdk[383678b6afa8b07b]::tests::test_keys_pattern::{closure#0}
[INFO] [stdout]   13:     0x59184831a2e3 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x59184831787c - <tokio[3db95fc21d6733b5]::runtime::context::scoped::Scoped<tokio[3db95fc21d6733b5]::runtime::scheduler::Context>>::set::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x59184831a957 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5918483273db - tokio[3db95fc21d6733b5]::runtime::context::runtime::enter_runtime::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x591848323ea5 - <tokio[3db95fc21d6733b5]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x591848311dee - <redis_sdk[383678b6afa8b07b]::tests::test_keys_pattern::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x59184832b23b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x591848338415 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x591848331d04 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x59184833b4a2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5918484323ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x763a6602edfa - <unknown>
[INFO] [stdout]   25:     0x763a660c23d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_set_and_get_json stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_set_and_get_json' (527) panicked at src/lib.rs:227:43:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to get Redis connection from pool: Timed out in bb8
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <redis_sdk::CacheClient>::get_conn::{closure#0}::{closure#0}
[INFO] [stdout]    2: <redis_sdk::CacheClient>::get_conn::{closure#0}
[INFO] [stdout]    3: <redis_sdk::CacheClient>::set::{closure#0}
[INFO] [stdout]    4: redis_sdk::tests::test_set_and_get_json::{closure#0}
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    6: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    7: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    8: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    9: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]   10: <redis_sdk::tests::test_set_and_get_json::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   11: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   12: test::run_test::{closure#0}
[INFO] [stdout]   13: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   14: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   15: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   16: <unknown>
[INFO] [stdout]   17: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x591848432f11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x59184844960a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x591848437d2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x59184840e096 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x59184842af39 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x591848337df0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x59184842b262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x59184840e142 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5918484067e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x59184840f04d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x591848449d6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x591848449b42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x591848310aa0 - redis_sdk[383678b6afa8b07b]::tests::test_set_and_get_json::{closure#0}
[INFO] [stdout]   13:     0x59184831a2e3 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x59184831787c - <tokio[3db95fc21d6733b5]::runtime::context::scoped::Scoped<tokio[3db95fc21d6733b5]::runtime::scheduler::Context>>::set::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x59184831a957 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5918483273db - tokio[3db95fc21d6733b5]::runtime::context::runtime::enter_runtime::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x591848323ea5 - <tokio[3db95fc21d6733b5]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x591848311f4e - <redis_sdk[383678b6afa8b07b]::tests::test_set_and_get_json::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x59184832b23b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x591848338415 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x591848331d04 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x59184833b4a2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5918484323ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x763a6602edfa - <unknown>
[INFO] [stdout]   25:     0x763a660c23d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_set_and_get stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_set_and_get' (526) panicked at src/lib.rs:195:38:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to get Redis connection from pool: Timed out in bb8
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <redis_sdk::CacheClient>::get_conn::{closure#0}::{closure#0}
[INFO] [stdout]    2: <redis_sdk::CacheClient>::get_conn::{closure#0}
[INFO] [stdout]    3: <redis_sdk::CacheClient>::set::{closure#0}
[INFO] [stdout]    4: redis_sdk::tests::test_set_and_get::{closure#0}
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    6: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    7: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    8: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    9: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]   10: <redis_sdk::tests::test_set_and_get::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   11: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   12: test::run_test::{closure#0}
[INFO] [stdout]   13: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   14: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   15: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   16: <unknown>
[INFO] [stdout]   17: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x591848432f11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x59184844960a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x591848437d2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x59184840e096 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x59184842af39 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x591848337df0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x59184842b262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x59184840e142 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5918484067e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x59184840f04d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x591848449d6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x591848449b42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x59184830f25f - redis_sdk[383678b6afa8b07b]::tests::test_set_and_get::{closure#0}
[INFO] [stdout]   13:     0x59184831a2e3 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x59184831787c - <tokio[3db95fc21d6733b5]::runtime::context::scoped::Scoped<tokio[3db95fc21d6733b5]::runtime::scheduler::Context>>::set::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x59184831a957 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5918483273db - tokio[3db95fc21d6733b5]::runtime::context::runtime::enter_runtime::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x591848323ea5 - <tokio[3db95fc21d6733b5]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x591848311c8e - <redis_sdk[383678b6afa8b07b]::tests::test_set_and_get::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x59184832b23b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x591848338415 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x591848331d04 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x59184833b4a2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5918484323ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x763a6602edfa - <unknown>
[INFO] [stdout]   25:     0x763a660c23d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_set_ex_and_expire stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_set_ex_and_expire' (528) panicked at src/lib.rs:208:44:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to get Redis connection from pool: Timed out in bb8
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <redis_sdk::CacheClient>::get_conn::{closure#0}::{closure#0}
[INFO] [stdout]    2: <redis_sdk::CacheClient>::get_conn::{closure#0}
[INFO] [stdout]    3: redis_sdk::tests::test_set_ex_and_expire::{closure#0}
[INFO] [stdout]    4: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    5: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    6: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    7: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    8: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    9: <redis_sdk::tests::test_set_ex_and_expire::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   10: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   11: test::run_test::{closure#0}
[INFO] [stdout]   12: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   13: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15: <unknown>
[INFO] [stdout]   16: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x591848432f11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x59184844960a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x591848437d2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x59184840e096 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x59184842af39 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x591848337df0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x59184842b262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x59184840e142 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5918484067e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x59184840f04d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x591848449d6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x591848449b42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5918483116ba - redis_sdk[383678b6afa8b07b]::tests::test_set_ex_and_expire::{closure#0}
[INFO] [stdout]   13:     0x59184831a2e3 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x59184831787c - <tokio[3db95fc21d6733b5]::runtime::context::scoped::Scoped<tokio[3db95fc21d6733b5]::runtime::scheduler::Context>>::set::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x59184831a957 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5918483273db - tokio[3db95fc21d6733b5]::runtime::context::runtime::enter_runtime::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x591848323ea5 - <tokio[3db95fc21d6733b5]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5918483120ae - <redis_sdk[383678b6afa8b07b]::tests::test_set_ex_and_expire::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x59184832b23b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x591848338415 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x591848331d04 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x59184833b4a2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5918484323ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x763a6602edfa - <unknown>
[INFO] [stdout]   25:     0x763a660c23d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_exists stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_exists' (524) panicked at src/lib.rs:239:40:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to get Redis connection from pool: Timed out in bb8
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <redis_sdk::CacheClient>::get_conn::{closure#0}::{closure#0}
[INFO] [stdout]    2: <redis_sdk::CacheClient>::get_conn::{closure#0}
[INFO] [stdout]    3: <redis_sdk::CacheClient>::set::{closure#0}
[INFO] [stdout]    4: redis_sdk::tests::test_exists::{closure#0}
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    6: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    7: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    8: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    9: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]   10: <redis_sdk::tests::test_exists::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   11: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   12: test::run_test::{closure#0}
[INFO] [stdout]   13: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   14: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   15: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   16: <unknown>
[INFO] [stdout]   17: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x591848432f11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x59184844960a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x591848437d2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x59184840e096 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x59184842af39 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x591848337df0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x59184842b262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x59184840e142 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5918484067e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x59184840f04d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x591848449d6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x591848449b42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x59184830eda5 - redis_sdk[383678b6afa8b07b]::tests::test_exists::{closure#0}
[INFO] [stdout]   13:     0x59184831a2e3 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x59184831787c - <tokio[3db95fc21d6733b5]::runtime::context::scoped::Scoped<tokio[3db95fc21d6733b5]::runtime::scheduler::Context>>::set::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x59184831a957 - <tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5918483273db - tokio[3db95fc21d6733b5]::runtime::context::runtime::enter_runtime::<<tokio[3db95fc21d6733b5]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x591848323ea5 - <tokio[3db95fc21d6733b5]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x591848311b2e - <redis_sdk[383678b6afa8b07b]::tests::test_exists::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x59184832b23b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x591848338415 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x591848331d04 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x59184833b4a2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5918484323ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x763a6602edfa - <unknown>
[INFO] [stdout]   25:     0x763a660c23d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_exists
[INFO] [stdout]     tests::test_keys_pattern
[INFO] [stdout]     tests::test_set_and_get
[INFO] [stdout]     tests::test_set_and_get_json
[INFO] [stdout]     tests::test_set_ex_and_expire
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 30.01s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9", kill_on_drop: false }`
[INFO] [stdout] be2fdfba01cdea1f778938c98f4e7a7d3fe4cade81dfb941319ef23e150f69c9
