[INFO] cloning repository https://github.com/iainlane/rssfilter
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/iainlane/rssfilter" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fiainlane%2Frssfilter", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fiainlane%2Frssfilter'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 19462f52b82d27b3f7c53b1d39d1663353765adf
[INFO] testing iainlane/rssfilter against 1.97.0-beta.6 for beta-1.98-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fiainlane%2Frssfilter" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-2-tc1/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/iainlane/rssfilter
[INFO] finished tweaking git repo https://github.com/iainlane/rssfilter
[INFO] tweaked toml for git repo https://github.com/iainlane/rssfilter written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/iainlane/rssfilter on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/iainlane/rssfilter already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded worker-macros v0.8.5
[INFO] [stderr]   Downloaded worker-sys v0.8.5
[INFO] [stderr]   Downloaded wasm-streams v0.6.0
[INFO] [stderr]   Downloaded wasm-bindgen-test v0.3.76
[INFO] [stderr]   Downloaded wasm-bindgen-test-shared v0.2.126
[INFO] [stderr]   Downloaded diligent-date-parser v0.1.5
[INFO] [stderr]   Downloaded uuid-rng-internal v1.23.5
[INFO] [stderr]   Downloaded atom_syndication v0.12.9
[INFO] [stderr]   Downloaded opentelemetry-stdout v0.32.0
[INFO] [stderr]   Downloaded tracing-web v0.1.3
[INFO] [stderr]   Downloaded wasm-bindgen-test-macro v0.3.76
[INFO] [stderr]   Downloaded temp-env v0.3.6
[INFO] [stderr]   Downloaded opentelemetry-resource-detectors v0.11.0
[INFO] [stderr]   Downloaded mockito v1.7.2
[INFO] [stderr]   Downloaded rss v2.1.0
[INFO] [stderr]   Downloaded worker v0.8.5
[INFO] [stderr]   Downloaded minicov v0.3.8
[INFO] [stderr]   Downloaded tracing-opentelemetry v0.33.0
[INFO] [stderr]   Downloaded opentelemetry-otlp v0.32.0
[INFO] [stderr]   Downloaded opentelemetry v0.32.0
[INFO] [stderr]   Downloaded headers-accept v0.3.0
[INFO] [stderr]   Downloaded sha1 v0.10.7
[INFO] [stderr]   Downloaded opentelemetry-semantic-conventions v0.32.1
[INFO] [stderr]   Downloaded prost-derive v0.14.4
[INFO] [stderr]   Downloaded opentelemetry-http v0.32.0
[INFO] [stderr]   Downloaded prost v0.14.4
[INFO] [stderr]   Downloaded mediatype v0.21.0
[INFO] [stderr]   Downloaded opentelemetry_sdk v0.32.1
[INFO] [stderr]   Downloaded jiff-static v0.2.32
[INFO] [stderr]   Downloaded opentelemetry-proto v0.32.0
[INFO] [stderr]   Downloaded jiff v0.2.32
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3
[INFO] running `Command { std: "docker" "start" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.126
[INFO] [stderr]    Compiling rustversion v1.0.23
[INFO] [stderr]    Compiling bumpalo v3.20.3
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling cc v1.2.67
[INFO] [stderr]    Compiling zmij v1.0.22
[INFO] [stderr]    Compiling zerocopy v0.8.54
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling anyhow v1.0.103
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling opentelemetry-resource-detectors v0.11.0
[INFO] [stderr]    Compiling opentelemetry-semantic-conventions v0.32.1
[INFO] [stderr]    Compiling http v1.4.2
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling quick-xml v0.41.0
[INFO] [stderr]    Compiling wasm-bindgen v0.2.126
[INFO] [stderr]    Compiling jiff v0.2.32
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling colored v3.1.1
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling mediatype v0.21.0
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling uuid v1.23.5
[INFO] [stderr]    Compiling sha1 v0.10.7
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling diligent-date-parser v0.1.5
[INFO] [stderr]    Compiling regex-automata v0.4.15
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling headers-core v0.3.0
[INFO] [stderr]    Compiling headers v0.4.1
[INFO] [stderr]    Compiling headers-accept v0.3.0
[INFO] [stderr]    Compiling aws-lc-sys v0.42.0
[INFO] [stderr]    Compiling zstd-sys v2.0.16+zstd.1.5.7
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.126
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling regex v1.13.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling env_filter v2.0.0
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling env_logger v0.11.11
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling displaydoc v0.2.6
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling zstd-safe v7.2.4
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling prost-derive v0.14.4
[INFO] [stderr]    Compiling compression-codecs v0.4.38
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling pin-project-internal v1.1.13
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling opentelemetry v0.32.0
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling pin-project v1.1.13
[INFO] [stderr]    Compiling prost v0.14.4
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.126
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling atom_syndication v0.12.9
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling js-sys v0.3.103
[INFO] [stderr]    Compiling opentelemetry_sdk v0.32.1
[INFO] [stderr]    Compiling rss v2.1.0
[INFO] [stderr]    Compiling console_error_panic_hook v0.1.7
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling async-compression v0.4.42
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling h2 v0.4.15
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling tracing-opentelemetry v0.33.0
[INFO] [stderr]    Compiling tower-http v0.6.11
[INFO] [stderr]    Compiling opentelemetry-proto v0.32.0
[INFO] [stderr]    Compiling opentelemetry-stdout v0.32.0
[INFO] [stderr]    Compiling aws-lc-rs v1.17.1
[INFO] [stderr]    Compiling rustls v0.23.41
[INFO] [stderr]    Compiling hyper v1.10.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling web-sys v0.3.103
[INFO] [stderr]    Compiling mockito v1.7.2
[INFO] [stderr]    Compiling wasm-bindgen-futures v0.4.76
[INFO] [stderr]    Compiling serde-wasm-bindgen v0.6.5
[INFO] [stderr]    Compiling worker-sys v0.8.5
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustls-platform-verifier v0.7.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling reqwest v0.13.4
[INFO] [stderr]    Compiling wasm-streams v0.6.0
[INFO] [stderr]    Compiling worker-macros v0.8.5
[INFO] [stderr]    Compiling worker v0.8.5
[INFO] [stderr]    Compiling opentelemetry-http v0.32.0
[INFO] [stderr]    Compiling opentelemetry-otlp v0.32.0
[INFO] [stderr]    Compiling rssfilter-telemetry v0.1.0 (/opt/rustwide/workdir/rssfilter-telemetry)
[INFO] [stderr]    Compiling filter-rss-feed v0.1.0 (/opt/rustwide/workdir/filter-rss-feed)
[INFO] [stderr]    Compiling test-utils v0.1.0 (/opt/rustwide/workdir/test-utils)
[INFO] [stderr]    Compiling rssfilter-cli v0.1.0 (/opt/rustwide/workdir/rssfilter-cli)
[INFO] [stderr]    Compiling workers-rssfilter v0.1.0 (/opt/rustwide/workdir/workers-rssfilter)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 21s
[INFO] running `Command { std: "docker" "inspect" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling test-case-core v3.3.1
[INFO] [stderr]    Compiling wasm-bindgen-test-macro v0.3.76
[INFO] [stderr]    Compiling temp-env v0.3.6
[INFO] [stderr]    Compiling wasm-bindgen-test-shared v0.2.126
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling serde-wasm-bindgen v0.6.5
[INFO] [stderr]    Compiling test-case-macros v3.3.1
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling test-case v3.3.1
[INFO] [stderr]    Compiling reqwest v0.13.4
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling wasm-bindgen-test v0.3.76
[INFO] [stderr]    Compiling mockito v1.7.2
[INFO] [stderr]    Compiling diligent-date-parser v0.1.5
[INFO] [stderr]    Compiling worker v0.8.5
[INFO] [stderr]    Compiling opentelemetry-stdout v0.32.0
[INFO] [stderr]    Compiling atom_syndication v0.12.9
[INFO] [stderr]    Compiling rss v2.1.0
[INFO] [stderr]    Compiling tracing-opentelemetry v0.33.0
[INFO] [stderr]    Compiling opentelemetry-http v0.32.0
[INFO] [stderr]    Compiling test-utils v0.1.0 (/opt/rustwide/workdir/test-utils)
[INFO] [stderr]    Compiling opentelemetry-otlp v0.32.0
[INFO] [stderr]    Compiling rssfilter-telemetry v0.1.0 (/opt/rustwide/workdir/rssfilter-telemetry)
[INFO] [stderr]    Compiling filter-rss-feed v0.1.0 (/opt/rustwide/workdir/filter-rss-feed)
[INFO] [stderr]    Compiling workers-rssfilter v0.1.0 (/opt/rustwide/workdir/workers-rssfilter)
[INFO] [stderr]    Compiling rssfilter-cli v0.1.0 (/opt/rustwide/workdir/rssfilter-cli)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 33.55s
[INFO] running `Command { std: "docker" "inspect" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.52s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/filter_rss_feed-e31a521cf5026266)
[INFO] [stdout] 
[INFO] [stdout] running 59 tests
[INFO] [stdout] test fake_http_client::tests::test_fake_http_client_error ... ok
[INFO] [stdout] test fake_http_client::tests::test_fake_http_client_not_found ... ok
[INFO] [stdout] test fake_http_client::tests::test_fake_http_client_with_headers ... ok
[INFO] [stdout] test fake_http_client::tests::test_fake_http_client_builder_convenience_methods ... ok
[INFO] [stdout] test fake_http_client::tests::test_fake_http_client_basic ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_empty_iterator ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_invalid_utf8 ... ok
[INFO] [stdout] test fake_http_client::tests::test_fake_response_convenience_methods ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::bypass_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::hit_lowercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::custom_status ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::revalidated_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::miss_lowercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::dynamic_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::dynamic_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::expired_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::unknown_status_with_hyphen ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::miss_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::hit_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::updating_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::expired_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::bypass_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::miss_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::updating_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::other_displays_original ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_display::revalidated_displays_as_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::bypass_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::dynamic_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::hit_mixed_case ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::expired_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::dynamic_lowercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::revalidated_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::updating_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_header_map_replace ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_header_map_integration ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_header_name ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::hit_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::other_preserves_original_case ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::hit_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode::miss_encodes_to_uppercase ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_multiple_header_values_takes_first ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_encode_other_with_invalid_characters ... ok
[INFO] [stdout] test http_client::tests::test_cache_config_default ... ok
[INFO] [stdout] test http_client::tests::test_cache_config_custom ... ok
[INFO] [stdout] test header_cf_cache_status::tests::test_roundtrip_encode_decode ... ok
[INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_post_with_body ... ok
[INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_custom_headers ... ok
[INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_error_handling ... ok
[INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_get ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.925545Z","level":"DEBUG","name":"BatchSpanProcessor.ThreadStarted","interval_in_millisecs":"5000","max_export_batch_size":512,"max_queue_size":2048,"target":"opentelemetry_sdk"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.943343Z","level":"DEBUG","message":"Loaded 121 CA root certificates from the system","log.target":"rustls_platform_verifier::verification::others","log.module_path":"rustls_platform_verifier::verification::others","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/rustls-platform-verifier-0.7.0/src/verification/others.rs","log.line":109,"target":"rustls_platform_verifier::verification::others"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.950848Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.951386Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.952590Z","level":"DEBUG","message":"Request received: \r\nGET /test\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:37511\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.954498Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.954841Z","level":"DEBUG","message":"Loaded 121 CA root certificates from the system","log.target":"rustls_platform_verifier::verification::others","log.module_path":"rustls_platform_verifier::verification::others","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/rustls-platform-verifier-0.7.0/src/verification/others.rs","log.line":109,"target":"rustls_platform_verifier::verification::others"}
[INFO] [stdout] test http_client::tests::reqwest_tests::test_custom_cache_config ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.994626Z","level":"DEBUG","message":"Loaded 121 CA root certificates from the system","log.target":"rustls_platform_verifier::verification::others","log.module_path":"rustls_platform_verifier::verification::others","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/rustls-platform-verifier-0.7.0/src/verification/others.rs","log.line":109,"target":"rustls_platform_verifier::verification::others"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.996080Z","level":"DEBUG","name":"BatchSpanProcessor.ThreadStarted","interval_in_millisecs":"5000","max_export_batch_size":512,"max_queue_size":2048,"target":"opentelemetry_sdk"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.996264Z","level":"DEBUG","name":"BatchSpanProcessor.ExportingDueToShutdown","target":"opentelemetry_sdk"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.996324Z","level":"DEBUG","name":"BatchSpanProcessor.ThreadExiting","reason":"ShutdownRequested","target":"opentelemetry_sdk"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.996358Z","level":"DEBUG","name":"BatchSpanProcessor.ThreadStopped","target":"opentelemetry_sdk"}
[INFO] [stdout] test tests::test_feed_size_validation ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997353Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:45073","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:45073","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:45073","name":"fetch"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997384Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:40421","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:40421","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40421","name":"fetch"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997564Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:45073","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] test tests::test_feed_size_validation_success ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997605Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40421","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997748Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:40481","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:40481","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40481","name":"fetch"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997893Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40481","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.997946Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:45073","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.998112Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40421","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.998154Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:40481","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.998626Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:33843","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:33843","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:33843","name":"fetch"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.998790Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:33843","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999015Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:40481\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999102Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999077Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:33843","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999139Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:40421\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999213Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999385Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:45073\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999485Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] test tests::test_content_type_validation_success ... {"timestamp":"2026-07-14T03:18:32.999603Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:39369","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:39369","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:39369","name":"fetch"}]}
[INFO] [stdout] ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999772Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:39369","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:32.999986Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000073Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:39369","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000138Z","level":"DEBUG","message":"Received response","target":"filter_rss_feed","span":{"status":"200 OK","name":"filter_response"},"spans":[{"status":"200 OK","name":"filter_response"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000188Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:33843\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000267Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000370Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000421Z","level":"INFO","message":"Filtering items from RSS feed","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000610Z","level":"DEBUG","message":"Received response","target":"filter_rss_feed","span":{"status":"200 OK","name":"filter_response"},"spans":[{"status":"200 OK","name":"filter_response"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000853Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000891Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000960Z","level":"INFO","message":"Filtering items from RSS feed","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.001028Z","level":"INFO","message":"No items filtered from RSS feed","channel_url":"http://www.example.com/","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] test tests::test_fetch_and_filter::no_filters ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.001653Z","level":"DEBUG","message":"Filtering out item","item":"http://www.example.com/test2","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.001834Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":2,"n_items_at_end":1,"n_items_filtered":1,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.000985Z","level":"DEBUG","message":"Received response","target":"filter_rss_feed","span":{"status":"200 OK","name":"filter_response"},"spans":[{"status":"200 OK","name":"filter_response"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.002684Z","level":"INFO","message":"Filtering items from RSS feed","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.002896Z","level":"DEBUG","message":"Filtering out item","item":"http://www.example.com/test1","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.002984Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:39369\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003070Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003118Z","level":"DEBUG","message":"Filtering out item","item":"http://www.example.com/test2","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003203Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":2,"n_items_at_end":0,"n_items_filtered":2,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] test tests::test_fetch_and_filter::link_filter_only ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.001001Z","level":"DEBUG","message":"Received response","target":"filter_rss_feed","span":{"status":"200 OK","name":"filter_response"},"spans":[{"status":"200 OK","name":"filter_response"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003693Z","level":"INFO","message":"Filtering items from RSS feed","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003782Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"}
[INFO] [stdout] test tests::test_fetch_and_filter::title_filter_only_both_items_match ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003907Z","level":"DEBUG","message":"Received response","target":"filter_rss_feed","span":{"status":"200 OK","name":"filter_response"},"spans":[{"status":"200 OK","name":"filter_response"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.003960Z","level":"DEBUG","message":"Filtering out item","item":"http://www.example.com/test1","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.004164Z","level":"INFO","message":"Filtering items from RSS feed","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.004243Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":2,"n_items_at_end":1,"n_items_filtered":1,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.004358Z","level":"DEBUG","message":"Filtering out item","item":"http://www.example.com/test1","target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] test tests::test_fetch_and_filter::guid_filter_only ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.007891Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":2,"n_items_at_end":1,"n_items_filtered":1,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]}
[INFO] [stdout] test tests::test_fetch_and_filter::title_filter_only ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.148051Z","level":"DEBUG","message":"Loaded 121 CA root certificates from the system","log.target":"rustls_platform_verifier::verification::others","log.module_path":"rustls_platform_verifier::verification::others","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/rustls-platform-verifier-0.7.0/src/verification/others.rs","log.line":109,"target":"rustls_platform_verifier::verification::others"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.148901Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:44211","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:44211","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:44211","name":"fetch"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.149495Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:44211","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.150366Z","level":"DEBUG","message":"starting new connection 'Some(\"127.0.0.1\")'","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.13.4/src/connect.rs","log.line":929,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:44211","name":"fetch"},{"name":"send"}]}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.151978Z","level":"DEBUG","message":"Request received: \r\nGET /\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: zstd,gzip,deflate,br\r\nhost: 127.0.0.1:44211\r\n","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":533,"target":"mockito::server"}
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.152576Z","level":"DEBUG","message":"Mock found","log.target":"mockito::server","log.module_path":"mockito::server","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/mockito-1.7.2/src/server.rs","log.line":553,"target":"mockito::server"}
[INFO] [stdout] test tests::test_server_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rssfilter-9fdbc4aa4c60ccaf)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rssfilter_telemetry-e94e79a2aacf04f6)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test tests::test_log_config_worker_precedence::all_unset ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::env_log_format_used_worker_rust_log_overrides ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::env_vars_only ... ok
[INFO] [stdout] test tests::test_env_filter_creation ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::worker_log_format_overrides_env_rust_log_used ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::worker_vars_only ... ok
[INFO] [stdout] test tests::test_integration_with_actual_logging ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::worker_vars_override_invalid_env_vars ... ok
[INFO] [stdout] test tests::test_log_format_from_str_invalid::empty_string ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::worker_vars_override_env_vars ... ok
[INFO] [stdout] test tests::test_log_config_worker_precedence::fallback_to_env_when_worker_vars_invalid ... ok
[INFO] [stdout] test tests::test_log_format_from_str_invalid::invalid_format ... ok
[INFO] [stdout] test tests::test_log_format_from_str_valid::json_lowercase ... ok
[INFO] [stdout] test tests::test_log_format_from_str_invalid::unsupported_format ... ok
[INFO] [stdout] test tests::test_log_format_from_str_valid::pretty_lowercase ... ok
[INFO] [stdout] test tests::test_log_format_from_str_valid::pretty_uppercase ... ok
[INFO] [stdout] test tests::test_tracing_error_display ... ok
[INFO] [stdout] test tests::test_writer_injection ... ok
[INFO] [stdout] test tests::test_log_format_from_str_valid::json_uppercase ... ok
[INFO] [stdout] test tests::test_extract_context_from_headers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_utils-e56e08ab9f8a8858)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/workers_rssfilter-4d7459ed9c1e0d35)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 41 tests
[INFO] [stdout] test filter::tests::test_filter_request_headers::filter_host_and_x_custom_header_headers ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::incoming_accept_and_user_agent_headers_are_both_overwritten ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::incoming_accept_header_is_overwritten ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::no_headers_to_filter ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::filter_host_and_x_custom_header_headers_retaining_content_type ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::filter_host_header_retaining_content_type ... ok
[INFO] [stdout] test filter::tests::test_with_header_map ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_invalid_regex ... ok
[INFO] [stdout] test integration_tests::test_404 ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::incoming_user_agent_header_is_overwritten ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_multiple_regexes ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_no_filters ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_no_params ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_no_url ... ok
[INFO] [stdout] test integration_tests::test_parameter_validation_success ... ok
[INFO] [stdout] test integration_tests::test_url_encoding_in_parameters ... ok
[INFO] [stdout] test filter::tests::test_filter_request_headers::filter_out_host_header ... ok
[INFO] [stdout] test integration_tests::test_filter_link_multiple ... ok
[INFO] [stdout] test integration_tests::test_rss_filtering_basic ... ok
[INFO] [stdout] test integration_tests::test_regex_no_matches ... ok
[INFO] [stdout] test integration_tests::test_empty_regex_matches ... ok
[INFO] [stdout] test integration_tests::test_http_error_handling ... ok
[INFO] [stdout] test integration_tests::test_rss_filtering_guid ... ok
[INFO] [stdout] test integration_tests::test_mixed_filter_types ... ok
[INFO] [stdout] test integration_tests::test_rss_filtering_link ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:33.957131Z","level":"INFO","message":"Error processing request","err":"Request validation failed: A url and at least one of title_filter_regex, guid_filter_regex, or link_filter_regex must be provided","target":"workers_rssfilter","span":{"request_id":"426e10c9-51b9-4050-91e4-b46ec5ada4cd","name":"request"},"spans":[{"request_id":"426e10c9-51b9-4050-91e4-b46ec5ada4cd","name":"request"}]}
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::put_method ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::options_method ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::favicon ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::health_check ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::post_method ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::robots ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_not_found_integration ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::head_method ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::api_endpoint ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::status_check ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_successful_validation ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_various_wrong_paths::well_known ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_content_type_header ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::patch_method ... ok
[INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::delete_method ... ok
[INFO] [stdout] {"timestamp":"2026-07-14T03:18:34.038564Z","level":"INFO","message":"Request completed","url":"http://127.0.0.1:46327","status":"307 Temporary Redirect","duration_ms":"81","target":"workers_rssfilter","span":{"name":"rss_handler"},"spans":[{"request_id":"6343062f-269f-429d-8486-e8a6dd5618b2","name":"request"},{"name":"rss_handler"}]}
[INFO] [stdout] test integration_tests::test_header_passthrough ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.57s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests filter_rss_feed
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rssfilter_telemetry
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests test_utils
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3", kill_on_drop: false }`
[INFO] [stdout] 95748aca070f2f32f827c5146006f7e2c4ec03e30219ddb1df38be7ecc9d78f3