[INFO] cloning repository https://github.com/jssblck/procession
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jssblck/procession" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjssblck%2Fprocession", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjssblck%2Fprocession'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] a1c7df41d32b18d58038fd1ec420162901562fbb
[INFO] testing jssblck/procession against 1.76.0 for beta-1.77-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjssblck%2Fprocession" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/jssblck/procession on toolchain 1.76.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.76.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/jssblck/procession
[INFO] finished tweaking git repo https://github.com/jssblck/procession
[INFO] tweaked toml for git repo https://github.com/jssblck/procession written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] crate git repo https://github.com/jssblck/procession 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.76.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded redis v0.21.6
[INFO] [stderr]   Downloaded error-stack v0.2.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a87dea88c04e1fafcdd6d5b533be0baf5a6f6154f34fb880f07b054eb5730b94
[INFO] running `Command { std: "docker" "start" "-a" "a87dea88c04e1fafcdd6d5b533be0baf5a6f6154f34fb880f07b054eb5730b94", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a87dea88c04e1fafcdd6d5b533be0baf5a6f6154f34fb880f07b054eb5730b94", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a87dea88c04e1fafcdd6d5b533be0baf5a6f6154f34fb880f07b054eb5730b94", kill_on_drop: false }`
[INFO] [stdout] a87dea88c04e1fafcdd6d5b533be0baf5a6f6154f34fb880f07b054eb5730b94
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 16ed6e31c5b5978d13642accf1cf1339c65a400ea105a6b231b0664938cc2524
[INFO] running `Command { std: "docker" "start" "-a" "16ed6e31c5b5978d13642accf1cf1339c65a400ea105a6b231b0664938cc2524", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling proc-macro2 v1.0.47
[INFO] [stderr]    Compiling unicode-ident v1.0.5
[INFO] [stderr]    Compiling quote v1.0.21
[INFO] [stderr]    Compiling syn v1.0.103
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling libc v0.2.137
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling once_cell v1.16.0
[INFO] [stderr]    Compiling log v0.4.17
[INFO] [stderr]    Compiling pin-project-lite v0.2.9
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling smallvec v1.10.0
[INFO] [stderr]    Compiling futures-core v0.3.25
[INFO] [stderr]    Compiling cc v1.0.77
[INFO] [stderr]    Compiling lock_api v0.4.9
[INFO] [stderr]    Compiling bytes v1.3.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.4
[INFO] [stderr]    Compiling tracing-core v0.1.30
[INFO] [stderr]    Compiling futures-sink v0.3.25
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling tokio v1.22.0
[INFO] [stderr]    Compiling slab v0.4.7
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling futures-channel v0.3.25
[INFO] [stderr]    Compiling parking_lot v0.12.1
[INFO] [stderr]    Compiling num_cpus v1.14.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.0
[INFO] [stderr]    Compiling socket2 v0.4.7
[INFO] [stderr]    Compiling mio v0.8.5
[INFO] [stderr]    Compiling futures-util v0.3.25
[INFO] [stderr]    Compiling futures-io v0.3.25
[INFO] [stderr]    Compiling itoa v1.0.4
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling indexmap v1.9.2
[INFO] [stderr]    Compiling pkg-config v0.3.26
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling http v0.2.8
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling tower-service v0.3.2
[INFO] [stderr]    Compiling ring v0.16.20
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling serde_derive v1.0.147
[INFO] [stderr]    Compiling httparse v1.8.0
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling serde v1.0.147
[INFO] [stderr]    Compiling percent-encoding v2.2.0
[INFO] [stderr]    Compiling try-lock v0.2.3
[INFO] [stderr]    Compiling want v0.3.0
[INFO] [stderr]    Compiling openssl-sys v0.9.78
[INFO] [stderr]    Compiling form_urlencoded v1.1.0
[INFO] [stderr]    Compiling tinyvec v1.6.0
[INFO] [stderr]    Compiling ryu v1.0.11
[INFO] [stderr]    Compiling http-body v0.4.5
[INFO] [stderr]    Compiling spin v0.5.2
[INFO] [stderr]    Compiling semver v1.0.14
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling httpdate v1.0.2
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling unicode-bidi v0.3.8
[INFO] [stderr]    Compiling openssl v0.10.43
[INFO] [stderr]    Compiling async-trait v0.1.58
[INFO] [stderr]    Compiling serde_json v1.0.89
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling unicode-normalization v0.1.22
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling native-tls v0.2.11
[INFO] [stderr]    Compiling tower-layer v0.3.2
[INFO] [stderr]    Compiling mime v0.3.16
[INFO] [stderr]    Compiling openssl-probe v0.1.5
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling rustls v0.20.7
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling idna v0.3.0
[INFO] [stderr]    Compiling rustc_version v0.4.0
[INFO] [stderr]    Compiling tracing-log v0.1.3
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling is_ci v1.1.1
[INFO] [stderr]    Compiling url v2.3.1
[INFO] [stderr]    Compiling supports-color v1.3.1
[INFO] [stderr]    Compiling error-stack v0.2.4
[INFO] [stderr]    Compiling http-range-header v0.3.0
[INFO] [stderr]    Compiling encoding_rs v0.8.31
[INFO] [stderr]    Compiling tracing-subscriber v0.3.16
[INFO] [stderr]    Compiling heck v0.4.0
[INFO] [stderr]    Compiling base64 v0.13.1
[INFO] [stderr]    Compiling sha1_smol v1.0.0
[INFO] [stderr]    Compiling os_str_bytes v6.4.1
[INFO] [stderr]    Compiling sha1 v0.6.1
[INFO] [stderr]    Compiling rustls-pemfile v1.0.1
[INFO] [stderr]    Compiling owo-colors v3.5.0
[INFO] [stderr]    Compiling clap_lex v0.2.4
[INFO] [stderr]    Compiling textwrap v0.16.0
[INFO] [stderr]    Compiling arc-swap v1.5.1
[INFO] [stderr]    Compiling webpki v0.22.0
[INFO] [stderr]    Compiling sct v0.7.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling thiserror v1.0.37
[INFO] [stderr]    Compiling matchit v0.5.0
[INFO] [stderr]    Compiling sync_wrapper v0.1.1
[INFO] [stderr]    Compiling strsim v0.10.0
[INFO] [stderr]    Compiling rustls-native-certs v0.6.2
[INFO] [stderr]    Compiling ipnet v2.5.1
[INFO] [stderr]    Compiling bench v0.1.0 (/opt/rustwide/workdir/housekeeping/bench)
[INFO] [stderr]    Compiling it v0.1.0 (/opt/rustwide/workdir/housekeeping/it)
[INFO] [stderr]    Compiling tracing-attributes v0.1.23
[INFO] [stderr]    Compiling tokio-macros v1.8.0
[INFO] [stderr]    Compiling futures-macro v0.3.25
[INFO] [stderr]    Compiling openssl-macros v0.1.0
[INFO] [stderr]    Compiling pin-project-internal v1.0.12
[INFO] [stderr]    Compiling pin-project v1.0.12
[INFO] [stderr]    Compiling clap_derive v3.2.18
[INFO] [stderr]    Compiling tracing v0.1.37
[INFO] [stderr]    Compiling tracing-error v0.2.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.37
[INFO] [stderr]    Compiling futures-executor v0.3.25
[INFO] [stderr]    Compiling futures v0.3.25
[INFO] [stderr]    Compiling axum-core v0.2.9
[INFO] [stderr]    Compiling clap v3.2.23
[INFO] [stderr]    Compiling tokio-util v0.7.4
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling tokio-rustls v0.23.4
[INFO] [stderr]    Compiling tokio-native-tls v0.3.0
[INFO] [stderr]    Compiling tower-http v0.3.4
[INFO] [stderr]    Compiling h2 v0.3.15
[INFO] [stderr]    Compiling combine v4.6.6
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling hyper v0.14.23
[INFO] [stderr]    Compiling redis v0.21.6
[INFO] [stderr]    Compiling axum v0.5.17
[INFO] [stderr]    Compiling hyper-rustls v0.23.1
[INFO] [stderr]    Compiling hyper-tls v0.5.0
[INFO] [stderr]    Compiling reqwest v0.11.13
[INFO] [stderr]    Compiling procession-client v0.1.0 (/opt/rustwide/workdir/client/rs)
[INFO] [stderr]    Compiling procession v0.1.0 (/opt/rustwide/workdir/server)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 20.18s
[INFO] running `Command { std: "docker" "inspect" "16ed6e31c5b5978d13642accf1cf1339c65a400ea105a6b231b0664938cc2524", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "16ed6e31c5b5978d13642accf1cf1339c65a400ea105a6b231b0664938cc2524", kill_on_drop: false }`
[INFO] [stdout] 16ed6e31c5b5978d13642accf1cf1339c65a400ea105a6b231b0664938cc2524
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f33908712e7f92caf49fe2b9f40d80c2d373cb5643224673c7c54e725aef1859
[INFO] running `Command { std: "docker" "start" "-a" "f33908712e7f92caf49fe2b9f40d80c2d373cb5643224673c7c54e725aef1859", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling getrandom v0.2.8
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling ppv-lite86 v0.2.17
[INFO] [stderr]    Compiling fastrand v1.8.0
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling bit-vec v0.6.3
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling quick-error v2.0.1
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling tempfile v3.3.0
[INFO] [stderr]    Compiling bit-set v0.5.3
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling regex-syntax v0.6.28
[INFO] [stderr]    Compiling assert_matches v1.5.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rusty-fork v0.3.0
[INFO] [stderr]    Compiling procession-client v0.1.0 (/opt/rustwide/workdir/client/rs)
[INFO] [stderr]    Compiling it v0.1.0 (/opt/rustwide/workdir/housekeeping/it)
[INFO] [stderr]    Compiling bench v0.1.0 (/opt/rustwide/workdir/housekeeping/bench)
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling proptest v1.0.0
[INFO] [stderr]    Compiling procession v0.1.0 (/opt/rustwide/workdir/server)
[INFO] [stdout] warning: `#[track_caller]` on async functions is a no-op
[INFO] [stdout]   --> server/tests/it/run.rs:25:1
[INFO] [stdout]    |
[INFO] [stdout] 25 |   #[track_caller]
[INFO] [stdout]    |   ^^^^^^^^^^^^^^^
[INFO] [stdout] 26 | / pub async fn server() -> String {
[INFO] [stdout] 27 | |     let app = api::server();
[INFO] [stdout] 28 | |
[INFO] [stdout] 29 | |     let listener = TcpListener::bind("127.0.0.1:0").expect("bind ephemeral socket");
[INFO] [stdout] ...  |
[INFO] [stdout] 39 | |     format!("http://{addr}")
[INFO] [stdout] 40 | | }
[INFO] [stdout]    | |_- this function will not propagate the caller location
[INFO] [stdout]    |
[INFO] [stdout]    = note: see issue #110011 <https://github.com/rust-lang/rust/issues/110011> for more information
[INFO] [stdout]    = note: `#[warn(ungated_async_fn_track_caller)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.39s
[INFO] running `Command { std: "docker" "inspect" "f33908712e7f92caf49fe2b9f40d80c2d373cb5643224673c7c54e725aef1859", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f33908712e7f92caf49fe2b9f40d80c2d373cb5643224673c7c54e725aef1859", kill_on_drop: false }`
[INFO] [stdout] f33908712e7f92caf49fe2b9f40d80c2d373cb5643224673c7c54e725aef1859
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5c9540b85d1f7545ee60892d4d53dca85dc914e124f9e39c819065133f7474a6
[INFO] running `Command { std: "docker" "start" "-a" "5c9540b85d1f7545ee60892d4d53dca85dc914e124f9e39c819065133f7474a6", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr] warning: `#[track_caller]` on async functions is a no-op
[INFO] [stderr]   --> server/tests/it/run.rs:25:1
[INFO] [stderr]    |
[INFO] [stderr] 25 |   #[track_caller]
[INFO] [stderr]    |   ^^^^^^^^^^^^^^^
[INFO] [stderr] 26 | / pub async fn server() -> String {
[INFO] [stderr] 27 | |     let app = api::server();
[INFO] [stderr] 28 | |
[INFO] [stderr] 29 | |     let listener = TcpListener::bind("127.0.0.1:0").expect("bind ephemeral socket");
[INFO] [stderr] ...  |
[INFO] [stderr] 39 | |     format!("http://{addr}")
[INFO] [stderr] 40 | | }
[INFO] [stderr]    | |_- this function will not propagate the caller location
[INFO] [stderr]    |
[INFO] [stderr]    = note: see issue #110011 <https://github.com/rust-lang/rust/issues/110011> for more information
[INFO] [stderr]    = note: `#[warn(ungated_async_fn_track_caller)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `procession` (test "it") generated 1 warning
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/bench-23c8d6c3752ef5d7)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/it-fedf397a11d0ccd7)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/procession-af1b4c9d927425e0)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/procession-ff218f4cd256c51a)
[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/it/main.rs (/opt/rustwide/target/debug/deps/it-79e9b8424c648f1d)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test ping ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- ping stdout ----
[INFO] [stdout] thread 'ping' panicked at server/tests/it/main.rs:18:5:
[INFO] [stdout] assertion failed: response.contains("redis latency:")
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55ea318910c6 - std::backtrace_rs::backtrace::libunwind::trace::hbee8a7973eeb6c93
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x55ea318910c6 - std::backtrace_rs::backtrace::trace_unsynchronized::hc8ac75eea3aa6899
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55ea318910c6 - std::sys_common::backtrace::_print_fmt::hc7f3e3b5298b1083
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x55ea318910c6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hbb235daedd7c6190
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x55ea318bd9d0 - core::fmt::rt::Argument::fmt::h76c38a80d925a410
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x55ea318bd9d0 - core::fmt::write::h3ed6aeaa977c8e45
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x55ea3188dd8f - std::io::Write::write_fmt::h1299aa7741865f2b
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x55ea31890ea4 - std::sys_common::backtrace::_print::h5d645a07e0fcfdbb
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x55ea31890ea4 - std::sys_common::backtrace::print::h85035a511aafe7a8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x55ea31892c17 - std::panicking::default_hook::{{closure}}::hcce8cea212785a25
[INFO] [stdout]   10:     0x55ea318928fd - std::panicking::default_hook::hf5fcb0f213fe709a
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x55ea31189d27 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h60265c2dfa87ee34
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x55ea31189d27 - test::test_main::{{closure}}::h77865bd3127078c6
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x55ea31893236 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hbc5ccf4eb663e1e5
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x55ea31893236 - std::panicking::rust_panic_with_hook::h095fccf1dc9379ee
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x55ea31892f49 - std::panicking::begin_panic_handler::{{closure}}::h032ba12139b353db
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:649:13
[INFO] [stdout]   16:     0x55ea318915c6 - std::sys_common::backtrace::__rust_end_short_backtrace::h9259bc2ff8fd0f76
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x55ea31892ce0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x55ea3109f675 - core::panicking::panic_fmt::h784f20a50eaab275
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x55ea3109f733 - core::panicking::panic::hb837a5ebbbe5b188
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panicking.rs:144:5
[INFO] [stdout]   20:     0x55ea310fd5a7 - it::ping::{{closure}}::ha27550c2b3ddedb7
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:18:5
[INFO] [stdout]   21:     0x55ea31154a7b - <core::pin::Pin<P> as core::future::future::Future>::poll::h62150f803f6bc422
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/future/future.rs:124:9
[INFO] [stdout]   22:     0x55ea3114ad84 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hc328a5b32df09712
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:531:57
[INFO] [stdout]   23:     0x55ea3114ac7a - tokio::runtime::coop::with_budget::h781c0912d522306c
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/coop.rs:102:5
[INFO] [stdout]   24:     0x55ea3114ac7a - tokio::runtime::coop::budget::hcd4e8545b3a6c76d
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/coop.rs:68:5
[INFO] [stdout]   25:     0x55ea3114ac7a - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h46dbf0e0f48a1d30
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:531:25
[INFO] [stdout]   26:     0x55ea311495ac - tokio::runtime::scheduler::current_thread::Context::enter::hbaaddfbd468bc72e
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:340:19
[INFO] [stdout]   27:     0x55ea3114a37a - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::hc7a9bec0417f9e81
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:530:36
[INFO] [stdout]   28:     0x55ea3114a0ab - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h21c6373d7932c160
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:601:57
[INFO] [stdout]   29:     0x55ea311069dd - tokio::macros::scoped_tls::ScopedKey<T>::set::h85f22a7534f63564
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/macros/scoped_tls.rs:61:9
[INFO] [stdout]   30:     0x55ea31149e3f - tokio::runtime::scheduler::current_thread::CoreGuard::enter::h7d766e80eb32492f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:601:27
[INFO] [stdout]   31:     0x55ea3114a0dc - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h1bcfc1758b9d70c7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:520:19
[INFO] [stdout]   32:     0x55ea3114805b - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h634e361d1a417f5f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:154:24
[INFO] [stdout]   33:     0x55ea310e7808 - tokio::runtime::runtime::Runtime::block_on::hc081887eeb6af659
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/runtime.rs:279:47
[INFO] [stdout]   34:     0x55ea310fd01c - it::ping::h613187493f5fd391
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:18:5
[INFO] [stdout]   35:     0x55ea310fcee7 - it::ping::{{closure}}::hdf05876a56f1496f
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:14:16
[INFO] [stdout]   36:     0x55ea31143026 - core::ops::function::FnOnce::call_once::h20df2fdb2fe502d0
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x55ea3118f5ff - core::ops::function::FnOnce::call_once::h8dc6907944022cf6
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   38:     0x55ea3118f5ff - test::__rust_begin_short_backtrace::haae1a87433f1efb3
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:627:18
[INFO] [stdout]   39:     0x55ea3118e381 - test::run_test_in_process::{{closure}}::h8c7decfa7c14e152
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:650:60
[INFO] [stdout]   40:     0x55ea3118e381 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h19e6ff056d9d21e9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   41:     0x55ea3118e381 - std::panicking::try::do_call::h89c848fcaa37c035
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:552:40
[INFO] [stdout]   42:     0x55ea3118e381 - std::panicking::try::h57ab3dc74e2839b8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:516:19
[INFO] [stdout]   43:     0x55ea3118e381 - std::panic::catch_unwind::hfb6a1b1abc120fb9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panic.rs:142:14
[INFO] [stdout]   44:     0x55ea3118e381 - test::run_test_in_process::h5ae2f9875edd562d
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:650:27
[INFO] [stdout]   45:     0x55ea3118e381 - test::run_test::{{closure}}::h35d7300d8928a067
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:573:43
[INFO] [stdout]   46:     0x55ea311556b6 - test::run_test::{{closure}}::h7525ced405d23d1b
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:601:41
[INFO] [stdout]   47:     0x55ea311556b6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h4e7db78ce05afad8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   48:     0x55ea3115a717 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcfbcb64f1a1b3482
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   49:     0x55ea3115a717 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h9d89c5c4108bd689
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   50:     0x55ea3115a717 - std::panicking::try::do_call::h8a4869bc94ec50c9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:552:40
[INFO] [stdout]   51:     0x55ea3115a717 - std::panicking::try::h9a576f20ff81ac30
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:516:19
[INFO] [stdout]   52:     0x55ea3115a717 - std::panic::catch_unwind::hbcb4e3f860ef9830
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panic.rs:142:14
[INFO] [stdout]   53:     0x55ea3115a717 - std::thread::Builder::spawn_unchecked_::{{closure}}::h93c79a6be1505948
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   54:     0x55ea3115a717 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h426d96740c81bdaf
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   55:     0x55ea31897f75 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h12de4fc57affb195
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   56:     0x55ea31897f75 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h3c619f45059d5cf1
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   57:     0x55ea31897f75 - std::sys::unix::thread::Thread::new::thread_start::hbac657605e4b7389
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   58:     0x7fde1a6b4ac3 - <unknown>
[INFO] [stdout]   59:     0x7fde1a745bf4 - __clone
[INFO] [stdout]   60:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     ping
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p procession --test it`
[INFO] running `Command { std: "docker" "inspect" "5c9540b85d1f7545ee60892d4d53dca85dc914e124f9e39c819065133f7474a6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c9540b85d1f7545ee60892d4d53dca85dc914e124f9e39c819065133f7474a6", kill_on_drop: false }`
[INFO] [stdout] 5c9540b85d1f7545ee60892d4d53dca85dc914e124f9e39c819065133f7474a6
[INFO] testing jssblck/procession against beta-2024-02-10 for beta-1.77-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjssblck%2Fprocession" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/jssblck/procession on toolchain beta-2024-02-10
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-02-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/jssblck/procession
[INFO] finished tweaking git repo https://github.com/jssblck/procession
[INFO] tweaked toml for git repo https://github.com/jssblck/procession written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] crate git repo https://github.com/jssblck/procession already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-02-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 910dbe5115c887667b318f82503722c316010fb6e1c710220da3c049e8909f11
[INFO] running `Command { std: "docker" "start" "-a" "910dbe5115c887667b318f82503722c316010fb6e1c710220da3c049e8909f11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "910dbe5115c887667b318f82503722c316010fb6e1c710220da3c049e8909f11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "910dbe5115c887667b318f82503722c316010fb6e1c710220da3c049e8909f11", kill_on_drop: false }`
[INFO] [stdout] 910dbe5115c887667b318f82503722c316010fb6e1c710220da3c049e8909f11
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 729f92325e2de1853378c9268e2eaa7aae7bccc955de6d2108cb6e67500a90d5
[INFO] running `Command { std: "docker" "start" "-a" "729f92325e2de1853378c9268e2eaa7aae7bccc955de6d2108cb6e67500a90d5", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling proc-macro2 v1.0.47
[INFO] [stderr]    Compiling unicode-ident v1.0.5
[INFO] [stderr]    Compiling quote v1.0.21
[INFO] [stderr]    Compiling syn v1.0.103
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling libc v0.2.137
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling once_cell v1.16.0
[INFO] [stderr]    Compiling log v0.4.17
[INFO] [stderr]    Compiling pin-project-lite v0.2.9
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling smallvec v1.10.0
[INFO] [stderr]    Compiling futures-core v0.3.25
[INFO] [stderr]    Compiling cc v1.0.77
[INFO] [stderr]    Compiling lock_api v0.4.9
[INFO] [stderr]    Compiling bytes v1.3.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.4
[INFO] [stderr]    Compiling tracing-core v0.1.30
[INFO] [stderr]    Compiling futures-sink v0.3.25
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling tokio v1.22.0
[INFO] [stderr]    Compiling slab v0.4.7
[INFO] [stderr]    Compiling futures-channel v0.3.25
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling parking_lot v0.12.1
[INFO] [stderr]    Compiling socket2 v0.4.7
[INFO] [stderr]    Compiling num_cpus v1.14.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.0
[INFO] [stderr]    Compiling mio v0.8.5
[INFO] [stderr]    Compiling futures-util v0.3.25
[INFO] [stderr]    Compiling futures-io v0.3.25
[INFO] [stderr]    Compiling itoa v1.0.4
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling indexmap v1.9.2
[INFO] [stderr]    Compiling tower-service v0.3.2
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling pkg-config v0.3.26
[INFO] [stderr]    Compiling http v0.2.8
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling ring v0.16.20
[INFO] [stderr]    Compiling httparse v1.8.0
[INFO] [stderr]    Compiling serde_derive v1.0.147
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling try-lock v0.2.3
[INFO] [stderr]    Compiling serde v1.0.147
[INFO] [stderr]    Compiling percent-encoding v2.2.0
[INFO] [stderr]    Compiling openssl-sys v0.9.78
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling tinyvec v1.6.0
[INFO] [stderr]    Compiling form_urlencoded v1.1.0
[INFO] [stderr]    Compiling want v0.3.0
[INFO] [stderr]    Compiling http-body v0.4.5
[INFO] [stderr]    Compiling ryu v1.0.11
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling semver v1.0.14
[INFO] [stderr]    Compiling httpdate v1.0.2
[INFO] [stderr]    Compiling spin v0.5.2
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling openssl v0.10.43
[INFO] [stderr]    Compiling unicode-bidi v0.3.8
[INFO] [stderr]    Compiling serde_json v1.0.89
[INFO] [stderr]    Compiling async-trait v0.1.58
[INFO] [stderr]    Compiling unicode-normalization v0.1.22
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling openssl-probe v0.1.5
[INFO] [stderr]    Compiling rustls v0.20.7
[INFO] [stderr]    Compiling tower-layer v0.3.2
[INFO] [stderr]    Compiling native-tls v0.2.11
[INFO] [stderr]    Compiling idna v0.3.0
[INFO] [stderr]    Compiling mime v0.3.16
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling rustc_version v0.4.0
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling tracing-log v0.1.3
[INFO] [stderr]    Compiling url v2.3.1
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling is_ci v1.1.1
[INFO] [stderr]    Compiling error-stack v0.2.4
[INFO] [stderr]    Compiling supports-color v1.3.1
[INFO] [stderr]    Compiling http-range-header v0.3.0
[INFO] [stderr]    Compiling os_str_bytes v6.4.1
[INFO] [stderr]    Compiling heck v0.4.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.16
[INFO] [stderr]    Compiling sha1_smol v1.0.0
[INFO] [stderr]    Compiling encoding_rs v0.8.31
[INFO] [stderr]    Compiling base64 v0.13.1
[INFO] [stderr]    Compiling sha1 v0.6.1
[INFO] [stderr]    Compiling clap_lex v0.2.4
[INFO] [stderr]    Compiling rustls-pemfile v1.0.1
[INFO] [stderr]    Compiling owo-colors v3.5.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling matchit v0.5.0
[INFO] [stderr]    Compiling arc-swap v1.5.1
[INFO] [stderr]    Compiling sync_wrapper v0.1.1
[INFO] [stderr]    Compiling thiserror v1.0.37
[INFO] [stderr]    Compiling textwrap v0.16.0
[INFO] [stderr]    Compiling strsim v0.10.0
[INFO] [stderr]    Compiling rustls-native-certs v0.6.2
[INFO] [stderr]    Compiling sct v0.7.0
[INFO] [stderr]    Compiling webpki v0.22.0
[INFO] [stderr]    Compiling ipnet v2.5.1
[INFO] [stderr]    Compiling it v0.1.0 (/opt/rustwide/workdir/housekeeping/it)
[INFO] [stderr]    Compiling bench v0.1.0 (/opt/rustwide/workdir/housekeeping/bench)
[INFO] [stderr]    Compiling tracing-attributes v0.1.23
[INFO] [stderr]    Compiling tokio-macros v1.8.0
[INFO] [stderr]    Compiling futures-macro v0.3.25
[INFO] [stderr]    Compiling openssl-macros v0.1.0
[INFO] [stderr]    Compiling pin-project-internal v1.0.12
[INFO] [stderr]    Compiling clap_derive v3.2.18
[INFO] [stderr]    Compiling pin-project v1.0.12
[INFO] [stderr]    Compiling thiserror-impl v1.0.37
[INFO] [stderr]    Compiling tracing v0.1.37
[INFO] [stderr]    Compiling tracing-error v0.2.0
[INFO] [stderr]    Compiling futures-executor v0.3.25
[INFO] [stderr]    Compiling axum-core v0.2.9
[INFO] [stderr]    Compiling futures v0.3.25
[INFO] [stderr]    Compiling clap v3.2.23
[INFO] [stderr]    Compiling tokio-util v0.7.4
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling tokio-rustls v0.23.4
[INFO] [stderr]    Compiling tokio-native-tls v0.3.0
[INFO] [stderr]    Compiling tower-http v0.3.4
[INFO] [stderr]    Compiling h2 v0.3.15
[INFO] [stderr]    Compiling combine v4.6.6
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling hyper v0.14.23
[INFO] [stderr]    Compiling redis v0.21.6
[INFO] [stderr]    Compiling axum v0.5.17
[INFO] [stderr]    Compiling hyper-rustls v0.23.1
[INFO] [stderr]    Compiling hyper-tls v0.5.0
[INFO] [stderr]    Compiling reqwest v0.11.13
[INFO] [stderr]    Compiling procession-client v0.1.0 (/opt/rustwide/workdir/client/rs)
[INFO] [stderr]    Compiling procession v0.1.0 (/opt/rustwide/workdir/server)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.67s
[INFO] running `Command { std: "docker" "inspect" "729f92325e2de1853378c9268e2eaa7aae7bccc955de6d2108cb6e67500a90d5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "729f92325e2de1853378c9268e2eaa7aae7bccc955de6d2108cb6e67500a90d5", kill_on_drop: false }`
[INFO] [stdout] 729f92325e2de1853378c9268e2eaa7aae7bccc955de6d2108cb6e67500a90d5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 119a4262113fc574c03b8d429c2f3de85c991878b441f2124548da7b476465f4
[INFO] running `Command { std: "docker" "start" "-a" "119a4262113fc574c03b8d429c2f3de85c991878b441f2124548da7b476465f4", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling getrandom v0.2.8
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling fastrand v1.8.0
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling ppv-lite86 v0.2.17
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling bit-vec v0.6.3
[INFO] [stderr]    Compiling regex-syntax v0.6.28
[INFO] [stderr]    Compiling quick-error v2.0.1
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling tempfile v3.3.0
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling bit-set v0.5.3
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rusty-fork v0.3.0
[INFO] [stderr]    Compiling assert_matches v1.5.0
[INFO] [stderr]    Compiling procession-client v0.1.0 (/opt/rustwide/workdir/client/rs)
[INFO] [stderr]    Compiling bench v0.1.0 (/opt/rustwide/workdir/housekeeping/bench)
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling it v0.1.0 (/opt/rustwide/workdir/housekeeping/it)
[INFO] [stderr]    Compiling proptest v1.0.0
[INFO] [stderr]    Compiling procession v0.1.0 (/opt/rustwide/workdir/server)
[INFO] [stdout] warning: `#[track_caller]` on async functions is a no-op
[INFO] [stdout]   --> server/tests/it/run.rs:25:1
[INFO] [stdout]    |
[INFO] [stdout] 25 |   #[track_caller]
[INFO] [stdout]    |   ^^^^^^^^^^^^^^^
[INFO] [stdout] 26 | / pub async fn server() -> String {
[INFO] [stdout] 27 | |     let app = api::server();
[INFO] [stdout] 28 | |
[INFO] [stdout] 29 | |     let listener = TcpListener::bind("127.0.0.1:0").expect("bind ephemeral socket");
[INFO] [stdout] ...  |
[INFO] [stdout] 39 | |     format!("http://{addr}")
[INFO] [stdout] 40 | | }
[INFO] [stdout]    | |_- this function will not propagate the caller location
[INFO] [stdout]    |
[INFO] [stdout]    = note: see issue #110011 <https://github.com/rust-lang/rust/issues/110011> for more information
[INFO] [stdout]    = note: `#[warn(ungated_async_fn_track_caller)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.14s
[INFO] running `Command { std: "docker" "inspect" "119a4262113fc574c03b8d429c2f3de85c991878b441f2124548da7b476465f4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "119a4262113fc574c03b8d429c2f3de85c991878b441f2124548da7b476465f4", kill_on_drop: false }`
[INFO] [stdout] 119a4262113fc574c03b8d429c2f3de85c991878b441f2124548da7b476465f4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 95a386b7704a6e92fec8102674006ebb72bcf4f1adc7d083166ba799e81a8e3a
[INFO] running `Command { std: "docker" "start" "-a" "95a386b7704a6e92fec8102674006ebb72bcf4f1adc7d083166ba799e81a8e3a", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr] note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr] note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr] note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr] warning: `#[track_caller]` on async functions is a no-op
[INFO] [stderr]   --> server/tests/it/run.rs:25:1
[INFO] [stderr]    |
[INFO] [stderr] 25 |   #[track_caller]
[INFO] [stderr]    |   ^^^^^^^^^^^^^^^
[INFO] [stderr] 26 | / pub async fn server() -> String {
[INFO] [stderr] 27 | |     let app = api::server();
[INFO] [stderr] 28 | |
[INFO] [stderr] 29 | |     let listener = TcpListener::bind("127.0.0.1:0").expect("bind ephemeral socket");
[INFO] [stderr] ...  |
[INFO] [stderr] 39 | |     format!("http://{addr}")
[INFO] [stderr] 40 | | }
[INFO] [stderr]    | |_- this function will not propagate the caller location
[INFO] [stderr]    |
[INFO] [stderr]    = note: see issue #110011 <https://github.com/rust-lang/rust/issues/110011> for more information
[INFO] [stderr]    = note: `#[warn(ungated_async_fn_track_caller)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `procession` (test "it") generated 1 warning
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/bench-f804983527d74318)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/it-a78c0b673c4ec495)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/procession-90260c4b7cf69647)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/procession-467b36e3a2d6ba64)
[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/it/main.rs (/opt/rustwide/target/debug/deps/it-41fbabca833de51a)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test ping ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- ping stdout ----
[INFO] [stdout] thread 'ping' panicked at server/tests/it/main.rs:18:5:
[INFO] [stdout] assertion failed: response.contains("redis latency:")
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x564ecee09d96 - std::backtrace_rs::backtrace::libunwind::trace::h140b6deb6a45c671
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x564ecee09d96 - std::backtrace_rs::backtrace::trace_unsynchronized::h8450d7e4e9219aed
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x564ecee09d96 - std::sys_common::backtrace::_print_fmt::hbd28e5e6c6fc3a3f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x564ecee09d96 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hb058f285768cf98e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x564ecee366e0 - core::fmt::rt::Argument::fmt::h87dec7a95127da24
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x564ecee366e0 - core::fmt::write::h873f89a91a4832db
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x564ecee0684f - std::io::Write::write_fmt::h90f651e9e694a4bb
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/io/mod.rs:1846:15
[INFO] [stdout]    7:     0x564ecee09b74 - std::sys_common::backtrace::_print::h7719d1529d9a9e13
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x564ecee09b74 - std::sys_common::backtrace::print::haa3afbd0e7947108
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x564ecee0b8e7 - std::panicking::default_hook::{{closure}}::h10e3de2ec0eaff22
[INFO] [stdout]   10:     0x564ecee0b5cd - std::panicking::default_hook::hbdc1b1b7fe073dab
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x564ece7017e7 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h20f7887d7cd0a330
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x564ece7017e7 - test::test_main::{{closure}}::hc125a615957b5cb1
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x564ecee0bf06 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h8c19e0590f8443c4
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x564ecee0bf06 - std::panicking::rust_panic_with_hook::hb81692602460473d
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:785:13
[INFO] [stdout]   15:     0x564ecee0bc19 - std::panicking::begin_panic_handler::{{closure}}::h2470593aa2678e9c
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:651:13
[INFO] [stdout]   16:     0x564ecee0a296 - std::sys_common::backtrace::__rust_end_short_backtrace::h560bb50225b0562b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x564ecee0b9a4 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:647:5
[INFO] [stdout]   18:     0x564ece617105 - core::panicking::panic_fmt::h29b6f262f6458dc3
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x564ece6171c3 - core::panicking::panic::h239eacb54d7c53ba
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panicking.rs:144:5
[INFO] [stdout]   20:     0x564ece6ac8f7 - it::ping::{{closure}}::he1e96909382a2f27
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:18:5
[INFO] [stdout]   21:     0x564ece68b676 - <core::pin::Pin<P> as core::future::future::Future>::poll::h6cd74be0c035aff1
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/future/future.rs:124:9
[INFO] [stdout]   22:     0x564ece688c94 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hbd10d6b39b184f59
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:531:57
[INFO] [stdout]   23:     0x564ece688b9a - tokio::runtime::coop::with_budget::h72ea04bb809844c9
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/coop.rs:102:5
[INFO] [stdout]   24:     0x564ece688b9a - tokio::runtime::coop::budget::h0577fe2afbbdbf5d
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/coop.rs:68:5
[INFO] [stdout]   25:     0x564ece688b9a - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h55d3b0f2ddd940a3
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:531:25
[INFO] [stdout]   26:     0x564ece6877cc - tokio::runtime::scheduler::current_thread::Context::enter::he56079c8a572b4f8
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:340:19
[INFO] [stdout]   27:     0x564ece68828a - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h369e71120d7ced00
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:530:36
[INFO] [stdout]   28:     0x564ece687fcb - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h44cbe5e997a8bcef
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:601:57
[INFO] [stdout]   29:     0x564ece69cdcd - tokio::macros::scoped_tls::ScopedKey<T>::set::hef23154757ac2179
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/macros/scoped_tls.rs:61:9
[INFO] [stdout]   30:     0x564ece687d5f - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hd49e37b506b90906
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:601:27
[INFO] [stdout]   31:     0x564ece687fec - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hfcb52784a3e14f07
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:520:19
[INFO] [stdout]   32:     0x564ece685f7b - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h26f28485c5bb6c59
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/scheduler/current_thread.rs:154:24
[INFO] [stdout]   33:     0x564ece6ab2c8 - tokio::runtime::runtime::Runtime::block_on::hc252937940f2248d
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.22.0/src/runtime/runtime.rs:279:47
[INFO] [stdout]   34:     0x564ece6ac36c - it::ping::hb7c9c4c768b68c8f
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:18:5
[INFO] [stdout]   35:     0x564ece6ac237 - it::ping::{{closure}}::h1c66710cec437771
[INFO] [stdout]                                at /opt/rustwide/workdir/server/tests/it/main.rs:14:16
[INFO] [stdout]   36:     0x564ece6809a6 - core::ops::function::FnOnce::call_once::hf6c57ed9d2086632
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x564ece7073df - core::ops::function::FnOnce::call_once::h692fe6f34484199f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   38:     0x564ece7073df - test::__rust_begin_short_backtrace::h48b62e76bd60e2de
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:621:18
[INFO] [stdout]   39:     0x564ece706201 - test::run_test_in_process::{{closure}}::h1c68597526ef43db
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:644:60
[INFO] [stdout]   40:     0x564ece706201 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hcae98092997f976b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   41:     0x564ece706201 - std::panicking::try::do_call::h8d1548c2f5bb535a
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:554:40
[INFO] [stdout]   42:     0x564ece706201 - std::panicking::try::hbb7a2054f484688e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:518:19
[INFO] [stdout]   43:     0x564ece706201 - std::panic::catch_unwind::h696d12d9d80497c2
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panic.rs:142:14
[INFO] [stdout]   44:     0x564ece706201 - test::run_test_in_process::h053367a7217c825b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:644:27
[INFO] [stdout]   45:     0x564ece706201 - test::run_test::{{closure}}::hfa524e9fabcba78e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:567:43
[INFO] [stdout]   46:     0x564ece6cd666 - test::run_test::{{closure}}::h2c4d0bb741ef7f9f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:595:41
[INFO] [stdout]   47:     0x564ece6cd666 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0d2c708415550075
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   48:     0x564ece6d2627 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h76e2635840d7293f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   49:     0x564ece6d2627 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::haa640b67a810a214
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   50:     0x564ece6d2627 - std::panicking::try::do_call::h41892e3bc2807ab5
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:554:40
[INFO] [stdout]   51:     0x564ece6d2627 - std::panicking::try::h861a16d180681c80
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:518:19
[INFO] [stdout]   52:     0x564ece6d2627 - std::panic::catch_unwind::h8195f9064b1509fa
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panic.rs:142:14
[INFO] [stdout]   53:     0x564ece6d2627 - std::thread::Builder::spawn_unchecked_::{{closure}}::h73cc419da1217371
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   54:     0x564ece6d2627 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h79dc6499ff073b09
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   55:     0x564ecee10535 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::ha718e8cdc51a5182
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   56:     0x564ecee10535 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h86072abc877c5474
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   57:     0x564ecee10535 - std::sys::pal::unix::thread::Thread::new::thread_start::hac4056197c365894
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys/pal/unix/thread.rs:108:17
[INFO] [stdout]   58:     0x7fd14c07eac3 - <unknown>
[INFO] [stdout]   59:     0x7fd14c10fbf4 - __clone
[INFO] [stdout]   60:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     ping
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p procession --test it`
[INFO] running `Command { std: "docker" "inspect" "95a386b7704a6e92fec8102674006ebb72bcf4f1adc7d083166ba799e81a8e3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "95a386b7704a6e92fec8102674006ebb72bcf4f1adc7d083166ba799e81a8e3a", kill_on_drop: false }`
[INFO] [stdout] 95a386b7704a6e92fec8102674006ebb72bcf4f1adc7d083166ba799e81a8e3a
