[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] b79bc00f74745a1a5cf74db804bcb1d6182522c5 [INFO] testing iainlane/rssfilter against master#c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 for pr-146098-7 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fiainlane%2Frssfilter" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'... [INFO] [stderr] done. [INFO] removed /workspace/builds/worker-3-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-3-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/iainlane/rssfilter on toolchain c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "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" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded wasm-bindgen-test v0.3.55 [INFO] [stderr] Downloaded ctor-proc-macro v0.0.7 [INFO] [stderr] Downloaded derive_builder_macro v0.20.2 [INFO] [stderr] Downloaded worker v0.6.7 [INFO] [stderr] Downloaded dtor-proc-macro v0.0.6 [INFO] [stderr] Downloaded opentelemetry-http v0.31.0 [INFO] [stderr] Downloaded mediatype v0.20.0 [INFO] [stderr] Downloaded opentelemetry-proto v0.31.0 [INFO] [stderr] Downloaded opentelemetry v0.31.0 [INFO] [stderr] Downloaded opentelemetry-otlp v0.31.0 [INFO] [stderr] Downloaded opentelemetry-semantic-conventions v0.31.0 [INFO] [stderr] Downloaded rss v2.0.12 [INFO] [stderr] Downloaded opentelemetry_sdk v0.31.0 [INFO] [stderr] Downloaded tracing-opentelemetry v0.32.0 [INFO] [stderr] Downloaded minicov v0.3.7 [INFO] [stderr] Downloaded atom_syndication v0.12.7 [INFO] [stderr] Downloaded temp-env v0.3.6 [INFO] [stderr] Downloaded tracing-web v0.1.3 [INFO] [stderr] Downloaded derive_builder_core v0.20.2 [INFO] [stderr] Downloaded derive_builder v0.20.2 [INFO] [stderr] Downloaded uuid-rng-internal v1.18.1 [INFO] [stderr] Downloaded wasm-bindgen-test-macro v0.3.55 [INFO] [stderr] Downloaded worker-macros v0.6.7 [INFO] [stderr] Downloaded dtor v0.1.0 [INFO] [stderr] Downloaded ctor v0.6.1 [INFO] [stderr] Downloaded headers-accept v0.2.1 [INFO] [stderr] Downloaded opentelemetry-resource-detectors v0.10.0 [INFO] [stderr] Downloaded opentelemetry-stdout v0.31.0 [INFO] [stderr] Downloaded diligent-date-parser v0.1.5 [INFO] [stderr] Downloaded worker-sys v0.6.7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 9cdfc591d7a512a052d9f80a2f3758bc785d475d5209014b84df06e2d299ee6a [INFO] running `Command { std: "docker" "start" "-a" "9cdfc591d7a512a052d9f80a2f3758bc785d475d5209014b84df06e2d299ee6a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "9cdfc591d7a512a052d9f80a2f3758bc785d475d5209014b84df06e2d299ee6a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9cdfc591d7a512a052d9f80a2f3758bc785d475d5209014b84df06e2d299ee6a", kill_on_drop: false }` [INFO] [stdout] 9cdfc591d7a512a052d9f80a2f3758bc785d475d5209014b84df06e2d299ee6a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 54e0a21902765fe218aeedd3b188e41323c2d411e586b2f5b574504f41d9c2a2 [INFO] running `Command { std: "docker" "start" "-a" "54e0a21902765fe218aeedd3b188e41323c2d411e586b2f5b574504f41d9c2a2", kill_on_drop: false }` [INFO] [stderr] Compiling parking_lot_core v0.9.12 [INFO] [stderr] Compiling lock_api v0.4.14 [INFO] [stderr] Compiling wasm-bindgen-shared v0.2.105 [INFO] [stderr] Compiling proc-macro2 v1.0.103 [INFO] [stderr] Compiling wasm-bindgen v0.2.105 [INFO] [stderr] Compiling cc v1.2.43 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling zstd-safe v7.2.4 [INFO] [stderr] Compiling rustls v0.23.34 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling brotli-decompressor v5.0.0 [INFO] [stderr] Compiling anyhow v1.0.100 [INFO] [stderr] Compiling chrono v0.4.42 [INFO] [stderr] Compiling itertools v0.14.0 [INFO] [stderr] Compiling flate2 v1.1.5 [INFO] [stderr] Compiling compression-core v0.4.29 [INFO] [stderr] Compiling webpki-roots v1.0.3 [INFO] [stderr] Compiling bitflags v2.10.0 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.2 [INFO] [stderr] Compiling quick-xml v0.37.5 [INFO] [stderr] Compiling anstream v0.6.21 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling parking_lot v0.12.5 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling never v0.1.0 [INFO] [stderr] Compiling nu-ansi-term v0.50.3 [INFO] [stderr] Compiling opentelemetry-resource-detectors v0.10.0 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling headers-core v0.3.0 [INFO] [stderr] Compiling quote v1.0.41 [INFO] [stderr] Compiling opentelemetry-semantic-conventions v0.31.0 [INFO] [stderr] Compiling syn v2.0.108 [INFO] [stderr] Compiling matchit v0.7.3 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling jiff v0.2.15 [INFO] [stderr] Compiling clap_lex v0.7.6 [INFO] [stderr] Compiling colored v3.0.0 [INFO] [stderr] Compiling similar v2.7.0 [INFO] [stderr] Compiling brotli v8.0.2 [INFO] [stderr] Compiling headers v0.4.1 [INFO] [stderr] Compiling clap_builder v4.5.51 [INFO] [stderr] Compiling mediatype v0.20.0 [INFO] [stderr] Compiling uuid v1.18.1 [INFO] [stderr] Compiling web-time v1.1.0 [INFO] [stderr] Compiling diligent-date-parser v0.1.5 [INFO] [stderr] Compiling headers-accept v0.2.1 [INFO] [stderr] Compiling zstd-sys v2.0.16+zstd.1.5.7 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling matchers v0.2.0 [INFO] [stderr] Compiling env_filter v0.1.4 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling synstructure v0.13.2 [INFO] [stderr] Compiling wasm-bindgen-macro-support v0.2.105 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling rustls-webpki v0.103.8 [INFO] [stderr] Compiling zstd v0.13.3 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling zerovec-derive v0.11.2 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling async-trait v0.1.89 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling prost-derive v0.14.1 [INFO] [stderr] Compiling clap_derive v4.5.49 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling yoke-derive v0.8.1 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling compression-codecs v0.4.31 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling yoke v0.8.1 [INFO] [stderr] Compiling prost v0.14.1 [INFO] [stderr] Compiling opentelemetry v0.31.0 [INFO] [stderr] Compiling zerovec v0.11.5 [INFO] [stderr] Compiling zerotrie v0.2.3 [INFO] [stderr] Compiling clap v4.5.51 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling tinystr v0.8.2 [INFO] [stderr] Compiling potential_utf v0.1.4 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling wasm-bindgen-macro v0.2.105 [INFO] [stderr] Compiling icu_collections v2.1.1 [INFO] [stderr] Compiling icu_locale_core v2.1.1 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling atom_syndication v0.12.7 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling icu_provider v2.1.1 [INFO] [stderr] Compiling js-sys v0.3.82 [INFO] [stderr] Compiling console_error_panic_hook v0.1.7 [INFO] [stderr] Compiling icu_normalizer v2.1.1 [INFO] [stderr] Compiling icu_properties v2.1.1 [INFO] [stderr] Compiling rss v2.0.12 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling assert-json-diff v2.0.2 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling opentelemetry_sdk v0.31.0 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling idna v1.1.0 [INFO] [stderr] Compiling url v2.5.7 [INFO] [stderr] Compiling tokio-util v0.7.16 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling async-compression v0.4.32 [INFO] [stderr] Compiling tower-http v0.6.6 [INFO] [stderr] Compiling tonic v0.14.2 [INFO] [stderr] Compiling h2 v0.4.12 [INFO] [stderr] Compiling web-sys v0.3.82 [INFO] [stderr] Compiling wasm-bindgen-futures v0.4.55 [INFO] [stderr] Compiling serde-wasm-bindgen v0.6.5 [INFO] [stderr] Compiling opentelemetry-stdout v0.31.0 [INFO] [stderr] Compiling tracing-opentelemetry v0.32.0 [INFO] [stderr] Compiling tonic-prost v0.14.2 [INFO] [stderr] Compiling opentelemetry-proto v0.31.0 [INFO] [stderr] Compiling worker-sys v0.6.7 [INFO] [stderr] Compiling hyper v1.7.0 [INFO] [stderr] Compiling wasm-streams v0.4.2 [INFO] [stderr] Compiling worker-macros v0.6.7 [INFO] [stderr] Compiling worker v0.6.7 [INFO] [stderr] Compiling hyper-util v0.1.17 [INFO] [stderr] Compiling hyper-rustls v0.27.7 [INFO] [stderr] Compiling mockito v1.7.0 [INFO] [stderr] Compiling reqwest v0.12.24 [INFO] [stderr] Compiling test-utils v0.0.0 (/opt/rustwide/workdir/test-utils) [INFO] [stderr] Compiling opentelemetry-http v0.31.0 [INFO] [stderr] Compiling opentelemetry-otlp v0.31.0 [INFO] [stderr] Compiling rssfilter-telemetry v0.0.0 (/opt/rustwide/workdir/rssfilter-telemetry) [INFO] [stderr] Compiling filter-rss-feed v0.0.0 (/opt/rustwide/workdir/filter-rss-feed) [INFO] [stderr] Compiling workers-rssfilter v0.0.0 (/opt/rustwide/workdir/workers-rssfilter) [INFO] [stderr] Compiling rssfilter-cli v0.0.0 (/opt/rustwide/workdir/rssfilter-cli) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 50s [INFO] running `Command { std: "docker" "inspect" "54e0a21902765fe218aeedd3b188e41323c2d411e586b2f5b574504f41d9c2a2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "54e0a21902765fe218aeedd3b188e41323c2d411e586b2f5b574504f41d9c2a2", kill_on_drop: false }` [INFO] [stdout] 54e0a21902765fe218aeedd3b188e41323c2d411e586b2f5b574504f41d9c2a2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b922d18d7f3a51d7259e34186fa0f7ff7d4c7b86dafc57da89ba2aabb5ec4dea [INFO] running `Command { std: "docker" "start" "-a" "b922d18d7f3a51d7259e34186fa0f7ff7d4c7b86dafc57da89ba2aabb5ec4dea", kill_on_drop: false }` [INFO] [stderr] Compiling test-case-core v3.3.1 [INFO] [stderr] Compiling dtor-proc-macro v0.0.6 [INFO] [stderr] Compiling ctor-proc-macro v0.0.7 [INFO] [stderr] Compiling temp-env v0.3.6 [INFO] [stderr] Compiling wasm-bindgen-test-macro v0.3.55 [INFO] [stderr] Compiling test-utils v0.0.0 (/opt/rustwide/workdir/test-utils) [INFO] [stderr] Compiling filter-rss-feed v0.0.0 (/opt/rustwide/workdir/filter-rss-feed) [INFO] [stderr] Compiling dtor v0.1.0 [INFO] [stderr] Compiling wasm-bindgen-test v0.3.55 [INFO] [stderr] Compiling ctor v0.6.1 [INFO] [stderr] Compiling test-case-macros v3.3.1 [INFO] [stderr] Compiling test-case v3.3.1 [INFO] [stderr] Compiling rssfilter-telemetry v0.0.0 (/opt/rustwide/workdir/rssfilter-telemetry) [INFO] [stderr] Compiling rssfilter-cli v0.0.0 (/opt/rustwide/workdir/rssfilter-cli) [INFO] [stderr] Compiling workers-rssfilter v0.0.0 (/opt/rustwide/workdir/workers-rssfilter) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 10.48s [INFO] running `Command { std: "docker" "inspect" "b922d18d7f3a51d7259e34186fa0f7ff7d4c7b86dafc57da89ba2aabb5ec4dea", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b922d18d7f3a51d7259e34186fa0f7ff7d4c7b86dafc57da89ba2aabb5ec4dea", kill_on_drop: false }` [INFO] [stdout] b922d18d7f3a51d7259e34186fa0f7ff7d4c7b86dafc57da89ba2aabb5ec4dea [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d7459643433ff865eed5978fac701a94d1a2ecbea9808123d94742a4cea85497 [INFO] running `Command { std: "docker" "start" "-a" "d7459643433ff865eed5978fac701a94d1a2ecbea9808123d94742a4cea85497", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.34s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/filter_rss_feed-ce58015642c4bddc) [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.519550Z","level":"DEBUG","message":"","name":"BatchSpanProcessor.ThreadStarted","interval_in_millisecs":"5000","max_export_batch_size":512,"max_queue_size":2048,"target":"opentelemetry_sdk"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.519785Z","level":"DEBUG","message":"","name":"BatchSpanProcessor.ExportingDueToShutdown","target":"opentelemetry_sdk"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.519892Z","level":"DEBUG","message":"","name":"BatchSpanProcessor.ThreadExiting","reason":"ShutdownRequested","target":"opentelemetry_sdk"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.519987Z","level":"DEBUG","message":"","name":"BatchSpanProcessor.ThreadStopped","target":"opentelemetry_sdk"} [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_basic ... 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_empty_iterator ... 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::custom_status ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::dynamic_lowercase ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::dynamic_uppercase ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::hit_lowercase ... ok [INFO] [stdout] test fake_http_client::tests::test_fake_http_client_not_found ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_invalid_utf8 ... 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_decode_success::hit_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_decode_success::miss_uppercase ... 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::unknown_status_with_hyphen ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::updating_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::hit_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::expired_displays_as_uppercase ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_display::revalidated_displays_as_uppercase ... ok [INFO] [stdout] test fake_http_client::tests::test_fake_http_client_with_headers ... 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_encode::hit_encodes_to_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::revalidated_encodes_to_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::dynamic_displays_as_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_encode::bypass_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_encode::expired_encodes_to_uppercase ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_encode_other_with_invalid_characters ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_header_map_integration ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_header_map_replace ... 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_roundtrip_encode_decode ... ok [INFO] [stdout] test http_client::tests::test_cache_config_custom ... ok [INFO] [stdout] test tests::test_feed_size_validation ... ok [INFO] [stdout] test tests::test_content_type_validation_success ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_decode_success::expired_uppercase ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_header_name ... ok [INFO] [stdout] test header_cf_cache_status::tests::test_multiple_header_values_takes_first ... ok [INFO] [stdout] test http_client::tests::test_cache_config_default ... ok [INFO] [stdout] test fake_http_client::tests::test_fake_http_client_builder_convenience_methods ... ok [INFO] [stdout] test tests::test_feed_size_validation_success ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.691935Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:37695","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:37695","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:37695","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.692141Z","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:37695","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.692645Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:37695/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:37695","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.697888Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:37695\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.697993Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] test tests::test_server_error ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.702414Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:39853","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:39853","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:39853","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.702630Z","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:39853","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.703010Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:39853/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:39853","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.704197Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:39853\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.704268Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.705003Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.705139Z","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":"2025-11-11T07:54:57.705446Z","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":"2025-11-11T07:54:57.705742Z","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":"2025-11-11T07:54:57.706050Z","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::guid_filter_only ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.807240Z","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":"2025-11-11T07:54:57.814890Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:36599/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.818785Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:36599\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.818903Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] test http_client::tests::reqwest_tests::test_custom_cache_config ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.835944Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:46399","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:46399","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:46399","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.836133Z","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:46399","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.836568Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:46399/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:46399","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.841681Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:36777","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:36777","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:36777","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.841857Z","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:36777","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.842215Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:36777/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:36777","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.843857Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:36777\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.844024Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.847235Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.848123Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:46399\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.848203Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.848851Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.848983Z","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":"2025-11-11T07:54:57.849263Z","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":"2025-11-11T07:54:57.849797Z","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":"2025-11-11T07:54:57.849860Z","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::link_filter_only ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.847428Z","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":"2025-11-11T07:54:57.853841Z","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":"2025-11-11T07:54:57.854094Z","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":"2025-11-11T07:54:57.854424Z","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":"2025-11-11T07:54:57.862644Z","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":"2025-11-11T07:54:57.863053Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:41171/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.864135Z","level":"DEBUG","message":"Request received: \r\nGET /test\r\nuser-agent: test-agent\r\nauthorization: Bearer token123\r\naccept: */*\r\naccept-encoding: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:41171\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.864213Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_custom_headers ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.873226Z","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":"2025-11-11T07:54:57.873712Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:34667/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.875649Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:37665","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:37665","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:37665","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.879591Z","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:37665","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.880240Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:37665/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:37665","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.885866Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:37665\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.885952Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.886824Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.887023Z","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":"2025-11-11T07:54:57.887655Z","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":"2025-11-11T07:54:57.888349Z","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":"2025-11-11T07:54:57.908861Z","level":"DEBUG","message":"Requesting URL: http://127.0.0.1:38385","target":"filter_rss_feed","span":{"headers":"{}","url":"http://127.0.0.1:38385","name":"fetch"},"spans":[{"headers":"{}","url":"http://127.0.0.1:38385","name":"fetch"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.909163Z","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:38385","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.909640Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:38385/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"headers":"{}","url":"http://127.0.0.1:38385","name":"fetch"},{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.910861Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:38385\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.910995Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.911804Z","level":"DEBUG","message":"Request received: \r\nPOST /test\r\ncontent-type: application/json\r\naccept: */*\r\nuser-agent: filter-rss-feed https://github.com/iainlane/filter-rss-feed\r\naccept-encoding: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:34667\r\ncontent-length: 16\r\n{\"test\": \"data\"}\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.911856Z","level":"DEBUG","message":"Received response","status":"200","target":"filter_rss_feed"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.911960Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.912138Z","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":"2025-11-11T07:54:57.912537Z","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] test http_client::tests::reqwest_tests::test_reqwest_client_post_with_body ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.912971Z","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":"2025-11-11T07:54:57.913162Z","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":"2025-11-11T07:54:57.913240Z","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::title_filter_only_both_items_match ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.937429Z","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":"2025-11-11T07:54:57.939863Z","level":"DEBUG","message":"starting new connection: http://127.0.0.1:40379/","log.target":"reqwest::connect","log.module_path":"reqwest::connect","log.file":"/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/reqwest-0.12.24/src/connect.rs","log.line":882,"target":"reqwest::connect","span":{"name":"send"},"spans":[{"name":"send"}]} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.940604Z","level":"DEBUG","message":"Making HTTP request via reqwest","target":"filter_rss_feed::http_client::reqwest_client","span":{"name":"send"},"spans":[{"name":"send"}]} [INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_error_handling ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.941866Z","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: gzip, br, zstd, deflate\r\nhost: 127.0.0.1:40379\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.0/src/server.rs","log.line":527,"target":"mockito::server"} [INFO] [stdout] {"timestamp":"2025-11-11T07:54:57.941944Z","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.0/src/server.rs","log.line":547,"target":"mockito::server"} [INFO] [stdout] test http_client::tests::reqwest_tests::test_reqwest_client_get ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.42s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rssfilter-ef93b6b85dcbf40b) [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rssfilter_telemetry-3bb1aa51cae7fe55) [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 20 tests [INFO] [stdout] test tests::test_log_config_worker_precedence::fallback_to_env_when_worker_vars_invalid ... ok [INFO] [stdout] test tests::test_log_config_worker_precedence::all_unset ... ok [INFO] [stdout] test tests::test_log_config_worker_precedence::env_vars_only ... ok [INFO] [stdout] test tests::test_integration_with_actual_logging ... 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_log_config_worker_precedence::worker_vars_override_env_vars ... 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_format_from_str_valid::json_uppercase ... 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_format_from_str_invalid::invalid_format ... ok [INFO] [stdout] test tests::test_log_format_from_str_valid::pretty_uppercase ... 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_tracing_error_display ... ok [INFO] [stdout] test tests::test_writer_injection ... ok [INFO] [stdout] test tests::test_log_format_from_str_valid::json_lowercase ... ok [INFO] [stdout] test tests::test_env_filter_creation ... ok [INFO] [stdout] test tests::test_extract_context_from_headers ... ok [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_utils-46904cbeaf7ffc4c) [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] [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/workers_rssfilter-6c4ae6e54cc6891f) [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::filter_host_header_retaining_content_type ... ok [INFO] [stdout] test filter::tests::test_filter_request_headers::filter_out_host_header ... 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::filter_host_and_x_custom_header_headers_retaining_content_type ... ok [INFO] [stdout] test filter::tests::test_filter_request_headers::incoming_user_agent_header_is_overwritten ... 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_with_header_map ... ok [INFO] [stdout] test filter::tests::test_filter_request_headers::no_headers_to_filter ... ok [INFO] [stdout] test integration_tests::test_404 ... ok [INFO] [stdout] test integration_tests::test_parameter_validation_invalid_regex ... ok [INFO] [stdout] test request_validation_integration_tests::test_validate_request_content_type_header ... ok [INFO] [stdout] test integration_tests::test_parameter_validation_no_params ... ok [INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::head_method ... ok [INFO] [stdout] test integration_tests::test_parameter_validation_success ... 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_method_not_allowed::post_method ... ok [INFO] [stdout] test integration_tests::test_parameter_validation_no_filters ... ok [INFO] [stdout] test request_validation_integration_tests::test_validate_request_method_not_allowed::patch_method ... ok [INFO] [stdout] test integration_tests::test_parameter_validation_no_url ... 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::put_method ... ok [INFO] [stdout] test integration_tests::test_url_encoding_in_parameters ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.480648Z","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":"37b7174f-276b-49a2-94ee-3f2448540955","name":"request"},"spans":[{"request_id":"37b7174f-276b-49a2-94ee-3f2448540955","name":"request"}]} [INFO] [stdout] test integration_tests::test_parameter_validation_multiple_regexes ... 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::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_various_wrong_paths::api_endpoint ... 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_various_wrong_paths::status_check ... 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_method_not_allowed::delete_method ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.563540Z","level":"INFO","message":"Request completed","url":"http://127.0.0.1:36185","status":"307 Temporary Redirect","duration_ms":"86","target":"workers_rssfilter","span":{"name":"rss_handler"},"spans":[{"request_id":"7b410242-f6a9-48e1-a7ee-32e0f5b04f63","name":"request"},{"name":"rss_handler"}]} [INFO] [stdout] test integration_tests::test_header_passthrough ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.596419Z","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":"2025-11-11T07:54:58.596941Z","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 integration_tests::test_rss_filtering_link ... ok [INFO] [stdout] test integration_tests::test_http_error_handling ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.634493Z","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":"2025-11-11T07:54:58.634968Z","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":"2025-11-11T07:54:58.637192Z","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":"2025-11-11T07:54:58.637900Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":3,"n_items_at_end":2,"n_items_filtered":1,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]} [INFO] [stdout] test integration_tests::test_rss_filtering_basic ... ok [INFO] [stdout] test integration_tests::test_rss_filtering_guid ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.658432Z","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":"2025-11-11T07:54:58.659247Z","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 integration_tests::test_empty_regex_matches ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.688663Z","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":"2025-11-11T07:54:58.689392Z","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 integration_tests::test_regex_no_matches ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.715017Z","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":"2025-11-11T07:54:58.715630Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":3,"n_items_at_end":1,"n_items_filtered":2,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]} [INFO] [stdout] test integration_tests::test_mixed_filter_types ... ok [INFO] [stdout] {"timestamp":"2025-11-11T07:54:58.760583Z","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":"2025-11-11T07:54:58.761478Z","level":"INFO","message":"Filtered items from RSS feed","channel_url":"http://www.example.com/","n_items_at_start":3,"n_items_at_end":1,"n_items_filtered":2,"target":"filter_rss_feed","span":{"name":"filter"},"spans":[{"status":"200 OK","name":"filter_response"},{"name":"filter"}]} [INFO] [stdout] test integration_tests::test_filter_link_multiple ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.31s [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" "d7459643433ff865eed5978fac701a94d1a2ecbea9808123d94742a4cea85497", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d7459643433ff865eed5978fac701a94d1a2ecbea9808123d94742a4cea85497", kill_on_drop: false }` [INFO] [stdout] d7459643433ff865eed5978fac701a94d1a2ecbea9808123d94742a4cea85497