[INFO] fetching crate ic-agent 0.39.3...
[INFO] testing ic-agent-0.39.3 against try#a7ce54db9b096bd1098d07c645b489195a667c9f for pr-137940-2
[INFO] extracting crate ic-agent 0.39.3 into /workspace/builds/worker-1-tc2/source
[INFO] validating manifest of crates.io crate ic-agent 0.39.3 on toolchain a7ce54db9b096bd1098d07c645b489195a667c9f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ic-agent 0.39.3
[INFO] finished tweaking crates.io crate ic-agent 0.39.3
[INFO] tweaked toml for crates.io crate ic-agent 0.39.3 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 297 packages to latest compatible versions
[INFO] [stderr]       Adding async-channel v1.9.0 (available: v2.3.1)
[INFO] [stderr]       Adding cached v0.52.0 (available: v0.55.1)
[INFO] [stderr]       Adding getrandom v0.2.15 (available: v0.3.1)
[INFO] [stderr]       Adding ic-verify-bls-signature v0.5.0 (available: v0.6.0)
[INFO] [stderr]       Adding rand v0.8.5 (available: v0.9.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 34f86f62a1f90003e72d7dc7ed91ae3d461818e6cb4e52983b8d43cc948ddec3
[INFO] running `Command { std: "docker" "start" "-a" "34f86f62a1f90003e72d7dc7ed91ae3d461818e6cb4e52983b8d43cc948ddec3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "34f86f62a1f90003e72d7dc7ed91ae3d461818e6cb4e52983b8d43cc948ddec3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "34f86f62a1f90003e72d7dc7ed91ae3d461818e6cb4e52983b8d43cc948ddec3", kill_on_drop: false }`
[INFO] [stdout] 34f86f62a1f90003e72d7dc7ed91ae3d461818e6cb4e52983b8d43cc948ddec3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 29340a2736ed6e65addf5bef021fb2fc03eaa11563f6364377ba79f63209e3dd
[INFO] running `Command { std: "docker" "start" "-a" "29340a2736ed6e65addf5bef021fb2fc03eaa11563f6364377ba79f63209e3dd", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.94
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling libc v0.2.171
[INFO] [stderr]    Compiling zeroize v1.8.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling typenum v1.18.0
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling stable_deref_trait v1.2.0
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling once_cell v1.21.1
[INFO] [stderr]    Compiling itoa v1.0.15
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling base64ct v1.7.3
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling litemap v0.7.5
[INFO] [stderr]    Compiling writeable v0.5.5
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling cc v1.2.16
[INFO] [stderr]    Compiling smallvec v1.14.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling zerocopy v0.8.23
[INFO] [stderr]    Compiling pem-rfc7468 v0.7.0
[INFO] [stderr]    Compiling rustls-pki-types v1.11.0
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling icu_locid_transform_data v1.5.0
[INFO] [stderr]    Compiling der v0.7.9
[INFO] [stderr]    Compiling base16ct v0.2.0
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling icu_properties_data v1.5.0
[INFO] [stderr]    Compiling http v1.3.1
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling write16 v1.0.0
[INFO] [stderr]    Compiling rustls v0.23.23
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling rustversion v1.0.20
[INFO] [stderr]    Compiling icu_normalizer_data v1.5.0
[INFO] [stderr]    Compiling utf16_iter v1.0.5
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling ahash v0.8.11
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling thiserror v2.0.12
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling anyhow v1.0.97
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling syn v2.0.100
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling paste v1.0.15
[INFO] [stderr]    Compiling percent-encoding v2.3.1
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling form_urlencoded v1.2.1
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling tokio v1.44.1
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling crc32fast v1.4.2
[INFO] [stderr]    Compiling arrayvec v0.5.2
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling typed-arena v2.0.2
[INFO] [stderr]    Compiling ryu v1.0.20
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling unicode-width v0.1.14
[INFO] [stderr]    Compiling event-listener v2.5.3
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling parking v2.2.1
[INFO] [stderr]    Compiling data-encoding v2.8.0
[INFO] [stderr]    Compiling time-core v0.1.3
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling event-listener v5.4.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling pairing v0.23.0
[INFO] [stderr]    Compiling time-macros v0.2.20
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling psm v0.1.25
[INFO] [stderr]    Compiling stacker v0.1.19
[INFO] [stderr]    Compiling deranged v0.3.11
[INFO] [stderr]    Compiling pretty v0.12.4
[INFO] [stderr]    Compiling webpki-roots v0.26.8
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling instant v0.1.13
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling leb128 v0.2.5
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling subtle-ng v2.5.0
[INFO] [stderr]    Compiling opaque-debug v0.3.1
[INFO] [stderr]    Compiling half v1.8.3
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling curve25519-dalek-ng v4.1.1
[INFO] [stderr]    Compiling sha2 v0.9.9
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling event-listener-strategy v0.5.3
[INFO] [stderr]    Compiling ic_bls12_381 v0.10.1
[INFO] [stderr]    Compiling async-channel v1.9.0
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling rustls-pemfile v2.2.0
[INFO] [stderr]    Compiling ipnet v2.11.0
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling log v0.4.26
[INFO] [stderr]    Compiling stop-token v0.7.0
[INFO] [stderr]    Compiling pem v3.0.5
[INFO] [stderr]    Compiling async-lock v3.4.0
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling async-watch v0.3.1
[INFO] [stderr]    Compiling rangemap v1.5.1
[INFO] [stderr]    Compiling arc-swap v1.7.1
[INFO] [stderr]    Compiling time v0.3.39
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling backoff v0.4.0
[INFO] [stderr]    Compiling ic-verify-bls-signature v0.5.0
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling binread_derive v2.1.0
[INFO] [stderr]    Compiling synstructure v0.13.1
[INFO] [stderr]    Compiling tokio-util v0.7.14
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.7.5
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling zerovec-derive v0.10.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling icu_provider_macros v1.5.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling thiserror-impl v2.0.12
[INFO] [stderr]    Compiling binread v2.2.0
[INFO] [stderr]    Compiling candid_derive v0.6.6
[INFO] [stderr]    Compiling serde_repr v0.1.20
[INFO] [stderr]    Compiling async-trait v0.1.88
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling cached v0.52.0
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.7.5
[INFO] [stderr]    Compiling rustls-webpki v0.102.8
[INFO] [stderr]    Compiling zerovec v0.10.4
[INFO] [stderr]    Compiling tinystr v0.7.6
[INFO] [stderr]    Compiling icu_collections v1.5.0
[INFO] [stderr]    Compiling icu_locid v1.5.0
[INFO] [stderr]    Compiling icu_provider v1.5.0
[INFO] [stderr]    Compiling hyper v1.6.0
[INFO] [stderr]    Compiling tower v0.5.2
[INFO] [stderr]    Compiling icu_locid_transform v1.5.0
[INFO] [stderr]    Compiling icu_properties v1.5.1
[INFO] [stderr]    Compiling hyper-util v0.1.10
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling serde_bytes v0.11.17
[INFO] [stderr]    Compiling ic_principal v0.1.1
[INFO] [stderr]    Compiling serde_cbor v0.11.2
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling ed25519-consensus v2.1.0
[INFO] [stderr]    Compiling tokio-rustls v0.26.2
[INFO] [stderr]    Compiling ic-certification v3.0.3
[INFO] [stderr]    Compiling hyper-rustls v0.27.5
[INFO] [stderr]    Compiling candid v0.10.13
[INFO] [stderr]    Compiling simple_asn1 v0.6.3
[INFO] [stderr]    Compiling icu_normalizer v1.5.0
[INFO] [stderr]    Compiling idna_adapter v1.2.0
[INFO] [stderr]    Compiling idna v1.0.3
[INFO] [stderr]    Compiling url v2.5.4
[INFO] [stderr]    Compiling reqwest v0.12.14
[INFO] [stderr]    Compiling ic-transport-types v0.39.3
[INFO] [stderr]    Compiling ic-agent v0.39.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 21.87s
[INFO] running `Command { std: "docker" "inspect" "29340a2736ed6e65addf5bef021fb2fc03eaa11563f6364377ba79f63209e3dd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "29340a2736ed6e65addf5bef021fb2fc03eaa11563f6364377ba79f63209e3dd", kill_on_drop: false }`
[INFO] [stdout] 29340a2736ed6e65addf5bef021fb2fc03eaa11563f6364377ba79f63209e3dd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 34f7252b0bc8c31a055f804771f5489ab6cf70b6df967466f0edf6c615352d8e
[INFO] running `Command { std: "docker" "start" "-a" "34f7252b0bc8c31a055f804771f5489ab6cf70b6df967466f0edf6c615352d8e", kill_on_drop: false }`
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling log v0.4.26
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling colored v3.0.0
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling tokio v1.44.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling indexmap v2.8.0
[INFO] [stderr]    Compiling rand v0.9.0
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling tokio-util v0.7.14
[INFO] [stderr]    Compiling tokio-rustls v0.26.2
[INFO] [stderr]    Compiling tower v0.5.2
[INFO] [stderr]    Compiling h2 v0.4.8
[INFO] [stderr]    Compiling hyper v1.6.0
[INFO] [stderr]    Compiling hyper-util v0.1.10
[INFO] [stderr]    Compiling hyper-rustls v0.27.5
[INFO] [stderr]    Compiling mockito v1.7.0
[INFO] [stderr]    Compiling reqwest v0.12.14
[INFO] [stderr]    Compiling ic-agent v0.39.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 13.26s
[INFO] running `Command { std: "docker" "inspect" "34f7252b0bc8c31a055f804771f5489ab6cf70b6df967466f0edf6c615352d8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "34f7252b0bc8c31a055f804771f5489ab6cf70b6df967466f0edf6c615352d8e", kill_on_drop: false }`
[INFO] [stdout] 34f7252b0bc8c31a055f804771f5489ab6cf70b6df967466f0edf6c615352d8e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:1680c8fbd8dfb46fcd6d22e546df16ab0e007457dc29fcd9e987cdd5077fd1af" "/opt/rustwide/cargo-home/bin/cargo" "+a7ce54db9b096bd1098d07c645b489195a667c9f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f2dafc94207c8eeb494534e92a721b9628c61f383829d458c6396438264371f3
[INFO] running `Command { std: "docker" "start" "-a" "f2dafc94207c8eeb494534e92a721b9628c61f383829d458c6396438264371f3", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ic_agent-d79ebfc5ed9791d1)
[INFO] [stdout] 
[INFO] [stdout] running 56 tests
[INFO] [stdout] test agent::agent_error::tests::formats_text_html ... ok
[INFO] [stdout] test agent::agent_error::tests::content_type_none_invalid_utf8 ... ok
[INFO] [stdout] test agent::agent_error::tests::content_type_none_valid_utf8 ... ok
[INFO] [stdout] test agent::agent_error::tests::formats_text_plain_charset_utf8 ... ok
[INFO] [stdout] test agent::agent_error::tests::formats_text_plain ... ok
[INFO] [stdout] test agent::agent_test::call_rejected ... ok
[INFO] [stdout] test agent::agent_test::check_subnet_range_with_pruned_range ... ok
[INFO] [stdout] test agent::agent_test::status_error ... ok
[INFO] [stdout] test agent::agent_test::status ... ok
[INFO] [stdout] test agent::agent_test::call_rejected_without_error_code ... ok
[INFO] [stdout] test agent::agent_test::query ... ok
[INFO] [stdout] test agent::agent_test::call_error ... ok
[INFO] [stdout] test agent::agent_test::query_error ... ok
[INFO] [stdout] test agent::agent_test::status_okay ... ok
[INFO] [stdout] test agent::offline_tests::rounded_expiry ... ok
[INFO] [stdout] test agent::agent_test::query_rejected ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_compute_score_with_penalty ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_snapshot_init ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_stats_for_next_n_nodes ... ignored
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_sync_node_scenarios ... ok
[INFO] [stdout] test agent::agent_test::reqwest_client_status_okay_when_request_retried ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_update_for_existing_node_succeeds ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_update_for_non_existing_node_fails ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::latency_based_routing::tests::test_weighted_sample ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_n_nodes ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_next_node ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_snapshot_init ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_update_of_existing_healthy_node_with_unhealthy_node_returns_true ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_sync_node_scenarios ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_update_of_existing_unhealthy_node_with_healthy_node_returns_true ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::snapshot::round_robin_routing::tests::test_update_of_non_existing_node_always_returns_false ... ok
[INFO] [stdout] test agent::route_provider::tests::test_empty_routes ... ok
[INFO] [stdout] test agent::route_provider::tests::test_routes_rotation ... ok
[INFO] [stdout] test agent::route_provider::tests::test_n_routes ... ok
[INFO] [stdout] test identity::prime256v1::test::test_prime256v1_public_key ... ok
[INFO] [stdout] test agent::agent_test::wrong_subnet_query_certificate ... ok
[INFO] [stdout] test agent::agent_test::check_subnet_range_with_unauthorized_range ... ok
[INFO] [stdout] test identity::secp256k1::test::test_secp256k1_public_key ... ok
[INFO] [stdout] test identity::prime256v1::test::test_prime256v1_signature ... ok
[INFO] [stdout] test agent::agent_test::check_subnet_range_with_valid_range ... ok
[INFO] [stdout] test agent::agent_test::too_many_delegations ... ok
[INFO] [stdout] test identity::secp256k1::test::test_secp256k1_signature ... ok
[INFO] [stdout] test identity::prime256v1::test::test_prime256v1_reject_wrong_curve - should panic ... ok
[INFO] [stdout] test identity::prime256v1::test::test_prime256v1_reject_wrong_curve_no_id - should panic ... ok
[INFO] [stdout] test identity::secp256k1::test::test_secp256k1_reject_wrong_curve - should panic ... ok
[INFO] [stdout] test identity::secp256k1::test::test_secp256k1_reject_wrong_curve_no_id - should panic ... ok
[INFO] [stdout] test agent::agent_test::no_cert ... ok
[INFO] [stdout] test agent::offline_tests::client_ratelimit ... ok
[INFO] [stdout] test agent::agent_test::retry_ratelimit ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_route_with_one_healthy_and_one_unhealthy_seed ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_routing_with_no_healthy_nodes_returns_an_error ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_route_with_no_healthy_seeds_errors ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_routing_with_an_empty_fetched_list_of_api_nodes ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_route_with_initially_unhealthy_seeds_becoming_healthy ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_routing_with_topology_and_node_health_updates ... ok
[INFO] [stdout] test agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet stdout ----
[INFO] [stdout] 2025-03-17T08:45:23.361397Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:23.361477Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:23.361512Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:23.364221Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:23.364309Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:23.364337Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:23.364357Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:23.364676Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:24.366586Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:24.366649Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:24.366683Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:24.367230Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:24.367305Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:24.367322Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:24.367336Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:24.367719Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:25.369145Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:25.369197Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:25.369235Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:25.369581Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:25.369657Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:25.369675Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:25.369690Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:25.369929Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:26.371814Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:26.371937Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:26.371990Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:26.372553Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:26.372695Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:26.372726Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:26.372752Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:26.373135Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:27.374521Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:27.374571Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:27.374631Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:27.375473Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:27.375565Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:27.375587Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:27.375606Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:27.375923Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:28.377679Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:28.377733Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:28.377765Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:28.378142Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:28.378228Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:28.378248Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:28.378265Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:28.378560Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:29.379278Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:29.379324Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:29.379353Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:29.379761Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:29.379871Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:29.379890Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:29.379905Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:29.380153Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:30.381742Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:30.381794Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:30.381825Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:30.382251Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:30.382334Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:30.382354Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:30.382372Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:30.382595Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:31.383001Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:31.383047Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:31.383075Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:31.383409Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:31.383488Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:31.383513Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:31.383530Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:31.383759Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:32.385571Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:32.385622Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:32.385653Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:32.386104Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:32.386194Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:32.386216Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:32.386235Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:32.386542Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:33.387065Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:33.387126Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:33.387165Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:33.387635Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:33.387710Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:33.387728Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:33.387743Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:33.388043Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:34.389024Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:34.389087Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:34.389117Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:34.389496Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:34.389572Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:34.389590Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:34.389606Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:34.389800Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:35.391251Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:35.391301Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:35.391332Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:35.392200Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:35.392278Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:35.392312Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:35.392334Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:35.392604Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:36.393526Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:36.393594Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:36.393636Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:36.394087Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:36.394174Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:36.394192Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:36.394207Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:36.394476Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:37.394912Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:37.394957Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:37.394985Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:37.395349Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:37.395440Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:37.395458Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:37.395473Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:37.395710Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:38.397450Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:38.397510Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:38.397546Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:38.397999Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:38.398083Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:38.398100Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:38.398115Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:38.398387Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:39.399220Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:39.399271Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:39.399302Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:39.399677Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:39.399750Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:39.399803Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:39.399822Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:39.400097Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:40.401068Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:40.401122Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:40.401155Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:40.401553Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:40.401626Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:40.401642Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:40.401657Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:40.401880Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:41.403464Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:41.403517Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:41.403547Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:41.404331Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:41.404666Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:41.404685Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:41.404701Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:41.404924Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:42.406578Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:42.406634Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:42.406667Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:42.407010Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:42.407082Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:42.407098Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:42.407113Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:42.407317Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:43.408886Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:43.408950Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:43.408987Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:43.409381Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:43.409458Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:43.409475Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:43.409499Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:43.409771Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:44.411620Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:44.411667Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:44.411694Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:44.412061Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:44.412135Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:44.412153Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:44.412169Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:44.412412Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:45.413875Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:45.413934Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:45.413968Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:45.414467Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:45.414555Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:45.414575Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:45.414593Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:45.414810Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:46.416643Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:46.416693Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:46.416722Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:46.417127Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:46.417210Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:46.417227Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:46.417242Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:46.417517Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:47.418957Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:47.419007Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:47.419036Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:47.419367Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:47.419448Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:47.419464Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:47.419479Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:47.419706Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:48.421563Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:48.421614Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:48.421646Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:48.422040Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:48.422110Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:48.422127Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:48.422155Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:48.422398Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:49.423081Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:49.423129Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:49.423158Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:49.423503Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:49.423580Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:49.423597Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:49.423613Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:49.423817Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:50.425723Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:50.425787Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:50.425827Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:50.426349Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:50.426462Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:50.426490Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:50.426514Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:50.426846Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:51.428394Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:51.428447Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:51.428479Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:51.428812Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:51.428915Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:51.428934Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:51.428948Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:51.429147Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:52.430113Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:52.430176Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:52.430214Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:52.430681Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:52.430780Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:52.430797Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:52.430814Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:52.431067Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:53.432593Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:53.432649Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:53.432683Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:53.433043Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:53.433122Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:53.433140Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:53.433156Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:53.433349Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:54.434297Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:54.434352Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:54.434381Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:54.434742Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:54.434826Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:54.434869Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:54.434885Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:54.435128Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:55.436761Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:55.436826Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:55.436914Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:55.437421Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:55.437553Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:55.437589Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:55.437613Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:55.437883Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:56.439619Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:56.439662Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:56.439718Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:56.440101Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:56.440178Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:56.440195Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:56.440210Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:56.440421Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:57.441929Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:57.441970Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:57.442006Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:57.442395Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:57.442489Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:57.442512Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:57.442528Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:57.442766Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:58.444629Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:58.444688Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:58.444723Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:58.445121Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:58.445193Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:58.445210Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:58.445225Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:58.445443Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:59.446132Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:59.446176Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:59.446204Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:59.446512Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:59.446595Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:45:59.446615Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:45:59.446633Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:45:59.446863Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:00.448662Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:00.448714Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:00.448747Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:00.449134Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:00.449211Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:00.449229Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:00.449245Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:00.449451Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:01.450996Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:01.451038Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:01.451063Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:01.451418Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:01.451494Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:01.451511Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:01.451526Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:01.451736Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:02.453498Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:02.453542Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:02.453569Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:02.453937Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:02.454030Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:02.454046Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:02.454061Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:02.454281Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:03.455748Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:03.455790Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:03.455860Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:03.456270Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:03.456369Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("https", ic0.app)
[INFO] [stdout] 2025-03-17T08:46:03.456391Z DEBUG reqwest::connect: starting new connection: https://ic0.app/    
[INFO] [stdout] 2025-03-17T08:46:03.456410Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("https"), host=Some("ic0.app"), port=None
[INFO] [stdout] 2025-03-17T08:46:03.456748Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("https", ic0.app)
[INFO] [stdout] 
[INFO] [stdout] thread 'agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet' panicked at src/agent/route_provider/dynamic_routing/dynamic_route_provider.rs:345:9:
[INFO] [stdout] Expected excluded domains ["ic0.app"] are still observed in routing over the last 30 calls
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e2c0ef8af32 - std::backtrace_rs::backtrace::libunwind::trace::h8d95625898284e7d
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e2c0ef8af32 - std::backtrace_rs::backtrace::trace_unsynchronized::h9eafd5c61303e492
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e2c0ef8af32 - std::sys::backtrace::_print_fmt::hec2578e807cc4a3f
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x5e2c0ef8af32 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hd8ebefbbcb1fe23a
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x5e2c0efb6fe3 - core::fmt::rt::Argument::fmt::h09f3c4b7b5ff5386
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/fmt/rt.rs:185:76
[INFO] [stdout]    5:     0x5e2c0efb6fe3 - core::fmt::write::hef9d041e1d5d9592
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/fmt/mod.rs:1449:21
[INFO] [stdout]    6:     0x5e2c0ef86e93 - std::io::Write::write_fmt::h7cfb9383e0a29362
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/io/mod.rs:1890:15
[INFO] [stdout]    7:     0x5e2c0ef8ad82 - std::sys::backtrace::BacktraceLock::print::h263d323f217ce3a7
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x5e2c0ef8c3da - std::panicking::default_hook::{{closure}}::h25050253f7dc0d3e
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:298:22
[INFO] [stdout]    9:     0x5e2c0ef8c1c9 - std::panicking::default_hook::hf3291a4c87ea2887
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:322:9
[INFO] [stdout]   10:     0x5e2c0e36a487 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h51204ed24e282361
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/alloc/src/boxed.rs:1984:9
[INFO] [stdout]   11:     0x5e2c0e36a487 - test::test_main::{{closure}}::h3588678a150ab7c4
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:136:21
[INFO] [stdout]   12:     0x5e2c0ef8cec3 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h95d63edfac47e230
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/alloc/src/boxed.rs:1984:9
[INFO] [stdout]   13:     0x5e2c0ef8cec3 - std::panicking::rust_panic_with_hook::h3550dbeaa1041124
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:839:13
[INFO] [stdout]   14:     0x5e2c0ef8cb7a - std::panicking::begin_panic_handler::{{closure}}::hde0dd646a9e2b5b7
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:704:13
[INFO] [stdout]   15:     0x5e2c0ef8b429 - std::sys::backtrace::__rust_end_short_backtrace::haeb22ba48b4d728e
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/backtrace.rs:168:18
[INFO] [stdout]   16:     0x5e2c0ef8c80d - rust_begin_unwind
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:695:5
[INFO] [stdout]   17:     0x5e2c0efb48e0 - core::panicking::panic_fmt::hca923aa694825dc3
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x5e2c0e2add80 - ic_agent::agent::route_provider::dynamic_routing::dynamic_route_provider::tests::assert_no_routing_via_domains::{{closure}}::hed00ad9ccf2163a1
[INFO] [stdout]                                at /opt/rustwide/workdir/src/agent/route_provider/dynamic_routing/dynamic_route_provider.rs:345:9
[INFO] [stdout]   19:     0x5e2c0e2b05a8 - ic_agent::agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet::{{closure}}::hca32c65d091ee5d5
[INFO] [stdout]                                at /opt/rustwide/workdir/src/agent/route_provider/dynamic_routing/dynamic_route_provider.rs:375:10
[INFO] [stdout]   20:     0x5e2c0e1e5952 - <core::pin::Pin<P> as core::future::future::Future>::poll::hb06ccafcc587b3e3
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/future/future.rs:124:9
[INFO] [stdout]   21:     0x5e2c0e1e556d - <core::pin::Pin<P> as core::future::future::Future>::poll::h23026e53ee116966
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/future/future.rs:124:9
[INFO] [stdout]   22:     0x5e2c0e1ac0ff - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h6b84591b664a1036
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:733:54
[INFO] [stdout]   23:     0x5e2c0e1abf75 - tokio::task::coop::with_budget::h530070dfecfc5030
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   24:     0x5e2c0e1abf75 - tokio::task::coop::budget::h65af68847a96248f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   25:     0x5e2c0e1abf75 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::hec190a9f3a118db5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:733:25
[INFO] [stdout]   26:     0x5e2c0e1a53d0 - tokio::runtime::scheduler::current_thread::Context::enter::h3dd2fcfd9544cd71
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:432:19
[INFO] [stdout]   27:     0x5e2c0e1ab058 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::he629952ba54354c2
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:732:36
[INFO] [stdout]   28:     0x5e2c0e1a88a4 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h19109a670634278d
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:820:68
[INFO] [stdout]   29:     0x5e2c0e119e5b - tokio::runtime::context::scoped::Scoped<T>::set::hbc570a966808b7ad
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   30:     0x5e2c0e119619 - tokio::runtime::context::set_scheduler::{{closure}}::h2b85095e3fe87e34
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/context.rs:180:26
[INFO] [stdout]   31:     0x5e2c0e181352 - std::thread::local::LocalKey<T>::try_with::h6cc16c0d49c98bb5
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/thread/local.rs:311:12
[INFO] [stdout]   32:     0x5e2c0e17f87e - std::thread::local::LocalKey<T>::with::hbed688e627fc8fec
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/thread/local.rs:275:15
[INFO] [stdout]   33:     0x5e2c0e1195cd - tokio::runtime::context::set_scheduler::hfa5cdf75bdc25fa1
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/context.rs:180:9
[INFO] [stdout]   34:     0x5e2c0e1a8230 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hb209da8e6ee7620a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:820:27
[INFO] [stdout]   35:     0x5e2c0e1a89e3 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hcad35700f0de862e
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:720:19
[INFO] [stdout]   36:     0x5e2c0e1a018c - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h29619f9c87d5af21
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:200:28
[INFO] [stdout]   37:     0x5e2c0e19e618 - tokio::runtime::context::runtime::enter_runtime::h372f5098cd20d3a7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   38:     0x5e2c0e19fd01 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h22644001e5c6cd13
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   39:     0x5e2c0e2aac34 - tokio::runtime::runtime::Runtime::block_on_inner::h5bec90f7e2edfe8e
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/runtime.rs:368:47
[INFO] [stdout]   40:     0x5e2c0e2ab177 - tokio::runtime::runtime::Runtime::block_on::hcaed62eb6d0c2541
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.44.1/src/runtime/runtime.rs:342:13
[INFO] [stdout]   41:     0x5e2c0e2af775 - ic_agent::agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet::hb6c208f69ce3a620
[INFO] [stdout]                                at /opt/rustwide/workdir/src/agent/route_provider/dynamic_routing/dynamic_route_provider.rs:381:9
[INFO] [stdout]   42:     0x5e2c0e2af617 - ic_agent::agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet::{{closure}}::h74c868fcd8ce14a6
[INFO] [stdout]                                at /opt/rustwide/workdir/src/agent/route_provider/dynamic_routing/dynamic_route_provider.rs:349:28
[INFO] [stdout]   43:     0x5e2c0e0eea76 - core::ops::function::FnOnce::call_once::hb8a4da7e2ff47bfe
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x5e2c0e36fd6b - core::ops::function::FnOnce::call_once::hbd335dbb4bb5fce4
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5e2c0e36fd6b - test::__rust_begin_short_backtrace::h04cc5e3b375a80f9
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:638:18
[INFO] [stdout]   46:     0x5e2c0e36ee25 - test::run_test_in_process::{{closure}}::h820e05c00a84ca94
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:661:60
[INFO] [stdout]   47:     0x5e2c0e36ee25 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h5d54c80bc199f62c
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   48:     0x5e2c0e36ee25 - std::panicking::try::do_call::h832d61a9fc2b6e3e
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:587:40
[INFO] [stdout]   49:     0x5e2c0e36ee25 - std::panicking::try::h9a404c5f3a090501
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:550:19
[INFO] [stdout]   50:     0x5e2c0e36ee25 - std::panic::catch_unwind::hf5157d4c366d9053
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5e2c0e36ee25 - test::run_test_in_process::h671deca6957ee157
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:661:27
[INFO] [stdout]   52:     0x5e2c0e36ee25 - test::run_test::{{closure}}::h8a18e214254d2663
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:582:43
[INFO] [stdout]   53:     0x5e2c0e331e14 - test::run_test::{{closure}}::ha2abac7cc0a13c10
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/test/src/lib.rs:612:41
[INFO] [stdout]   54:     0x5e2c0e331e14 - std::sys::backtrace::__rust_begin_short_backtrace::h013f8b8ca29e5f67
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/backtrace.rs:152:18
[INFO] [stdout]   55:     0x5e2c0e33594a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc0336636a8c55b05
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/thread/mod.rs:559:17
[INFO] [stdout]   56:     0x5e2c0e33594a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h256d9472a72a8c66
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   57:     0x5e2c0e33594a - std::panicking::try::do_call::hf49ea3ffcc3bd1db
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:587:40
[INFO] [stdout]   58:     0x5e2c0e33594a - std::panicking::try::h2a067a3336161e1c
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panicking.rs:550:19
[INFO] [stdout]   59:     0x5e2c0e33594a - std::panic::catch_unwind::h931ea2a9df59b7fa
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/panic.rs:359:14
[INFO] [stdout]   60:     0x5e2c0e33594a - std::thread::Builder::spawn_unchecked_::{{closure}}::haf08f1439431615c
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/thread/mod.rs:557:30
[INFO] [stdout]   61:     0x5e2c0e33594a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hd1dc5dd1edff0233
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   62:     0x5e2c0ef9071b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf6ad22f40418d409
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/alloc/src/boxed.rs:1970:9
[INFO] [stdout]   63:     0x5e2c0ef9071b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h734c3e370b18da4b
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/alloc/src/boxed.rs:1970:9
[INFO] [stdout]   64:     0x5e2c0ef9071b - std::sys::pal::unix::thread::Thread::new::thread_start::hfbde3a90511cd82d
[INFO] [stdout]                                at /rustc/a7ce54db9b096bd1098d07c645b489195a667c9f/library/std/src/sys/pal/unix/thread.rs:109:17
[INFO] [stdout]   65:     0x75406962fac3 - <unknown>
[INFO] [stdout]   66:     0x7540696c0a04 - clone
[INFO] [stdout]   67:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     agent::route_provider::dynamic_routing::dynamic_route_provider::tests::test_mainnet
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 54 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out; finished in 41.05s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "f2dafc94207c8eeb494534e92a721b9628c61f383829d458c6396438264371f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2dafc94207c8eeb494534e92a721b9628c61f383829d458c6396438264371f3", kill_on_drop: false }`
[INFO] [stdout] f2dafc94207c8eeb494534e92a721b9628c61f383829d458c6396438264371f3