[INFO] cloning repository https://github.com/johan-steffens/foxy [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/johan-steffens/foxy" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjohan-steffens%2Ffoxy", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjohan-steffens%2Ffoxy'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 442ab6616b602f0f6cb109422825ddc8e88f74b0 [INFO] testing johan-steffens/foxy against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjohan-steffens%2Ffoxy" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/johan-steffens/foxy [INFO] finished tweaking git repo https://github.com/johan-steffens/foxy [INFO] tweaked toml for git repo https://github.com/johan-steffens/foxy written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/johan-steffens/foxy on toolchain ad85bc524b1ad696e42061ad8338d382dffbdbe5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/johan-steffens/foxy 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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded assert-json-diff v2.0.2 [INFO] [stderr] Downloaded slog-async v2.8.0 [INFO] [stderr] Downloaded headers v0.3.9 [INFO] [stderr] Downloaded deadpool-runtime v0.1.4 [INFO] [stderr] Downloaded hostname v0.4.1 [INFO] [stderr] Downloaded deadpool v0.10.0 [INFO] [stderr] Downloaded opentelemetry-http v0.29.0 [INFO] [stderr] Downloaded opentelemetry v0.29.1 [INFO] [stderr] Downloaded opentelemetry v0.28.0 [INFO] [stderr] Downloaded opentelemetry_sdk v0.28.0 [INFO] [stderr] Downloaded tonic v0.12.3 [INFO] [stderr] Downloaded opentelemetry_sdk v0.29.0 [INFO] [stderr] Downloaded warp v0.3.7 [INFO] [stderr] Downloaded wiremock v0.6.4 [INFO] [stderr] Downloaded opentelemetry-otlp v0.29.0 [INFO] [stderr] Downloaded slog-scope v4.4.0 [INFO] [stderr] Downloaded tracing-opentelemetry v0.29.0 [INFO] [stderr] Downloaded slog-stdlog v4.1.1 [INFO] [stderr] Downloaded slog-json v2.6.1 [INFO] [stderr] Downloaded slog-term v2.9.1 [INFO] [stderr] Downloaded openssl-src v300.5.0+3.5.0 [INFO] [stderr] Downloaded opentelemetry-semantic-conventions v0.29.0 [INFO] [stderr] Downloaded opentelemetry-proto v0.29.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 7179b7a657fb22d441d5f65dc804f034e2b594316299ebc5c17345d7547681f5 [INFO] running `Command { std: "docker" "start" "-a" "7179b7a657fb22d441d5f65dc804f034e2b594316299ebc5c17345d7547681f5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "7179b7a657fb22d441d5f65dc804f034e2b594316299ebc5c17345d7547681f5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7179b7a657fb22d441d5f65dc804f034e2b594316299ebc5c17345d7547681f5", kill_on_drop: false }` [INFO] [stdout] 7179b7a657fb22d441d5f65dc804f034e2b594316299ebc5c17345d7547681f5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f3a5cee4b7fb0d3c032f65fdabd9cc61034a2229eda0f50d8100cc345e0ced6a [INFO] running `Command { std: "docker" "start" "-a" "f3a5cee4b7fb0d3c032f65fdabd9cc61034a2229eda0f50d8100cc345e0ced6a", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling openssl-src v300.5.0+3.5.0 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling slog v2.7.0 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling openssl v0.10.73 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling powerfmt v0.2.0 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling atomic-waker v1.1.2 [INFO] [stderr] Compiling icu_normalizer v2.0.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling time-macros v0.2.22 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling httpdate v1.0.3 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling openssl-sys v0.9.109 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling toml_datetime v0.6.11 [INFO] [stderr] Compiling idna v1.0.3 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling serde_spanned v0.6.9 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling untrusted v0.9.0 [INFO] [stderr] Compiling slog-async v2.8.0 [INFO] [stderr] Compiling arc-swap v1.7.1 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling anstream v0.6.19 [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling toml_edit v0.22.27 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling slog-scope v4.4.0 [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling term v0.7.0 [INFO] [stderr] Compiling bstr v1.12.0 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling pem v3.0.5 [INFO] [stderr] Compiling time v0.3.41 [INFO] [stderr] Compiling is-terminal v0.4.16 [INFO] [stderr] Compiling unsafe-libyaml v0.2.11 [INFO] [stderr] Compiling take_mut v0.2.2 [INFO] [stderr] Compiling iana-time-zone v0.1.63 [INFO] [stderr] Compiling jiff v0.2.15 [INFO] [stderr] Compiling uuid v1.17.0 [INFO] [stderr] Compiling serde_yaml v0.9.34+deprecated [INFO] [stderr] Compiling chrono v0.4.41 [INFO] [stderr] Compiling slog-stdlog v4.1.1 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling simple_asn1 v0.6.3 [INFO] [stderr] Compiling slog-term v2.9.1 [INFO] [stderr] Compiling slog-json v2.6.1 [INFO] [stderr] Compiling urlencoding v2.1.3 [INFO] [stderr] Compiling toml v0.8.23 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling globset v0.4.16 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling jsonwebtoken v9.3.1 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling tower-http v0.6.6 [INFO] [stderr] Compiling h2 v0.4.10 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.14 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling reqwest v0.12.20 [INFO] [stderr] Compiling foxy-io v0.3.12 (/opt/rustwide/workdir) [INFO] [stdout] warning: struct `ProxyConfig` is never constructed [INFO] [stdout] --> src/config/proxy.rs:12:12 [INFO] [stdout] | [INFO] [stdout] 12 | pub struct ProxyConfig { [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `ServerConfig` is never constructed [INFO] [stdout] --> src/config/proxy.rs:25:12 [INFO] [stdout] | [INFO] [stdout] 25 | pub struct ServerConfig { [INFO] [stdout] | ^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_listen` is never used [INFO] [stdout] --> src/config/proxy.rs:39:4 [INFO] [stdout] | [INFO] [stdout] 39 | fn default_listen() -> String { [INFO] [stdout] | ^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_body_limit` is never used [INFO] [stdout] --> src/config/proxy.rs:43:4 [INFO] [stdout] | [INFO] [stdout] 43 | fn default_body_limit() -> usize { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_header_limit` is never used [INFO] [stdout] --> src/config/proxy.rs:47:4 [INFO] [stdout] | [INFO] [stdout] 47 | fn default_header_limit() -> usize { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 32s [INFO] running `Command { std: "docker" "inspect" "f3a5cee4b7fb0d3c032f65fdabd9cc61034a2229eda0f50d8100cc345e0ced6a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f3a5cee4b7fb0d3c032f65fdabd9cc61034a2229eda0f50d8100cc345e0ced6a", kill_on_drop: false }` [INFO] [stdout] f3a5cee4b7fb0d3c032f65fdabd9cc61034a2229eda0f50d8100cc345e0ced6a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] e697bb1b69c240dc023b1c050c711dc649f385d68b7fb5c8f8cd46336dcb006b [INFO] running `Command { std: "docker" "start" "-a" "e697bb1b69c240dc023b1c050c711dc649f385d68b7fb5c8f8cd46336dcb006b", kill_on_drop: false }` [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling typenum v1.18.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling bitflags v2.9.1 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling http v0.2.12 [INFO] [stderr] Compiling cpufeatures v0.2.17 [INFO] [stderr] Compiling unicase v2.8.1 [INFO] [stderr] Compiling multer v2.1.0 [INFO] [stderr] Compiling rustix v1.0.7 [INFO] [stderr] Compiling data-encoding v2.9.0 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling h2 v0.4.10 [INFO] [stderr] Compiling openssl v0.10.73 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling mime_guess v2.0.5 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling base64 v0.21.7 [INFO] [stderr] Compiling sdd v3.0.8 [INFO] [stderr] Compiling deadpool-runtime v0.1.4 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling slog-json v2.6.1 [INFO] [stderr] Compiling linux-raw-sys v0.9.4 [INFO] [stderr] Compiling scc v2.3.4 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling http-body v0.4.6 [INFO] [stderr] Compiling headers-core v0.2.0 [INFO] [stderr] Compiling assert-json-diff v2.0.2 [INFO] [stderr] Compiling deadpool v0.10.0 [INFO] [stderr] Compiling serial_test_derive v3.2.0 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling jsonwebtoken v9.3.1 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling tungstenite v0.21.0 [INFO] [stderr] Compiling headers v0.3.9 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling globset v0.4.16 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling h2 v0.3.26 [INFO] [stderr] Compiling tokio-tungstenite v0.21.0 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling serial_test v3.2.0 [INFO] [stderr] Compiling tower-http v0.6.6 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.14 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling wiremock v0.6.4 [INFO] [stderr] Compiling reqwest v0.12.20 [INFO] [stderr] Compiling hyper v0.14.32 [INFO] [stderr] Compiling foxy-io v0.3.12 (/opt/rustwide/workdir) [INFO] [stdout] warning: struct `ProxyConfig` is never constructed [INFO] [stdout] --> src/config/proxy.rs:12:12 [INFO] [stdout] | [INFO] [stdout] 12 | pub struct ProxyConfig { [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `ServerConfig` is never constructed [INFO] [stdout] --> src/config/proxy.rs:25:12 [INFO] [stdout] | [INFO] [stdout] 25 | pub struct ServerConfig { [INFO] [stdout] | ^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_listen` is never used [INFO] [stdout] --> src/config/proxy.rs:39:4 [INFO] [stdout] | [INFO] [stdout] 39 | fn default_listen() -> String { [INFO] [stdout] | ^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_body_limit` is never used [INFO] [stdout] --> src/config/proxy.rs:43:4 [INFO] [stdout] | [INFO] [stdout] 43 | fn default_body_limit() -> usize { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `default_header_limit` is never used [INFO] [stdout] --> src/config/proxy.rs:47:4 [INFO] [stdout] | [INFO] [stdout] 47 | fn default_header_limit() -> usize { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling warp v0.3.7 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 03s [INFO] running `Command { std: "docker" "inspect" "e697bb1b69c240dc023b1c050c711dc649f385d68b7fb5c8f8cd46336dcb006b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e697bb1b69c240dc023b1c050c711dc649f385d68b7fb5c8f8cd46336dcb006b", kill_on_drop: false }` [INFO] [stdout] e697bb1b69c240dc023b1c050c711dc649f385d68b7fb5c8f8cd46336dcb006b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] f670c6d0adf6ff9c0a14330e88af4d6ce81c55a3c8c10c913352302993af8657 [INFO] running `Command { std: "docker" "start" "-a" "f670c6d0adf6ff9c0a14330e88af4d6ce81c55a3c8c10c913352302993af8657", kill_on_drop: false }` [INFO] [stderr] warning: struct `ProxyConfig` is never constructed [INFO] [stderr] --> src/config/proxy.rs:12:12 [INFO] [stderr] | [INFO] [stderr] 12 | pub struct ProxyConfig { [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: struct `ServerConfig` is never constructed [INFO] [stderr] --> src/config/proxy.rs:25:12 [INFO] [stderr] | [INFO] [stderr] 25 | pub struct ServerConfig { [INFO] [stderr] | ^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: function `default_listen` is never used [INFO] [stderr] --> src/config/proxy.rs:39:4 [INFO] [stderr] | [INFO] [stderr] 39 | fn default_listen() -> String { [INFO] [stderr] | ^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: function `default_body_limit` is never used [INFO] [stderr] --> src/config/proxy.rs:43:4 [INFO] [stderr] | [INFO] [stderr] 43 | fn default_body_limit() -> usize { [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: function `default_header_limit` is never used [INFO] [stderr] --> src/config/proxy.rs:47:4 [INFO] [stderr] | [INFO] [stderr] 47 | fn default_header_limit() -> usize { [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: `foxy-io` (lib) generated 5 warnings [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.47s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foxy-337bc98fb397129f) [INFO] [stdout] [INFO] [stdout] running 554 tests [INFO] [stdout] test config::tests::config_tests::test_case_sensitivity ... ok [INFO] [stdout] test config::tests::config_tests::test_cache_refresh ... ok [INFO] [stdout] test config::tests::config_tests::test_config_builder_debug ... ok [INFO] [stdout] test config::tests::config_tests::test_config_clone ... ok [INFO] [stdout] test config::tests::config_tests::test_complex_nested_keys ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_debug ... ok [INFO] [stdout] test config::tests::config_tests::test_config_builder_new ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_io_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_debug ... ok [INFO] [stdout] test config::tests::config_tests::test_config_array_values ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_is_error_trait ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_parse_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_not_found ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_other ... ok [INFO] [stdout] test config::tests::config_tests::test_array_values ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_provider_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_provider_error_with_display_types ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_source ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_variants_equality ... ok [INFO] [stdout] test config::tests::config_tests::test_config_builder ... ok [INFO] [stdout] test config::tests::config_tests::test_config_get_or_default ... ok [INFO] [stdout] test config::tests::config_tests::test_config_builder_default ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_debug_with_data ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_empty_strings ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_io_error_from_conversion ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_provider_error_constructor ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_special_characters ... ok [INFO] [stdout] test config::tests::config_tests::test_config_default_file_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_nested_keys ... ok [INFO] [stdout] test config::tests::config_tests::test_config_object_values ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_ext_error_handling ... ok [INFO] [stdout] test config::tests::config_tests::test_config_error_no_source ... ok [INFO] [stdout] test config::tests::config_tests::test_config_get_raw_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_ext_type_conversion_error ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_name ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_ext_get ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_priority ... ok [INFO] [stdout] test config::tests::config_tests::test_config_type_conversion ... ok [INFO] [stdout] test config::tests::config_tests::test_config_invalid_type_conversion ... ok [INFO] [stdout] test config::tests::config_tests::test_custom_prefix ... ok [INFO] [stdout] test config::tests::config_tests::test_empty_environment ... ok [INFO] [stdout] test config::tests::config_tests::test_different_value_types ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_ext_null_values ... ok [INFO] [stdout] test config::tests::config_tests::test_custom_prefix_comprehensive ... ok [INFO] [stdout] test config::tests::config_tests::test_file_format_case_insensitive ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_debug ... ok [INFO] [stdout] test config::tests::config_tests::test_file_without_extension ... ok [INFO] [stdout] test config::tests::config_tests::test_empty_prefix ... ok [INFO] [stdout] test config::tests::config_tests::test_config_provider_has ... ok [INFO] [stdout] test config::tests::config_tests::test_empty_json_deserialization ... ok [INFO] [stdout] test config::tests::config_tests::test_empty_file ... ok [INFO] [stdout] test config::tests::config_tests::test_file_format_detection ... ok [INFO] [stdout] test config::tests::config_tests::test_env_provider ... ok [INFO] [stdout] test config::tests::config_tests::test_invalid_json_value ... ok [INFO] [stdout] test config::tests::config_tests::test_proxy_config_default ... ok [INFO] [stdout] test config::tests::config_tests::test_proxy_config_clone ... ok [INFO] [stdout] test config::tests::config_tests::test_nonexistent_file ... ok [INFO] [stdout] test config::tests::config_tests::test_invalid_toml ... ok [INFO] [stdout] test config::tests::config_tests::test_proxy_config_partial_deserialization ... ok [INFO] [stdout] test config::tests::config_tests::test_proxy_config_serialization ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_custom ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_edge_cases ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_full_deserialization ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_large_values ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_partial_deserialization ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_serialization ... ok [INFO] [stdout] test config::tests::config_tests::test_special_characters_in_values ... ok [INFO] [stdout] test config::tests::config_tests::test_config_builder_multiple_providers ... ok [INFO] [stdout] test config::tests::config_tests::test_invalid_json_deserialization ... ok [INFO] [stdout] test config::tests::config_tests::test_numeric_string_parsing ... ok [INFO] [stdout] test config::tests::config_tests::test_proxy_config_debug ... ok [INFO] [stdout] test core::tests::core_tests::test_filter_trait_default_implementations ... ok [INFO] [stdout] test core::tests::core_tests::test_filter_type_debug ... ok [INFO] [stdout] test config::tests::config_tests::test_yaml_config ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_default ... ok [INFO] [stdout] test config::tests::config_tests::test_yml_extension ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_debug ... ok [INFO] [stdout] test core::tests::core_tests::test_filter_type_is_methods ... ok [INFO] [stdout] test core::tests::core_tests::test_http_method_into_reqwest ... ok [INFO] [stdout] test config::tests::config_tests::test_toml_config ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_post_filter_success ... ok [INFO] [stdout] test core::tests::core_tests::test_filter_type_equality ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_post_filter_with_modification ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_post_filter_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_http_method_from ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_pre_filter_success ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_pre_filter_failure ... ok [INFO] [stdout] test config::tests::config_tests::test_config_empty_provider ... ok [INFO] [stdout] test core::tests::core_tests::test_http_method_to_string ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_pre_filter_with_modification ... ok [INFO] [stdout] test core::tests::core_tests::test_http_method_from_unsupported_method ... ok [INFO] [stdout] test config::tests::config_tests::test_provider_name ... ok [INFO] [stdout] test config::tests::config_tests::test_invalid_yaml ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_router_add_remove_route ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_router_no_routes ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_router_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_router_success ... ok [INFO] [stdout] test config::tests::config_tests::test_config_boolean_values ... ok [INFO] [stdout] test config::tests::config_tests::test_complex_nested_structure ... ok [INFO] [stdout] test config::tests::config_tests::test_unsupported_file_format ... ok [INFO] [stdout] test config::tests::config_tests::test_invalid_json ... ok [INFO] [stdout] test config::tests::config_tests::test_config_default_file ... ok [INFO] [stdout] test config::tests::config_tests::test_json_config ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_filter_properties ... ok [INFO] [stdout] test core::tests::core_tests::test_mock_router_get_routes ... ok [INFO] [stdout] test config::tests::config_tests::test_server_config_clone ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_route_post_filter_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_with_query_string ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_with_no_security_chain_config ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_creation ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_client_builder_error ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_global_pre_filter_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_creation_with_custom_timeout ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_concurrent_filter_access ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_add_global_filter ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_comprehensive_display ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_from_globset_error ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_debug_formatting ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_from_io_error ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_from_config_error ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_from_jwt_error ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_request ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_request_clone ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_request_with_empty_body ... ok [INFO] [stdout] test core::tests::core_tests::test_request_context ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_response ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_response_with_large_status_code ... ok [INFO] [stdout] test core::tests::core_tests::test_request_context_default ... ok [INFO] [stdout] test core::tests::core_tests::test_request_context_with_data ... ok [INFO] [stdout] test core::tests::core_tests::test_response_context ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_routing_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_response_context_clone ... ok [INFO] [stdout] test core::tests::core_tests::test_request_context_clone ... ok [INFO] [stdout] test core::tests::core_tests::test_route_creation ... ok [INFO] [stdout] test core::tests::core_tests::test_route_with_filters ... ok [INFO] [stdout] test core::tests::core_tests::test_response_context_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_default_false ... ok [INFO] [stdout] test filters::tests::filter_tests::test_default_true ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_with_zero_timeout ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_header_filter_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_logging_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_header_filter ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_route_pre_filter_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_with_security_chain_config_error ... ok [INFO] [stdout] test core::tests::core_tests::test_route_clone ... ok [INFO] [stdout] test core::tests::core_tests::test_response_context_with_data ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_concurrent_security_provider_access ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_with_very_large_timeout ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_security_post_auth_failure ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_error_display ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_add_request_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_add_response_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_config_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_config_serialization ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_multiple_global_filters ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_security_pre_auth_failure ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_path_rewrite_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_timeout_filter_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_path_rewrite_filter_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_invalid_config_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_logging_filter_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_unknown_filter_type ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_unknown_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_create_timeout_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_filter_factory_invalid_config ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_empty_config ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_invalid_header_values ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_timeout_from_context ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_invalid_header_names ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_post_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_empty_configs ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_remove_request_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_remove_nonexistent_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_remove_response_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_header_filter_replace_existing_request_header ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_config_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_config_serialization ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_different_log_levels ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_global_post_filter_failure ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_empty_body ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_error_level ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_body_binary ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_body_small ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_body_truncated ... ok [INFO] [stdout] test core::tests::core_tests::test_proxy_core_process_request_custom_target ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_body_empty ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_headers_empty ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_invalid_log_level ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_info_level ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_large_body ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_post_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_post_filter_response_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_post_filter_response_body ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_format_headers ... ok [INFO] [stdout] test filters::tests::filter_tests::test_register_custom_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_tee_body_empty ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_warn_level ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter_no_match ... ok [INFO] [stdout] test filters::tests::filter_tests::test_register_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_trace_level ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter_disabled_request ... ok [INFO] [stdout] test filters::tests::filter_tests::test_tee_body_streaming ... ok [INFO] [stdout] test filters::tests::filter_tests::test_tee_body_error_stream ... ok [INFO] [stdout] test filters::tests::filter_tests::test_logging_filter_post_filter_large_response_body ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_config_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_config_serialization ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter_post_filter_enabled ... ok [INFO] [stdout] test filters::tests::filter_tests::test_multiple_filters_chaining ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter_complex_pattern ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter ... ok [INFO] [stdout] test filters::tests::filter_tests::test_tee_body_exact_limit ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_default ... ok [INFO] [stdout] test filters::tests::filter_tests::test_path_rewrite_filter_invalid_regex ... ok [INFO] [stderr] [2025-09-10T17:20:07.732Z INFO foxy::logging] Logging initialized at level: INFO [INFO] [stderr] [2025-09-10T17:20:07.732Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_very_small_timeout ... ok [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_foxy_loader_static_method ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_large_timeout ... ok [INFO] [stderr] [2025-09-10T17:20:07.734Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_zero_timeout ... ok [INFO] [stderr] [2025-09-10T17:20:07.734Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_foxy_start_error_mapping ... ok [INFO] [stderr] [2025-09-10T17:20:07.734Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_default_config ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_large_timeout_comprehensive ... ok [INFO] [stdout] test filters::tests::filter_tests::test_timeout_filter_overwrite_existing_timeout ... ok [INFO] [stderr] [2025-09-10T17:20:07.735Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.737Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.738Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.734Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.737Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.740Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_foxy_start_method_exists ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_missing_logging_config ... ok [INFO] [stderr] [2025-09-10T17:20:07.822Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stderr] [2025-09-10T17:20:07.733Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_with_invalid_config_file ... ok [INFO] [stderr] [2025-09-10T17:20:07.852Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_foxy_core_access ... ok [INFO] [stderr] [2025-09-10T17:20:07.860Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_opentelemetry_feature_disabled ... ok [INFO] [stdout] test loader::tests::loader_tests::test_foxy_clone ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_error_display ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_with_custom_env_prefix ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_error_from_io_error ... ok [INFO] [stdout] test loader::tests::loader_tests::test_foxy_config_access ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_new ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_error_from_proxy_error ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_error_other ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_config ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_default ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_malformed_logging_config ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_invalid_rust_log_level ... ok [INFO] [stderr] [2025-09-10T17:20:07.930Z ERROR foxy::filters] [Filter] filter error: Unknown filter type: nonexistent_filter [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_filter ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_config_file_path ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_env_prefix ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_env_vars ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_layered_config ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_invalid_logging_config_level ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_with_invalid_global_filter ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_rust_log_env_precedence ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_multiple_filters ... ok [INFO] [stdout] test logging::tests::logging_tests::test_add_fields_to_logger_empty_fields ... ok [INFO] [stdout] test logging::tests::logging_tests::test_add_fields_to_logger ... ok [INFO] [stdout] test logging::tests::logging_tests::test_add_fields_to_logger_many_fields ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_partial_logging_config ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_with_env_vars ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_provider ... ok [INFO] [stdout] test logging::tests::logging_tests::test_add_fields_to_logger_single_field ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_provider_existing_config ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_rust_log_env_not_set ... ok [INFO] [stdout] test logging::tests::logging_tests::test_generate_trace_id ... ok [INFO] [stderr] [2025-09-10T17:20:07.949Z INFO foxy::loader] [Loader] Added global filter: logging [INFO] [stdout] test logging::tests::logging_tests::test_generate_trace_id_format ... ok [INFO] [stdout] test logging::tests::logging_tests::test_atomic_operations_thread_safety ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_with_static_fields ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_with_config_different_levels ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_with_special_character_static_fields ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_with_global_filters ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_terminal ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_with_config_multiple_calls ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_with_config_structured_logging ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_with_large_static_fields ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_debug_non_structured ... ok [INFO] [stdout] test logging::tests::logging_tests::test_is_structured_logging ... ok [INFO] [stderr] [2025-09-10T17:20:07.955Z ERROR foxy::logging] TestContext: Test error message [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_json_with_all_levels ... ok [INFO] [stderr] [2025-09-10T17:20:07.956Z ERROR foxy::logging] TestContext: Error 404: Not found [INFO] [stdout] test logging::tests::logging_tests::test_log_debug_structured ... ok [INFO] [stderr] [2025-09-10T17:20:07.956Z INFO foxy::logging] TestContext: Test info message [INFO] [stdout] test logging::tests::logging_tests::test_log_error_non_structured ... ok [INFO] [stderr] [2025-09-10T17:20:07.957Z INFO foxy::logging::middleware] [timing] DELETE /api/resource/123 -> 500 | total=0ms upstream=0ms internal=0ms (trace_id: test-trace-789) [INFO] [stdout] test logging::tests::logging_tests::test_log_error_return_value_preservation ... ok [INFO] [stderr] [2025-09-10T17:20:07.957Z INFO foxy::logging::middleware] [timing] GET /test -> 200 | total=0ms upstream=50ms internal=0ms (trace_id: test-trace-123) [INFO] [stdout] test logging::tests::logging_tests::test_log_error_structured ... ok [INFO] [stderr] [2025-09-10T17:20:07.963Z INFO foxy::loader] [Loader] Foxy starting up [INFO] [stdout] test logging::tests::logging_tests::test_log_format_debug ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_format_equality ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_info_non_structured ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_format_clone ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_info_structured ... ok [INFO] [stdout] {"@timestamp":"2025-09-10T17:20:07.957Z","message":"Response completed","level":"INFO","internal_ms":0,"upstream_ms":0,"elapsed_ms":0,"status":404,"path":"/api/users","method":"POST","trace_id":"test-trace-456"} [INFO] [stdout] test logging::tests::logging_tests::test_log_response_no_upstream_duration ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_json ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_response_structured ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_response_basic ... ok [INFO] [stdout] test logging::tests::logging_tests::test_empty_static_fields_handling ... ok [INFO] [stdout] test logging::tests::logging_tests::test_generate_trace_id_uniqueness ... ok [INFO] [stdout] test loader::tests::loader_tests::test_foxy_core_add_global_filter ... ok [INFO] [stdout] test logging::tests::logging_tests::test_concurrent_logger_initialization ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_config_clone ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_config_debug ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_config_default ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_config_custom ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_guard_drop_behavior ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_debug_clone ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_default ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_default_functions_via_serde ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_global_logger_all_log_levels ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_empty_deserialization ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_partial_deserialization ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_serialization ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_with_config_file ... ok [INFO] [stdout] test loader::tests::loader_tests::test_foxy_debug ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logger_config_with_all_combinations ... ok [INFO] [stderr] [2025-09-10T17:20:08.113Z INFO foxy::logging::middleware] Request received: GET /test/path from 192.168.1.100:8080 (trace_id: 2477ef54-38d1-4ef0-beb6-98a8b883a686) [INFO] [stdout] test logging::tests::logging_tests::test_logging_middleware_new ... ok [INFO] [stderr] [2025-09-10T17:20:08.114Z INFO foxy::logging::middleware] Request received: GET /test from unknown (trace_id: f806c834-7196-4e8a-a9ae-a9c949a975f4) [INFO] [stdout] test logging::tests::logging_tests::test_process_request_basic ... ok [INFO] [stdout] test loader::tests::loader_tests::test_loader_build_without_global_filters ... ok [INFO] [stderr] [2025-09-10T17:20:08.115Z INFO foxy::logging::middleware] Request received: GET /test/path from unknown (trace_id: 4fc6ae9c-aa2d-4199-8388-f9fc164f0abc) [INFO] [stdout] test logging::tests::logging_tests::test_process_request_invalid_trace_header ... ok [INFO] [stdout] test logging::tests::logging_tests::test_process_request_no_remote_addr ... ok [INFO] [stderr] [2025-09-10T17:20:08.116Z INFO foxy::logging::middleware] Request received: POST /api/test from unknown (trace_id: 2a6b0ca7-0945-472b-9702-b1fec02d5e52) [INFO] [stdout] test logging::tests::logging_tests::test_process_request_no_user_agent ... ok [INFO] [stderr] [2025-09-10T17:20:08.117Z INFO foxy::logging::middleware] Request received: GET /test/path from unknown (trace_id: existing-trace-123) [INFO] [stdout] test logging::tests::logging_tests::test_process_request_with_trace_propagation ... ok [INFO] [stderr] [2025-09-10T17:20:08.117Z INFO foxy::logging::middleware] Request received: GET /test/path from unknown (trace_id: 485a5302-f20f-4322-bb1d-a5d281cb1a12) [INFO] [stdout] test logging::tests::logging_tests::test_process_request_without_trace_propagation ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_clone ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_creation ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_debug ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_elapsed_ms ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_elapsed_ms_future_time ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_with_extreme_values ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_with_unicode_and_special_chars ... ok [INFO] [stdout] test logging::tests::logging_tests::test_request_info_elapsed_ms_edge_cases ... ok [INFO] [stdout] test logging::tests::logging_tests::test_static_fields_memory_management ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_boolean_fields ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_comprehensive ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_edge_cases ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_format_mapping ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_level_case_insensitive ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_level_defaults ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_level_mapping ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_must_use_annotation ... ok [INFO] [stdout] test logging::tests::logging_tests::test_slog_level_to_log_level_conversion ... ok [INFO] [stdout] test logging::tests::logging_tests::test_traced_response_future_error ... ok [INFO] [stdout] test logging::tests::logging_tests::test_traced_response_future_invalid_header_name ... ok [INFO] [stdout] test logging::tests::logging_tests::test_to_logger_config_static_fields ... ok [INFO] [stdout] test logging::tests::logging_tests::test_traced_response_future_invalid_header_value ... ok [INFO] [stdout] test logging::tests::logging_tests::test_traced_response_future_success_with_trace_id ... ok [INFO] [stdout] test logging::tests::logging_tests::test_traced_response_future_success_without_trace_id ... ok [INFO] [stdout] test router::tests::router_tests::test_header_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_header_predicate_contains_match ... ok [INFO] [stdout] test router::tests::router_tests::test_header_predicate_invalid_utf8 ... ok [INFO] [stdout] test router::tests::router_tests::test_header_predicate_multiple_headers ... ok [INFO] [stdout] test router::tests::router_tests::test_method_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_method_predicate_all_methods ... ok [INFO] [stdout] test router::tests::router_tests::test_method_predicate_empty_methods ... ok [INFO] [stderr] [2025-09-10T17:20:08.170Z ERROR foxy::router] [Router] routing error: Invalid path predicate config: missing field `pattern` [INFO] [stdout] test logging::tests::logging_tests::test_generate_trace_id_concurrent ... ok [INFO] [stderr] [2025-09-10T17:20:08.171Z ERROR foxy::router] [Router] routing error: Unknown predicate type: unknown [INFO] [stdout] test router::tests::router_tests::test_path_predicate_complex_patterns ... ok [INFO] [stderr] [2025-09-10T17:20:08.171Z WARN foxy::router] [Router] routing error: No route matched the request: GET /api/users [INFO] [stdout] test router::tests::router_tests::test_path_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_create_header_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_create_method_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_create_query_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_path_predicate_invalid_regex ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_invalid_config ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_create_path_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_router_add_route ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_factory_unknown_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_router_new ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_router_route_no_match ... ok [INFO] [stdout] test router::tests::router_tests::test_predicate_router_remove_route ... ok [INFO] [stdout] test router::tests::router_tests::test_query_predicate ... ok [INFO] [stdout] test router::tests::router_tests::test_query_predicate_empty_params ... ok [INFO] [stdout] test router::tests::router_tests::test_query_predicate_contains_match ... ok [INFO] [stderr] [2025-09-10T17:20:08.181Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test router::tests::router_tests::test_query_predicate_multiple_params ... ok [INFO] [stderr] [2025-09-10T17:20:08.181Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: Basic: security error: Missing authorization header [INFO] [stdout] test router::tests::router_tests::test_register_predicate ... ok [INFO] [stderr] [2025-09-10T17:20:08.181Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_bypass ... ok [INFO] [stderr] [2025-09-10T17:20:08.181Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: Basic: security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_case_sensitive_scheme ... ok [INFO] [stderr] [2025-09-10T17:20:08.184Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_empty_credentials ... ok [INFO] [stderr] [2025-09-10T17:20:08.184Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_failure_invalid_credentials ... ok [INFO] [stderr] [2025-09-10T17:20:08.184Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: Basic: security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_invalid_auth_scheme ... ok [INFO] [stderr] [2025-09-10T17:20:08.185Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid authorization scheme: expected 'Basic', got 'Bearer' [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_invalid_base64 ... ok [INFO] [stderr] [2025-09-10T17:20:08.186Z WARN foxy::security::basic] [BasicAuthProvider] security error: Failed to base64 decode credentials: Invalid symbol 95, offset 7. [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_invalid_credential_format ... ok [INFO] [stderr] [2025-09-10T17:20:08.187Z ERROR foxy::security::basic] [BasicAuthProvider] security error: Invalid credential format: invalid_format [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_invalid_glob_pattern ... ok [INFO] [stderr] [2025-09-10T17:20:08.190Z ERROR foxy::security::basic] [BasicAuthProvider] security error: Invalid glob pattern in bypass rule: error parsing glob '[invalid_glob': unclosed character class; missing ']' [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_malformed_credentials ... ok [INFO] [stderr] [2025-09-10T17:20:08.190Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credential format [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_missing_auth_header ... ok [INFO] [stderr] [2025-09-10T17:20:08.191Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_multiple_bypass_rules ... ok [INFO] [stderr] [2025-09-10T17:20:08.196Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_basic_auth_provider_success ... ok [INFO] [stderr] [2025-09-10T17:20:08.198Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_timing_attack_mitigation ... ok [INFO] [stderr] [2025-09-10T17:20:08.199Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stdout] test security::tests::security_tests::test_basic_auth_timing_attack_protection ... ok [INFO] [stderr] [2025-09-10T17:20:08.200Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.200Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.200Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.200Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.201Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.202Z WARN foxy::security::basic] [BasicAuthProvider] security error: Invalid basic auth credentials [INFO] [stderr] [2025-09-10T17:20:08.259Z WARN foxy::logging] TestContext: Test warning message [INFO] [stderr] [2025-09-10T17:20:08.282Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid JWT header: JSON error: unknown variant `none`, expected one of `HS256`, `HS384`, `HS512`, `ES256`, `ES384`, `RS256`, `RS384`, `RS512`, `PS256`, `PS384`, `PS512`, `EdDSA` at line 1 column 13 [INFO] [stdout] test logging::tests::logging_tests::test_log_warning_structured ... FAILED [INFO] [stdout] test logging::tests::logging_tests::test_log_warning_non_structured ... ok [INFO] [stdout] test security::tests::security_tests::test_clean_jwk_oct_key_type ... ok [INFO] [stdout] test security::tests::security_tests::test_algorithm_downgrade_attack_prevention ... ok [INFO] [stdout] test security::tests::security_tests::test_dependency_security_issues ... ok [INFO] [stdout] test security::tests::security_tests::test_configuration_security_issues ... ok [INFO] [stdout] test security::tests::security_tests::test_information_disclosure_in_errors ... ok [INFO] [stdout] test security::tests::security_tests::test_authorization_header_with_extra_whitespace ... ok [INFO] [stderr] [2025-09-10T17:20:08.374Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid authorization scheme: expected 'Bearer', got 'Bearer' [INFO] [stdout] test security::tests::security_tests::test_integration_full_oidc_flow_with_bypass ... ok [INFO] [stderr] [2025-09-10T17:20:08.405Z WARN foxy::security::oidc] [OidcProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_jwk_to_decoding_key_ec_success ... ok [INFO] [stdout] test security::tests::security_tests::test_jwk_to_decoding_key_okp_success ... ok [INFO] [stderr] [2025-09-10T17:20:08.523Z WARN foxy::security::oidc] [OidcProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_jwk_to_decoding_key_octet_key_success ... ok [INFO] [stdout] test security::tests::security_tests::test_integration_full_oidc_flow_auth_failure ... ok [INFO] [stdout] test security::tests::security_tests::test_clean_jwk_okp_key_type ... ok [INFO] [stderr] [2025-09-10T17:20:08.540Z WARN foxy::security::oidc] [OidcProvider] Unknown key type in JWKS: unknown_type [INFO] [stderr] [2025-09-10T17:20:08.540Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response. Standard error: unknown variant `UNKNOWN256`, expected one of `HS256`, `HS384`, `HS512`, `ES256`, `ES384`, `RS256`, `RS384`, `RS512`, `PS256`, `PS384`, `PS512`, `EdDSA`, `RSA1_5`, `RSA-OAEP`, `RSA-OAEP-256`. Fallback error: No valid keys found in JWKS after cleaning. JSON structure: { [INFO] [stderr] "keys": [ [INFO] [stderr] { [INFO] [stderr] "alg": "UNKNOWN256", [INFO] [stderr] "kid": "unknown-key-1", [INFO] [stderr] "kty": "unknown_type", [INFO] [stderr] "use": "sig" [INFO] [stderr] } [INFO] [stderr] ] [INFO] [stderr] } [INFO] [stdout] test security::tests::security_tests::test_clean_jwk_unknown_key_type ... ok [INFO] [stderr] [2025-09-10T17:20:08.571Z WARN foxy::security::oidc] [OidcProvider] Unknown key type in JWKS: unknown_type [INFO] [stdout] test security::tests::security_tests::test_jwks_fallback_parsing_empty_keys ... ok [INFO] [stderr] [2025-09-10T17:20:08.571Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response. Standard error: data did not match any variant of untagged enum AlgorithmParameters. Fallback error: No valid keys found in JWKS after cleaning. JSON structure: { [INFO] [stderr] "keys": [ [INFO] [stderr] { [INFO] [stderr] "kid": "test-key-1", [INFO] [stderr] "kty": "unknown_type" [INFO] [stderr] } [INFO] [stderr] ] [INFO] [stderr] } [INFO] [stdout] test security::tests::security_tests::test_jwks_fallback_parsing_success ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_cache_fresh ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_cognito_format ... ok [INFO] [stdout] test security::tests::security_tests::test_jwk_to_decoding_key_rsa_success ... ok [INFO] [stderr] [2025-09-10T17:20:08.617Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to connect to JWKS endpoint: error sending request [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_different_content_types ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_connection_error ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_fallback_parsing ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_http_error ... ok [INFO] [stderr] [2025-09-10T17:20:08.676Z ERROR foxy::security::oidc] [OidcProvider] security error: JWKS endpoint returned error: HTTP status server error (500 Internal Server Error) for url (http://127.0.0.1:35693/jwks) [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_malformed_key ... ok [INFO] [stderr] [2025-09-10T17:20:08.694Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response. Standard error: data did not match any variant of untagged enum AlgorithmParameters. Fallback error: Failed to parse cleaned JWKS: data did not match any variant of untagged enum AlgorithmParameters. JSON structure: { [INFO] [stderr] "keys": [ [INFO] [stderr] { [INFO] [stderr] "alg": "RS256", [INFO] [stderr] "kid": "test-key-1", [INFO] [stderr] "kty": "RSA", [INFO] [stderr] "use": "sig" [INFO] [stderr] }, [INFO] [stderr] { [INFO] [stderr] "alg": "RS256", [INFO] [stderr] "e": "AQAB", [INFO] [stderr] "kid": "test-key-2", [INFO] [stderr] "kty": "RSA", [INFO] [stderr] "n": "test-modulus", [INFO] [stderr] "use": "sig" [INFO] [stderr] } [INFO] [stderr] ] [INFO] [stderr] } [INFO] [stderr] [2025-09-10T17:20:08.711Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response. Standard error: missing field `keys`. Fallback error: Missing or invalid 'keys' field. JSON structure: { [INFO] [stderr] "metadata": "some data", [INFO] [stderr] "other_field": "value" [INFO] [stderr] } [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_missing_keys_field ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_empty_keys ... ok [INFO] [stderr] [2025-09-10T17:20:08.770Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response. Standard error: data did not match any variant of untagged enum AlgorithmParameters. Fallback error: Failed to parse cleaned JWKS: data did not match any variant of untagged enum AlgorithmParameters. JSON structure: { [INFO] [stderr] "keys": [ [INFO] [stderr] { [INFO] [stderr] "alg": "RS256", [INFO] [stderr] "e": "AQAB", [INFO] [stderr] "kid": "valid-key-1", [INFO] [stderr] "kty": "RSA", [INFO] [stderr] "n": "test-modulus", [INFO] [stderr] "use": "sig" [INFO] [stderr] }, [INFO] [stderr] { [INFO] [stderr] "alg": "RS256", [INFO] [stderr] "kid": "invalid-key-1", [INFO] [stderr] "kty": "RSA", [INFO] [stderr] "use": "sig" [INFO] [stderr] }, [INFO] [stderr] { [INFO] [stderr] "alg": "ES256", [INFO] [stderr] "crv": "P-256", [INFO] [stderr] "kid": "valid-key-2", [INFO] [stderr] "kty": "EC", [INFO] [stderr] "use": "sig", [INFO] [stderr] "x": "f83OJ3D2xF1Bg8vub9tLe1gHMzV76e8Tus9uPHvRVEU", [INFO] [stderr] "y": "x_FEzRu9m36HLN_tue659LNpXW6pCyStikYjKIWI5a0" [INFO] [stderr] } [INFO] [stderr] ] [INFO] [stderr] } [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_success ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_partial_key_failure ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_invalid_json ... ok [INFO] [stderr] [2025-09-10T17:20:08.794Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response as JSON: expected value at line 1 column 1. Response body: invalid json [INFO] [stdout] test security::tests::security_tests::test_jwks_response_body_read_failure ... ok [INFO] [stderr] [2025-09-10T17:20:08.823Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to parse JWKS response as JSON: expected value at line 1 column 1. Response body: valid json [INFO] [stderr] [2025-09-10T17:20:08.827Z WARN foxy::security::oidc] [OidcProvider] security error: HMAC algorithms require shared secret configuration [INFO] [stdout] test security::tests::security_tests::test_jwt_algorithm_confusion_attack ... ok [INFO] [stdout] test security::tests::security_tests::test_missing_security_headers ... ok [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_with_extra_fields ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_config_deserialization ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_config_empty_bypass ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_config_minimal ... ok [INFO] [stderr] [2025-09-10T17:20:08.847Z ERROR foxy::security::oidc] [OidcProvider] security error: Invalid RSA key: Base64 error: Invalid input length: 341 [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_multiple_real_keys ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_bypass_rules_compilation ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_complex_bypass_rules ... ok [INFO] [stderr] [2025-09-10T17:20:08.899Z ERROR foxy::security::oidc] [OidcProvider] security error: Invalid RSA key: Base64 error: Invalid input length: 341 [INFO] [stdout] test security::tests::security_tests::test_jwks_refresh_real_world_format ... ok [INFO] [stdout] test security::tests::security_tests::test_jwt_algorithm_confusion_with_shared_secret_fallback ... ok [INFO] [stdout] test security::tests::security_tests::test_multiple_bypass_rules_overlapping ... ok [INFO] [stderr] [2025-09-10T17:20:08.948Z ERROR foxy::security::oidc] [OidcProvider] security error: Invalid glob pattern in bypass rule: error parsing glob '[invalid-glob': unclosed character class; missing ']' [INFO] [stderr] [2025-09-10T17:20:08.951Z ERROR foxy::security::oidc] [OidcProvider] security error: JWKS endpoint returned error: HTTP status client error (404 Not Found) for url (http://127.0.0.1:35693/jwks) [INFO] [stderr] [2025-09-10T17:20:08.953Z ERROR foxy::security::oidc] [OidcProvider] security error: JWKS endpoint returned error: HTTP status client error (404 Not Found) for url (http://127.0.0.1:43561/jwks) [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_glob_set_build_failure ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_http_error ... ok [INFO] [stderr] [2025-09-10T17:20:08.958Z ERROR foxy::security::oidc] [OidcProvider] security error: Invalid glob pattern in bypass rule: error parsing glob '[invalid-glob': unclosed character class; missing ']' [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_invalid_json ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_invalid_bypass_glob ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_with_context_all_levels_structured ... FAILED [INFO] [stderr] [2025-09-10T17:20:08.965Z ERROR foxy::logging] TestContext: Test message [INFO] [stderr] [2025-09-10T17:20:08.965Z WARN foxy::logging] TestContext: Test message [INFO] [stderr] [2025-09-10T17:20:08.965Z INFO foxy::logging] TestContext: Test message [INFO] [stdout] test logging::tests::logging_tests::test_log_with_context_all_levels_non_structured ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_with_context_empty_fields ... FAILED [INFO] [stdout] test logging::tests::logging_tests::test_log_with_context_special_field_values ... FAILED [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_invalid_url ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_extract_bearer_token_ensure_token_isnt_altered ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_fallback_instant_calculation ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_issuer_uri_no_normalization ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_http_client_build_failure ... ok [INFO] [stderr] [2025-09-10T17:20:09.071Z WARN foxy::security::oidc] [OidcProvider] security error: Empty bearer token [INFO] [stderr] [2025-09-10T17:20:09.072Z ERROR foxy::security::oidc] [OidcProvider] security error: Invalid glob pattern in bypass rule: error parsing glob '[invalid-glob': unclosed character class; missing ']' [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_pre_empty_bearer_token ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_invalid_bypass_rule ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_functions_with_different_types ... ok [INFO] [stderr] [2025-09-10T17:20:09.081Z ERROR foxy::logging] context: 42 [INFO] [stderr] [2025-09-10T17:20:09.081Z ERROR foxy::logging] context: string error [INFO] [stderr] [2025-09-10T17:20:09.081Z ERROR foxy::logging] context: formatted error [INFO] [stderr] [2025-09-10T17:20:09.081Z WARN foxy::logging] context: 3.14 [INFO] [stderr] [2025-09-10T17:20:09.081Z WARN foxy::logging] context: true [INFO] [stderr] [2025-09-10T17:20:09.081Z INFO foxy::logging] context: info message [INFO] [stdout] test security::tests::security_tests::test_proxy_safeguard_bypass_techniques ... ok [INFO] [stdout] test security::tests::security_tests::test_register_security_provider ... ok [INFO] [stderr] [2025-09-10T17:20:09.087Z ERROR foxy::logging] : error message [INFO] [stderr] [2025-09-10T17:20:09.087Z WARN foxy::logging] : warning message [INFO] [stderr] [2025-09-10T17:20:09.087Z INFO foxy::logging] : info message [INFO] [stderr] [2025-09-10T17:20:09.087Z ERROR foxy::logging] Context with spaces: Error with [INFO] [stdout] test security::tests::security_tests::test_request_smuggling_header_injection ... ok [INFO] [stderr] newlines [INFO] [stdout] test logging::tests::logging_tests::test_logging_functions_with_empty_context ... ok [INFO] [stderr] [2025-09-10T17:20:09.088Z WARN foxy::logging] Context-with-dashes: Warning with tabs [INFO] [stderr] [2025-09-10T17:20:09.088Z INFO foxy::logging] Context.with.dots: Info with 'single quotes' [INFO] [stderr] [2025-09-10T17:20:09.089Z WARN foxy::server] [Server] Request contains both Content-Length and Transfer-Encoding headers - removing Content-Length to prevent request smuggling [INFO] [stderr] [2025-09-10T17:20:09.089Z WARN foxy::server] [Server] security error: Multiple Host headers detected - potential request smuggling attack [INFO] [stderr] [2025-09-10T17:20:09.089Z WARN foxy::server] [Server] security error: Multiple Content-Length values detected - potential request smuggling attack [INFO] [stderr] [2025-09-10T17:20:09.090Z WARN foxy::server] [Server] Suspicious Transfer-Encoding header value: chunked, gzip - normalizing to 'chunked' [INFO] [stdout] test security::tests::security_tests::test_request_smuggling_header_injection_mitigation ... ok [INFO] [stdout] test security::tests::security_tests::test_route_rule_config_deserialization ... ok [INFO] [stdout] test logging::tests::logging_tests::test_logging_functions_with_special_characters ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_success_minimal_config ... ok [INFO] [stderr] [2025-09-10T17:20:09.094Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stderr] [2025-09-10T17:20:09.094Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stderr] [2025-09-10T17:20:09.095Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_route_rule_exact_path_match ... ok [INFO] [stderr] [2025-09-10T17:20:09.095Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_route_rule_complex_glob_patterns ... ok [INFO] [stderr] [2025-09-10T17:20:09.105Z WARN foxy::security::oidc] [OidcProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_route_rule_matches_edge_cases ... ok [INFO] [stderr] [2025-09-10T17:20:09.106Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid authorization scheme: expected 'Bearer', got 'Basic' [INFO] [stdout] test security::tests::security_tests::test_route_rule_matches_empty_methods ... ok [INFO] [stdout] test security::tests::security_tests::test_route_rule_matches ... ok [INFO] [stderr] [2025-09-10T17:20:09.108Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: 'value [INFO] [stdout] test security::tests::security_tests::test_route_rule_wildcard_method ... ok [INFO] [stderr] Injected-Header: malicious', patterns: [CRLF injection] [INFO] [stdout] test security::tests::security_tests::test_route_rule_wildcard_methods ... ok [INFO] [stderr] [2025-09-10T17:20:09.108Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: ''; DROP TABLE users; --', patterns: [potential SQL injection, potential command injection] [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_pre_missing_auth_header ... ok [INFO] [stderr] [2025-09-10T17:20:09.108Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: '', patterns: [potential XSS] [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_pre_invalid_auth_scheme ... ok [INFO] [stdout] test security::tests::security_tests::test_security_chain_apply_post ... ok [INFO] [stdout] test security::tests::security_tests::test_security_chain_from_configs_empty ... ok [INFO] [stderr] [2025-09-10T17:20:09.108Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: '../../../etc/passwd', patterns: [path traversal] [INFO] [stdout] test security::tests::security_tests::test_router_input_validation_attacks ... ok [INFO] [stderr] [2025-09-10T17:20:09.110Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: 'value [INFO] [stdout] test security::tests::security_tests::test_security_chain_both_stage_provider ... ok [INFO] [stderr] Injected-Header: malicious', patterns: [CRLF injection] [INFO] [stdout] test security::tests::security_tests::test_security_chain_from_configs_unknown_provider ... ok [INFO] [stderr] [2025-09-10T17:20:09.110Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: '', patterns: [potential XSS] [INFO] [stdout] test security::tests::security_tests::test_router_input_validation_mitigation ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: '../../../etc/passwd', patterns: [path traversal] [INFO] [stdout] test security::tests::security_tests::test_security_chain_with_oidc_bypass ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: ''; DROP TABLE users; --', patterns: [potential SQL injection, potential command injection] [INFO] [stdout] test security::tests::security_tests::test_security_chain_multiple_providers ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: 'test; rm -rf /', patterns: [potential command injection] [INFO] [stdout] test security::tests::security_tests::test_route_rule_specific_methods ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z WARN foxy::router::predicates] [QueryPredicate] Suspicious query parameter detected - key: 'param', value: 'testmalicious', patterns: [null byte injection] [INFO] [stdout] test security::tests::security_tests::test_security_chain_with_providers ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z WARN foxy::router::predicates] [QueryPredicate] Query string too long: 10006 bytes (max: 8192) [INFO] [stdout] test security::tests::security_tests::test_security_provider_default_pre ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: mock-provider: security error: Mock authentication failure [INFO] [stdout] test security::tests::security_tests::test_security_stage_is_post ... ok [INFO] [stderr] [2025-09-10T17:20:09.111Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: mock-oidc-with-bypass: security error: OIDC validation failed [INFO] [stdout] test security::tests::security_tests::test_security_chain_mixed_providers ... ok [INFO] [stderr] [2025-09-10T17:20:09.112Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test security::tests::security_tests::test_security_stage_is_pre ... ok [INFO] [stderr] [2025-09-10T17:20:09.112Z WARN foxy::security::basic] [BasicAuthProvider] security error: Missing authorization header [INFO] [stdout] test logging::tests::logging_tests::test_logging_config_with_static_fields ... ok [INFO] [stderr] [2025-09-10T17:20:09.112Z ERROR foxy::security] [SecurityChain] Security pre-auth failed: security error: mock-provider: security error: Mock authentication failure [INFO] [stderr] [2025-09-10T17:20:09.117Z ERROR foxy::security] [SecurityChain] Security post-auth failed: security error: mock-post-provider: security error: Mock post-auth failure [INFO] [stdout] test logging::tests::logging_tests::test_log_trace_structured ... ok [INFO] [stdout] test logging::tests::logging_tests::test_init_with_config_env_logger ... ok [INFO] [stdout] test logging::tests::logging_tests::test_log_trace_non_structured ... ok [INFO] [stdout] test security::tests::security_tests::test_security_chain_apply_post_failure ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_success ... ok [INFO] [stderr] [2025-09-10T17:20:09.196Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid audience: expected 'test-audience' [INFO] [stderr] [2025-09-10T17:20:09.197Z WARN foxy::security::oidc] [OidcProvider] security error: JWT validation failed: ExpiredSignature [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_with_direct_jwks_uri ... ok [INFO] [stdout] test security::tests::security_tests::test_security_provider_trait_implementation ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_audience_array_failure ... ok [INFO] [stderr] [2025-09-10T17:20:09.234Z WARN foxy::security::oidc] [OidcProvider] security error: JWT validation failed: InvalidAudience [INFO] [stderr] [2025-09-10T17:20:09.235Z WARN foxy::security::oidc] [OidcProvider] security error: Missing issuer claim [INFO] [stdout] test security::tests::security_tests::test_validate_claims_expired_token ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_claims_long_expiration ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_with_audience_and_secret ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_missing_issuer ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_claims_wrong_audience ... ok [INFO] [stdout] test security::tests::security_tests::test_security_provider_default_post ... ok [INFO] [stderr] [2025-09-10T17:20:09.247Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid audience: expected 'test-audience' [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_invalid_audience_type ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_audience_array_success ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_no_audience_configured ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_is_bypassed ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_pre_bypass ... ok [INFO] [stdout] test security::tests::security_tests::test_oidc_provider_discover_success_with_well_known_suffix ... ok [INFO] [stderr] [2025-09-10T17:20:09.301Z WARN foxy::security::oidc] [OidcProvider] security error: Token expired at 1757521209, current time is 1757524809 [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_expired_token ... ok [INFO] [stderr] [2025-09-10T17:20:09.315Z WARN foxy::security::oidc] [OidcProvider] security error: JWT validation failed: InvalidIssuer [INFO] [stdout] test security::tests::security_tests::test_validate_token_hs512_algorithm ... ok [INFO] [stderr] [2025-09-10T17:20:09.321Z WARN foxy::security::oidc] [OidcProvider] security error: HMAC algorithms require shared secret configuration [INFO] [stderr] [2025-09-10T17:20:09.329Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid issuer: expected 'https://auth.example.com', got 'https://wrong-issuer.com' [INFO] [stderr] [2025-09-10T17:20:09.343Z WARN foxy::security::oidc] [OidcProvider] security error: JWT validation failed: InvalidSignature [INFO] [stdout] test security::tests::security_tests::test_validate_claims_wrong_issuer ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_success ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_hmac_no_shared_secret ... ok [INFO] [stderr] [2025-09-10T17:20:09.380Z WARN foxy::security::oidc] [OidcProvider] security error: Asymmetric algorithms require 'kid' (key ID) header [INFO] [stderr] [2025-09-10T17:20:09.413Z WARN foxy::security::oidc] [OidcProvider] security error: Key ID missing-key not found in JWKS [INFO] [stdout] test security::tests::security_tests::test_validate_std_claims_wrong_issuer ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_hmac_success ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_hmac_wrong_secret ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_asymmetric_algorithm_no_shared_secret ... ok [INFO] [stderr] [2025-09-10T17:20:09.467Z WARN foxy::security::oidc] [OidcProvider] security error: HMAC algorithm with kid 'non-existent-key' not found in JWKS - potential algorithm confusion attack [INFO] [stderr] [2025-09-10T17:20:09.481Z WARN foxy::security::oidc] [OidcProvider] security error: Asymmetric algorithms require 'kid' (key ID) header [INFO] [stdout] test security::tests::security_tests::test_validate_token_key_not_found ... ok [INFO] [stdout] test server::health::tests::test_health_server_debug ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_different_algorithms ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_hmac_kid_not_found_in_jwks ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_missing_key_id ... ok [INFO] [stderr] [2025-09-10T17:20:09.520Z ERROR foxy::security::oidc] [OidcProvider] security error: Failed to connect to JWKS endpoint: error sending request [INFO] [stderr] [2025-09-10T17:20:09.530Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid JWT header: Base64 error: Invalid last symbol 100, offset 6. [INFO] [stdout] test server::health::tests::test_set_ready_multiple_times ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_no_jwks_available ... ok [INFO] [stdout] test server::health::tests::test_health_server_creation ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_invalid_header ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_no_headers ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_with_custom_target ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_with_query ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_response ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_proxy_response_different_status_codes ... ok [INFO] [stderr] [2025-09-10T17:20:09.557Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid JWT header: InvalidToken [INFO] [stderr] [2025-09-10T17:20:09.557Z WARN foxy::security::oidc] [OidcProvider] Token validation failed: security error: Invalid JWT header: InvalidToken [INFO] [stdout] test server::tests::server_tests::test_convert_proxy_response_empty_headers ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_proxy_response_success ... ok [INFO] [stderr] [2025-09-10T17:20:09.558Z WARN foxy::security::oidc] [OidcProvider] security error: HMAC algorithms require shared secret configuration [INFO] [stdout] test security::tests::security_tests::test_validate_token_invalid_authorization_header ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_proxy_response_with_large_headers ... ok [INFO] [stderr] [2025-09-10T17:20:09.562Z ERROR foxy::server] [Server] Connection error: test error [INFO] [stderr] [2025-09-10T17:20:09.581Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid JWT header: JSON error: unknown variant `NONE`, expected one of `HS256`, `HS384`, `HS512`, `ES256`, `ES384`, `RS256`, `RS384`, `RS512`, `PS256`, `PS384`, `PS512`, `EdDSA` at line 1 column 13 [INFO] [stderr] [2025-09-10T17:20:09.588Z WARN foxy::security::oidc] [OidcProvider] security error: Invalid JWT header: JSON error: unknown variant `none`, expected one of `HS256`, `HS384`, `HS512`, `ES256`, `ES384`, `RS256`, `RS384`, `RS512`, `PS256`, `PS384`, `PS512`, `EdDSA` at line 1 column 13 [INFO] [stderr] [2025-09-10T17:20:09.601Z WARN foxy::security::oidc] [OidcProvider] security error: Key ID missing-rsa-key not found in JWKS and algorithm RS256 requires asymmetric key [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_basic ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_root_path ... ok [INFO] [stdout] test server::tests::server_tests::test_convert_hyper_request_different_methods ... ok [INFO] [stdout] test server::tests::server_tests::test_handle_connection_result_graceful_close ... ok [INFO] [stdout] test server::tests::server_tests::test_handle_connection_result_error ... ok [INFO] [stdout] test server::tests::server_tests::test_proxy_error_variants ... ok [INFO] [stdout] test server::tests::server_tests::test_handle_request_basic ... ok [INFO] [stdout] test server::tests::server_tests::test_handle_connection_result_success ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_no_shared_secret_no_kid ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_clone_and_debug ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_default_functions ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_defaults ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_empty_json ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_serialization ... ok [INFO] [stdout] test server::tests::server_tests::test_server_config_with_defaults ... ok [INFO] [stdout] test server::health::tests::test_health_endpoint ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_none_algorithm ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_unsupported_algorithm ... ok [INFO] [stdout] test server::tests::server_tests::test_server_start_invalid_address ... ok [INFO] [stdout] test server::tests::server_tests::test_setup_signal_handlers_unix ... ok [INFO] [stdout] test server::tests::server_tests::test_unix_signal_handling ... ok [INFO] [stdout] test server::tests::server_tests::test_proxy_server_debug_implementation ... ok [INFO] [stdout] test security::tests::security_tests::test_validate_token_non_hmac_algorithm_with_missing_kid ... ok [INFO] [stdout] test server::tests::server_tests::test_proxy_server_new ... ok [INFO] [stderr] [2025-09-10T17:20:09.630Z INFO foxy::server] [Server] Shutting down; waiting for 0 connection(s) [INFO] [stderr] [2025-09-10T17:20:09.630Z INFO foxy::server] [Server] Signaling 0 connections to shut down [INFO] [stderr] [2025-09-10T17:20:09.630Z INFO foxy::server] [Server] All connections drained gracefully in 0.0s [INFO] [stderr] [2025-09-10T17:20:09.630Z INFO foxy::server] [Server] Shutdown complete [INFO] [stdout] test server::tests::server_tests::test_graceful_shutdown_empty_joinset ... ok [INFO] [stdout] test server::tests::server_tests::test_proxy_server_clone ... ok [INFO] [stdout] test server::tests::server_tests::test_server_start_method_exists ... ok [INFO] [stderr] [2025-09-10T17:20:09.659Z INFO foxy::server] [Server] Foxy proxy listening on http://127.0.0.1:0 [INFO] [stdout] test server::health::tests::test_unknown_endpoint ... ok [INFO] [stdout] test server::tests::server_tests::test_setup_listener ... ok [INFO] [stderr] [2025-09-10T17:20:09.684Z INFO foxy::server] [Server] Shutting down; waiting for 0 connection(s) [INFO] [stderr] [2025-09-10T17:20:09.684Z INFO foxy::server] [Server] Signaling 2 connections to shut down [INFO] [stderr] [2025-09-10T17:20:09.684Z INFO foxy::server] [Server] All connections drained gracefully in 0.0s [INFO] [stderr] [2025-09-10T17:20:09.684Z INFO foxy::server] [Server] Shutdown complete [INFO] [stdout] test server::tests::server_tests::test_graceful_shutdown_with_senders ... ok [INFO] [stdout] test server::health::tests::test_ready_endpoint_ready ... ok [INFO] [stdout] test server::tests::server_tests::test_setup_listener_invalid_address ... ok [INFO] [stdout] test server::health::tests::test_multiple_endpoints ... ok [INFO] [stderr] [2025-09-10T17:20:09.822Z WARN foxy::security::oidc] [OidcProvider] security error: HMAC algorithm with kid 'missing-key' not found in JWKS - potential algorithm confusion attack [INFO] [stdout] test security::tests::security_tests::test_validate_token_with_kid_fallback_to_shared_secret ... ok [INFO] [stdout] test server::health::tests::test_concurrent_requests ... ok [INFO] [stdout] test server::health::tests::test_ready_endpoint_not_ready ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- logging::tests::logging_tests::test_log_warning_structured stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'logging::tests::logging_tests::test_log_warning_structured' (387) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13: [INFO] [stdout] slog-scope: No logger set. Use `slog_scope::set_global_logger` or `slog_scope::scope`. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x64ea69f124b2 - std::backtrace_rs::backtrace::libunwind::trace::h52580dd202462214 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x64ea69f124b2 - std::backtrace_rs::backtrace::trace_unsynchronized::hc969519abce0f52b [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x64ea69f124b2 - std::sys::backtrace::_print_fmt::hfd5825900b6e0030 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x64ea69f124b2 - ::fmt::h427144ad75cfb218 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x64ea69f25e2f - core::fmt::rt::Argument::fmt::hd5ccc9cf97cea7f7 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x64ea69f25e2f - core::fmt::write::h593aaf5adf0f5dae [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x64ea69edb061 - std::io::default_write_fmt::h305b5a0727055346 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x64ea69edb061 - std::io::Write::write_fmt::h76f14a8a88733dc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x64ea69ee8e42 - std::sys::backtrace::BacktraceLock::print::h3953113552dca3ca [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x64ea69eee1bf - std::panicking::default_hook::{{closure}}::h486b96ad75eafbb8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x64ea69eee051 - std::panicking::default_hook::h99456317c5a1a20c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x64ea69091e6e - as core::ops::function::Fn>::call::h441e4551014e4cf8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x64ea69091e6e - test::test_main_with_exit_callback::{{closure}}::hdbca54e17f1a00e4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x64ea69eee92f - as core::ops::function::Fn>::call::h1e57b72c4e1d3045 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x64ea69eee92f - std::panicking::panic_with_hook::h67ab0df20212e4ea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x64ea69eedecc - std::panicking::begin_panic::{{closure}}::h8c03e328b7452c1a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x64ea69ee8f79 - std::sys::backtrace::__rust_end_short_backtrace::h13517477b5f78aab [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x64ea69eede9c - std::panicking::begin_panic::hf95d9c4f848d2c11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x64ea6938840b - ::log::h2c4818cd0b7fad87 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13 [INFO] [stdout] 19: 0x64ea69018b14 - as slog::Drain>::log::hf64a01afe51e5c94 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1719:18 [INFO] [stdout] 20: 0x64ea69018aab - slog::Logger::log::hb2bd2b3a83276458 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1233:28 [INFO] [stdout] 21: 0x64ea68d2c843 - foxy::logging::log_warning::{{closure}}::h0b1a7686dc0f2489 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:85:34 [INFO] [stdout] 22: 0x64ea68f4ddd4 - slog_scope::with_logger::{{closure}}::h36cb230271f547a5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:221:21 [INFO] [stdout] 23: 0x64ea68eb3d0d - std::thread::local::LocalKey::try_with::hf817ac4719e8c8a2 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 24: 0x64ea68eafc78 - std::thread::local::LocalKey::with::h24f7f2b0e8068809 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 25: 0x64ea68f4d6c0 - slog_scope::with_logger::h863da045b117b423 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:217:15 [INFO] [stdout] 26: 0x64ea68d2c316 - foxy::logging::log_warning::h1ccab7677c5fcf63 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/mod.rs:84:9 [INFO] [stdout] 27: 0x64ea68db06aa - foxy::logging::tests::logging_tests::test_log_warning_structured::{{closure}}::ha78cc8ffef596502 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:175:9 [INFO] [stdout] 28: 0x64ea68ca374e - core::ops::function::FnOnce::call_once::h62517143b78a7e6f [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 29: 0x64ea692a48e3 - serial_test::serial_code_lock::local_serial_core::h44740be89adc817e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serial_test-3.2.0/src/serial_code_lock.rs:36:5 [INFO] [stdout] 30: 0x64ea68db05f9 - foxy::logging::tests::logging_tests::test_log_warning_structured::h13504f9653ae9270 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:165:5 [INFO] [stdout] 31: 0x64ea68db0627 - foxy::logging::tests::logging_tests::test_log_warning_structured::{{closure}}::h1aacbd8712e1b657 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:166:37 [INFO] [stdout] 32: 0x64ea68cab106 - core::ops::function::FnOnce::call_once::hf48a4f416c712d4d [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 33: 0x64ea69091c2b - core::ops::function::FnOnce::call_once::h5d9016d521f5aee4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 34: 0x64ea69091c2b - test::__rust_begin_short_backtrace::h123fcee610330442 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18 [INFO] [stdout] 35: 0x64ea690a7715 - test::run_test_in_process::{{closure}}::h0442efda2e6359b2 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74 [INFO] [stdout] 36: 0x64ea690a7715 - as core::ops::function::FnOnce<()>>::call_once::hfe3528c8dd09682c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 37: 0x64ea690a7715 - std::panicking::catch_unwind::do_call::hb1659f7844fe8531 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 38: 0x64ea690a7715 - std::panicking::catch_unwind::hdc1f4be0019e72ad [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: 0x64ea690a7715 - std::panic::catch_unwind::hd6d7ccfa4513e770 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x64ea690a7715 - test::run_test_in_process::h6bbb839ed0802366 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27 [INFO] [stdout] 41: 0x64ea690a7715 - test::run_test::{{closure}}::he4fad7398c8acc11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43 [INFO] [stdout] 42: 0x64ea6907e084 - test::run_test::{{closure}}::h41975797e198d26c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41 [INFO] [stdout] 43: 0x64ea6907e084 - std::sys::backtrace::__rust_begin_short_backtrace::hfb46c397857336f1 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 44: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hddf826a64f532bea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 45: 0x64ea69081a2a - as core::ops::function::FnOnce<()>>::call_once::hc3518c3eab91aeb3 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 46: 0x64ea69081a2a - std::panicking::catch_unwind::do_call::h7bf3ad6dc012415a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 47: 0x64ea69081a2a - std::panicking::catch_unwind::h8b88f0375c767e36 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 48: 0x64ea69081a2a - std::panic::catch_unwind::h66cb5d61a90f7bc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 49: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::h65bc9d15fb966bbd [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 50: 0x64ea69081a2a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8d2c9a8796342e5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 51: 0x64ea69ee2e6f - as core::ops::function::FnOnce>::call_once::hef12b47e5c033835 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 52: 0x64ea69ee2e6f - std::sys::pal::unix::thread::Thread::new::thread_start::h135087e7c97657bc [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 53: 0x703e50ffeaa4 - [INFO] [stdout] 54: 0x703e5108ba34 - clone [INFO] [stdout] 55: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- logging::tests::logging_tests::test_log_with_context_all_levels_structured stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'logging::tests::logging_tests::test_log_with_context_all_levels_structured' (389) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13: [INFO] [stdout] slog-scope: No logger set. Use `slog_scope::set_global_logger` or `slog_scope::scope`. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x64ea69f124b2 - std::backtrace_rs::backtrace::libunwind::trace::h52580dd202462214 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x64ea69f124b2 - std::backtrace_rs::backtrace::trace_unsynchronized::hc969519abce0f52b [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x64ea69f124b2 - std::sys::backtrace::_print_fmt::hfd5825900b6e0030 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x64ea69f124b2 - ::fmt::h427144ad75cfb218 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x64ea69f25e2f - core::fmt::rt::Argument::fmt::hd5ccc9cf97cea7f7 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x64ea69f25e2f - core::fmt::write::h593aaf5adf0f5dae [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x64ea69edb061 - std::io::default_write_fmt::h305b5a0727055346 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x64ea69edb061 - std::io::Write::write_fmt::h76f14a8a88733dc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x64ea69ee8e42 - std::sys::backtrace::BacktraceLock::print::h3953113552dca3ca [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x64ea69eee1bf - std::panicking::default_hook::{{closure}}::h486b96ad75eafbb8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x64ea69eee051 - std::panicking::default_hook::h99456317c5a1a20c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x64ea69091e6e - as core::ops::function::Fn>::call::h441e4551014e4cf8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x64ea69091e6e - test::test_main_with_exit_callback::{{closure}}::hdbca54e17f1a00e4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x64ea69eee92f - as core::ops::function::Fn>::call::h1e57b72c4e1d3045 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x64ea69eee92f - std::panicking::panic_with_hook::h67ab0df20212e4ea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x64ea69eedecc - std::panicking::begin_panic::{{closure}}::h8c03e328b7452c1a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x64ea69ee8f79 - std::sys::backtrace::__rust_end_short_backtrace::h13517477b5f78aab [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x64ea69eede9c - std::panicking::begin_panic::hf95d9c4f848d2c11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x64ea6938840b - ::log::h2c4818cd0b7fad87 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13 [INFO] [stdout] 19: 0x64ea69018b14 - as slog::Drain>::log::hf64a01afe51e5c94 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1719:18 [INFO] [stdout] 20: 0x64ea69018aab - slog::Logger::log::hb2bd2b3a83276458 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1233:28 [INFO] [stdout] 21: 0x64ea68d2dd67 - foxy::logging::log_with_context::h7a4607c3fcb558a2 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/mod.rs:131:17 [INFO] [stdout] 22: 0x64ea68dc1fca - foxy::logging::tests::logging_tests::test_log_with_context_all_levels_structured::{{closure}}::hf765d70d60289e6d [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:292:13 [INFO] [stdout] 23: 0x64ea68ca78be - core::ops::function::FnOnce::call_once::hb1510de340b4fe37 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x64ea692a48e3 - serial_test::serial_code_lock::local_serial_core::h44740be89adc817e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serial_test-3.2.0/src/serial_code_lock.rs:36:5 [INFO] [stdout] 25: 0x64ea68dc1b69 - foxy::logging::tests::logging_tests::test_log_with_context_all_levels_structured::h47eb79355576d997 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:269:5 [INFO] [stdout] 26: 0x64ea68dc1b97 - foxy::logging::tests::logging_tests::test_log_with_context_all_levels_structured::{{closure}}::hec1109302ee16aac [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:270:53 [INFO] [stdout] 27: 0x64ea68ca4216 - core::ops::function::FnOnce::call_once::h71fa2f727b86a66d [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 28: 0x64ea69091c2b - core::ops::function::FnOnce::call_once::h5d9016d521f5aee4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 29: 0x64ea69091c2b - test::__rust_begin_short_backtrace::h123fcee610330442 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18 [INFO] [stdout] 30: 0x64ea690a7715 - test::run_test_in_process::{{closure}}::h0442efda2e6359b2 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74 [INFO] [stdout] 31: 0x64ea690a7715 - as core::ops::function::FnOnce<()>>::call_once::hfe3528c8dd09682c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 32: 0x64ea690a7715 - std::panicking::catch_unwind::do_call::hb1659f7844fe8531 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 33: 0x64ea690a7715 - std::panicking::catch_unwind::hdc1f4be0019e72ad [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 34: 0x64ea690a7715 - std::panic::catch_unwind::hd6d7ccfa4513e770 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 35: 0x64ea690a7715 - test::run_test_in_process::h6bbb839ed0802366 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27 [INFO] [stdout] 36: 0x64ea690a7715 - test::run_test::{{closure}}::he4fad7398c8acc11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43 [INFO] [stdout] 37: 0x64ea6907e084 - test::run_test::{{closure}}::h41975797e198d26c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41 [INFO] [stdout] 38: 0x64ea6907e084 - std::sys::backtrace::__rust_begin_short_backtrace::hfb46c397857336f1 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 39: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hddf826a64f532bea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 40: 0x64ea69081a2a - as core::ops::function::FnOnce<()>>::call_once::hc3518c3eab91aeb3 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 41: 0x64ea69081a2a - std::panicking::catch_unwind::do_call::h7bf3ad6dc012415a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 42: 0x64ea69081a2a - std::panicking::catch_unwind::h8b88f0375c767e36 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 43: 0x64ea69081a2a - std::panic::catch_unwind::h66cb5d61a90f7bc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 44: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::h65bc9d15fb966bbd [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 45: 0x64ea69081a2a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8d2c9a8796342e5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 46: 0x64ea69ee2e6f - as core::ops::function::FnOnce>::call_once::hef12b47e5c033835 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 47: 0x64ea69ee2e6f - std::sys::pal::unix::thread::Thread::new::thread_start::h135087e7c97657bc [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 48: 0x703e50ffeaa4 - [INFO] [stdout] 49: 0x703e5108ba34 - clone [INFO] [stdout] 50: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- logging::tests::logging_tests::test_log_with_context_empty_fields stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'logging::tests::logging_tests::test_log_with_context_empty_fields' (390) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13: [INFO] [stdout] slog-scope: No logger set. Use `slog_scope::set_global_logger` or `slog_scope::scope`. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x64ea69f124b2 - std::backtrace_rs::backtrace::libunwind::trace::h52580dd202462214 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x64ea69f124b2 - std::backtrace_rs::backtrace::trace_unsynchronized::hc969519abce0f52b [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x64ea69f124b2 - std::sys::backtrace::_print_fmt::hfd5825900b6e0030 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x64ea69f124b2 - ::fmt::h427144ad75cfb218 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x64ea69f25e2f - core::fmt::rt::Argument::fmt::hd5ccc9cf97cea7f7 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x64ea69f25e2f - core::fmt::write::h593aaf5adf0f5dae [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x64ea69edb061 - std::io::default_write_fmt::h305b5a0727055346 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x64ea69edb061 - std::io::Write::write_fmt::h76f14a8a88733dc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x64ea69ee8e42 - std::sys::backtrace::BacktraceLock::print::h3953113552dca3ca [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x64ea69eee1bf - std::panicking::default_hook::{{closure}}::h486b96ad75eafbb8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x64ea69eee051 - std::panicking::default_hook::h99456317c5a1a20c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x64ea69091e6e - as core::ops::function::Fn>::call::h441e4551014e4cf8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x64ea69091e6e - test::test_main_with_exit_callback::{{closure}}::hdbca54e17f1a00e4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x64ea69eee92f - as core::ops::function::Fn>::call::h1e57b72c4e1d3045 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x64ea69eee92f - std::panicking::panic_with_hook::h67ab0df20212e4ea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x64ea69eedecc - std::panicking::begin_panic::{{closure}}::h8c03e328b7452c1a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x64ea69ee8f79 - std::sys::backtrace::__rust_end_short_backtrace::h13517477b5f78aab [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x64ea69eede9c - std::panicking::begin_panic::hf95d9c4f848d2c11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x64ea6938840b - ::log::h2c4818cd0b7fad87 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13 [INFO] [stdout] 19: 0x64ea69018b14 - as slog::Drain>::log::hf64a01afe51e5c94 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1719:18 [INFO] [stdout] 20: 0x64ea69018aab - slog::Logger::log::hb2bd2b3a83276458 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1233:28 [INFO] [stdout] 21: 0x64ea68d2e259 - foxy::logging::log_with_context::h7a4607c3fcb558a2 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/mod.rs:145:17 [INFO] [stdout] 22: 0x64ea68db70b2 - foxy::logging::tests::logging_tests::test_log_with_context_empty_fields::{{closure}}::ha49aedc93e803e93 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:344:9 [INFO] [stdout] 23: 0x64ea68c9f5be - core::ops::function::FnOnce::call_once::h180ec3255589f5dd [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x64ea692a48e3 - serial_test::serial_code_lock::local_serial_core::h44740be89adc817e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serial_test-3.2.0/src/serial_code_lock.rs:36:5 [INFO] [stdout] 25: 0x64ea68db6fd9 - foxy::logging::tests::logging_tests::test_log_with_context_empty_fields::h68dce614a5e75843 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:333:5 [INFO] [stdout] 26: 0x64ea68db70f7 - foxy::logging::tests::logging_tests::test_log_with_context_empty_fields::{{closure}}::hbb6d7dc2f9d52eaf [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:334:44 [INFO] [stdout] 27: 0x64ea68ca21c6 - core::ops::function::FnOnce::call_once::h466e73eeaee4c5d7 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 28: 0x64ea69091c2b - core::ops::function::FnOnce::call_once::h5d9016d521f5aee4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 29: 0x64ea69091c2b - test::__rust_begin_short_backtrace::h123fcee610330442 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18 [INFO] [stdout] 30: 0x64ea690a7715 - test::run_test_in_process::{{closure}}::h0442efda2e6359b2 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74 [INFO] [stdout] 31: 0x64ea690a7715 - as core::ops::function::FnOnce<()>>::call_once::hfe3528c8dd09682c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 32: 0x64ea690a7715 - std::panicking::catch_unwind::do_call::hb1659f7844fe8531 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 33: 0x64ea690a7715 - std::panicking::catch_unwind::hdc1f4be0019e72ad [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 34: 0x64ea690a7715 - std::panic::catch_unwind::hd6d7ccfa4513e770 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 35: 0x64ea690a7715 - test::run_test_in_process::h6bbb839ed0802366 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27 [INFO] [stdout] 36: 0x64ea690a7715 - test::run_test::{{closure}}::he4fad7398c8acc11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43 [INFO] [stdout] 37: 0x64ea6907e084 - test::run_test::{{closure}}::h41975797e198d26c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41 [INFO] [stdout] 38: 0x64ea6907e084 - std::sys::backtrace::__rust_begin_short_backtrace::hfb46c397857336f1 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 39: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hddf826a64f532bea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 40: 0x64ea69081a2a - as core::ops::function::FnOnce<()>>::call_once::hc3518c3eab91aeb3 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 41: 0x64ea69081a2a - std::panicking::catch_unwind::do_call::h7bf3ad6dc012415a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 42: 0x64ea69081a2a - std::panicking::catch_unwind::h8b88f0375c767e36 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 43: 0x64ea69081a2a - std::panic::catch_unwind::h66cb5d61a90f7bc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 44: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::h65bc9d15fb966bbd [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 45: 0x64ea69081a2a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8d2c9a8796342e5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 46: 0x64ea69ee2e6f - as core::ops::function::FnOnce>::call_once::hef12b47e5c033835 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 47: 0x64ea69ee2e6f - std::sys::pal::unix::thread::Thread::new::thread_start::h135087e7c97657bc [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 48: 0x703e50ffeaa4 - [INFO] [stdout] 49: 0x703e5108ba34 - clone [INFO] [stdout] 50: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- logging::tests::logging_tests::test_log_with_context_special_field_values stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'logging::tests::logging_tests::test_log_with_context_special_field_values' (391) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13: [INFO] [stdout] slog-scope: No logger set. Use `slog_scope::set_global_logger` or `slog_scope::scope`. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x64ea69f124b2 - std::backtrace_rs::backtrace::libunwind::trace::h52580dd202462214 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x64ea69f124b2 - std::backtrace_rs::backtrace::trace_unsynchronized::hc969519abce0f52b [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x64ea69f124b2 - std::sys::backtrace::_print_fmt::hfd5825900b6e0030 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x64ea69f124b2 - ::fmt::h427144ad75cfb218 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x64ea69f25e2f - core::fmt::rt::Argument::fmt::hd5ccc9cf97cea7f7 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x64ea69f25e2f - core::fmt::write::h593aaf5adf0f5dae [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x64ea69edb061 - std::io::default_write_fmt::h305b5a0727055346 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x64ea69edb061 - std::io::Write::write_fmt::h76f14a8a88733dc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x64ea69ee8e42 - std::sys::backtrace::BacktraceLock::print::h3953113552dca3ca [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x64ea69eee1bf - std::panicking::default_hook::{{closure}}::h486b96ad75eafbb8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x64ea69eee051 - std::panicking::default_hook::h99456317c5a1a20c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x64ea69091e6e - as core::ops::function::Fn>::call::h441e4551014e4cf8 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x64ea69091e6e - test::test_main_with_exit_callback::{{closure}}::hdbca54e17f1a00e4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x64ea69eee92f - as core::ops::function::Fn>::call::h1e57b72c4e1d3045 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x64ea69eee92f - std::panicking::panic_with_hook::h67ab0df20212e4ea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x64ea69eedecc - std::panicking::begin_panic::{{closure}}::h8c03e328b7452c1a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x64ea69ee8f79 - std::sys::backtrace::__rust_end_short_backtrace::h13517477b5f78aab [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x64ea69eede9c - std::panicking::begin_panic::hf95d9c4f848d2c11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x64ea6938840b - ::log::h2c4818cd0b7fad87 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-scope-4.4.0/lib.rs:125:13 [INFO] [stdout] 19: 0x64ea69018b14 - as slog::Drain>::log::hf64a01afe51e5c94 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1719:18 [INFO] [stdout] 20: 0x64ea69018aab - slog::Logger::log::hb2bd2b3a83276458 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/slog-2.7.0/src/lib.rs:1233:28 [INFO] [stdout] 21: 0x64ea68d2e259 - foxy::logging::log_with_context::h7a4607c3fcb558a2 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/mod.rs:145:17 [INFO] [stdout] 22: 0x64ea68dc1ab3 - foxy::logging::tests::logging_tests::test_log_with_context_special_field_values::{{closure}}::h857a3cff71bb0e33 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:472:9 [INFO] [stdout] 23: 0x64ea68ca290e - core::ops::function::FnOnce::call_once::h4f8bc1d5cd51441a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x64ea692a48e3 - serial_test::serial_code_lock::local_serial_core::h44740be89adc817e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serial_test-3.2.0/src/serial_code_lock.rs:36:5 [INFO] [stdout] 25: 0x64ea68dc15e9 - foxy::logging::tests::logging_tests::test_log_with_context_special_field_values::h9aa3e36a60a8978b [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:455:5 [INFO] [stdout] 26: 0x64ea68dc1617 - foxy::logging::tests::logging_tests::test_log_with_context_special_field_values::{{closure}}::h75f868d31a093749 [INFO] [stdout] at /opt/rustwide/workdir/src/logging/../../tests/unit/logging/tests.rs:456:52 [INFO] [stdout] 27: 0x64ea68ca5f66 - core::ops::function::FnOnce::call_once::h929f9d389a90183f [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 28: 0x64ea69091c2b - core::ops::function::FnOnce::call_once::h5d9016d521f5aee4 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 29: 0x64ea69091c2b - test::__rust_begin_short_backtrace::h123fcee610330442 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18 [INFO] [stdout] 30: 0x64ea690a7715 - test::run_test_in_process::{{closure}}::h0442efda2e6359b2 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74 [INFO] [stdout] 31: 0x64ea690a7715 - as core::ops::function::FnOnce<()>>::call_once::hfe3528c8dd09682c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 32: 0x64ea690a7715 - std::panicking::catch_unwind::do_call::hb1659f7844fe8531 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 33: 0x64ea690a7715 - std::panicking::catch_unwind::hdc1f4be0019e72ad [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 34: 0x64ea690a7715 - std::panic::catch_unwind::hd6d7ccfa4513e770 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 35: 0x64ea690a7715 - test::run_test_in_process::h6bbb839ed0802366 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27 [INFO] [stdout] 36: 0x64ea690a7715 - test::run_test::{{closure}}::he4fad7398c8acc11 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43 [INFO] [stdout] 37: 0x64ea6907e084 - test::run_test::{{closure}}::h41975797e198d26c [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41 [INFO] [stdout] 38: 0x64ea6907e084 - std::sys::backtrace::__rust_begin_short_backtrace::hfb46c397857336f1 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 39: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hddf826a64f532bea [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 40: 0x64ea69081a2a - as core::ops::function::FnOnce<()>>::call_once::hc3518c3eab91aeb3 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 41: 0x64ea69081a2a - std::panicking::catch_unwind::do_call::h7bf3ad6dc012415a [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40 [INFO] [stdout] 42: 0x64ea69081a2a - std::panicking::catch_unwind::h8b88f0375c767e36 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19 [INFO] [stdout] 43: 0x64ea69081a2a - std::panic::catch_unwind::h66cb5d61a90f7bc5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14 [INFO] [stdout] 44: 0x64ea69081a2a - std::thread::Builder::spawn_unchecked_::{{closure}}::h65bc9d15fb966bbd [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 45: 0x64ea69081a2a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8d2c9a8796342e5 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 46: 0x64ea69ee2e6f - as core::ops::function::FnOnce>::call_once::hef12b47e5c033835 [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 47: 0x64ea69ee2e6f - std::sys::pal::unix::thread::Thread::new::thread_start::h135087e7c97657bc [INFO] [stdout] at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 48: 0x703e50ffeaa4 - [INFO] [stdout] 49: 0x703e5108ba34 - clone [INFO] [stdout] 50: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] logging::tests::logging_tests::test_log_warning_structured [INFO] [stdout] logging::tests::logging_tests::test_log_with_context_all_levels_structured [INFO] [stdout] logging::tests::logging_tests::test_log_with_context_empty_fields [INFO] [stdout] logging::tests::logging_tests::test_log_with_context_special_field_values [INFO] [stdout] [INFO] [stdout] test result: FAILED. 550 passed; 4 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.67s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "f670c6d0adf6ff9c0a14330e88af4d6ce81c55a3c8c10c913352302993af8657", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f670c6d0adf6ff9c0a14330e88af4d6ce81c55a3c8c10c913352302993af8657", kill_on_drop: false }` [INFO] [stdout] f670c6d0adf6ff9c0a14330e88af4d6ce81c55a3c8c10c913352302993af8657