[INFO] fetching crate rsrpp 2.0.0...
[INFO] testing rsrpp-2.0.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate rsrpp 2.0.0 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate rsrpp 2.0.0
[INFO] finished tweaking crates.io crate rsrpp 2.0.0
[INFO] tweaked toml for crates.io crate rsrpp 2.0.0 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rsrpp 2.0.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rsrpp 2.0.0 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17
[INFO] running `Command { std: "docker" "start" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", 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" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", 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" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling smallvec v1.16.0
[INFO] [stderr]    Compiling find-msvc-tools v0.1.11
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling siphasher v1.0.3
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling zerovec-derive v0.11.6
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling cc v1.4.4
[INFO] [stderr]    Compiling futures-sink v0.3.34
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling displaydoc v0.2.7
[INFO] [stderr]    Compiling phf_shared v0.13.1
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling phf_generator v0.13.1
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling futures-channel v0.3.34
[INFO] [stderr]    Compiling futures-macro v0.3.34
[INFO] [stderr]    Compiling futures-task v0.3.34
[INFO] [stderr]    Compiling futures-io v0.3.34
[INFO] [stderr]    Compiling aws-lc-rs v1.18.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling phf_codegen v0.13.1
[INFO] [stderr]    Compiling rustls-pki-types v1.15.1
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling glob v0.3.4
[INFO] [stderr]    Compiling openssl v0.10.81
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling rustls v0.23.43
[INFO] [stderr]    Compiling clang-sys v1.9.1
[INFO] [stderr]    Compiling indexmap v2.14.1
[INFO] [stderr]    Compiling aws-lc-sys v0.45.0
[INFO] [stderr]    Compiling openssl-sys v0.9.117
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling string_cache_codegen v0.6.1
[INFO] [stderr]    Compiling num-integer v0.1.47
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling crc32fast v1.5.1
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling web_atoms v0.2.6
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling approx v0.5.1
[INFO] [stderr]    Compiling simba v0.9.1
[INFO] [stderr]    Compiling clang v2.1.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling miniz_oxide v0.9.1
[INFO] [stderr]    Compiling string_cache v0.9.0
[INFO] [stderr]    Compiling num-rational v0.4.2
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling matrixmultiply v0.3.11
[INFO] [stderr]    Compiling dtoa v1.0.11
[INFO] [stderr]    Compiling ipnet v2.12.1
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling bit-vec v0.6.3
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling opencv-binding-generator v0.105.0
[INFO] [stderr]    Compiling dtoa-short v0.3.5
[INFO] [stderr]    Compiling bit-set v0.5.3
[INFO] [stderr]    Compiling sha1 v0.10.7
[INFO] [stderr]    Compiling flate2 v1.1.10
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling phf_macros v0.13.1
[INFO] [stderr]    Compiling tokio-util v0.7.19
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling phf v0.13.1
[INFO] [stderr]    Compiling zerovec v0.11.8
[INFO] [stderr]    Compiling zerotrie v0.2.5
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling h2 v0.4.19
[INFO] [stderr]    Compiling tinystr v0.8.4
[INFO] [stderr]    Compiling potential_utf v0.1.6
[INFO] [stderr]    Compiling icu_locale_core v2.3.0
[INFO] [stderr]    Compiling icu_collections v2.3.0
[INFO] [stderr]    Compiling nalgebra v0.33.3
[INFO] [stderr]    Compiling icu_provider v2.3.1
[INFO] [stderr]    Compiling icu_properties v2.3.0
[INFO] [stderr]    Compiling icu_normalizer v2.3.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling cssparser-macros v0.7.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling hyper v1.11.1
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling tendril v0.5.1
[INFO] [stderr]    Compiling rustls-native-certs v0.8.4
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling selectors v0.38.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling weezl v0.1.12
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling pxfm v0.1.30
[INFO] [stderr]    Compiling utf-8 v0.7.6
[INFO] [stderr]    Compiling zune-core v0.5.3
[INFO] [stderr]    Compiling portable-atomic v1.15.0
[INFO] [stderr]    Compiling gif v0.14.2
[INFO] [stderr]    Compiling tungstenite v0.28.0
[INFO] [stderr]    Compiling zune-jpeg v0.5.15
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling markup5ever v0.39.0
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling tower-http v0.6.11
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling moxcms v0.8.1
[INFO] [stderr]    Compiling cssparser v0.37.0
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling opencv v0.100.1
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling fancy-regex v0.13.0
[INFO] [stderr]    Compiling test-log-core v0.2.21
[INFO] [stderr]    Compiling http-body-util v0.1.5
[INFO] [stderr]    Compiling bstr v1.13.1
[INFO] [stderr]    Compiling servo_arc v0.4.3
[INFO] [stderr]    Compiling either v1.18.0
[INFO] [stderr]    Compiling byteorder-lite v0.1.0
[INFO] [stderr]    Compiling cpufeatures v0.3.1
[INFO] [stderr]    Compiling chacha20 v0.10.2
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling tiktoken-rs v0.9.1
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling test-log-macros v0.2.21
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling tokio-tungstenite v0.28.0
[INFO] [stderr]    Compiling html5ever v0.39.0
[INFO] [stderr]    Compiling ndarray v0.16.1
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling kalmanfilt v0.3.0
[INFO] [stderr]    Compiling lstsq v0.6.0
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling derive-new v0.7.0
[INFO] [stderr]    Compiling getopts v0.2.24
[INFO] [stderr]    Compiling console v0.16.4
[INFO] [stderr]    Compiling futures-executor v0.3.34
[INFO] [stderr]    Compiling gaussfilt v0.1.3
[INFO] [stderr]    Compiling ego-tree v0.11.0
[INFO] [stderr]    Compiling unit-prefix v0.5.2
[INFO] [stderr]    Compiling indicatif v0.18.6
[INFO] [stderr]    Compiling sci-rs v0.4.1
[INFO] [stderr]    Compiling scraper v0.27.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling futures v0.3.34
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling test-log v0.2.21
[INFO] [stderr]    Compiling quick-xml v0.42.0
[INFO] [stderr]    Compiling base64 v0.23.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.15
[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 openai-tools v3.0.0
[INFO] [stderr]    Compiling rsrpp v2.0.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `base64`
[INFO] [stderr]  --> Cargo.toml:4:15
[INFO] [stderr]   |
[INFO] [stderr] 4 | [dependencies.base64]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `base64`
[INFO] [stderr] warning: unused dependency `chrono`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.chrono]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `chrono`
[INFO] [stderr] warning: unused dependency `test-log`
[INFO] [stderr]   --> Cargo.toml:57:15
[INFO] [stderr]    |
[INFO] [stderr] 57 | [dependencies.test-log]
[INFO] [stderr]    |               ^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `test-log`
[INFO] [stderr] warning: `rsrpp` (manifest) generated 3 warnings
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 8m 21s
[INFO] running `Command { std: "docker" "inspect" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", 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" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling rsrpp v2.0.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]     --> src/parser.rs:1267:17
[INFO] [stdout]      |
[INFO] [stdout] 1267 |             let mut page = Page::new(595.0, 842.0, page_number);
[INFO] [stdout]      |                 ----^^^^
[INFO] [stdout]      |                 |
[INFO] [stdout]      |                 help: remove this `mut`
[INFO] [stdout]      |
[INFO] [stdout]      = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] warning: unused dependency `base64`
[INFO] [stderr]  --> Cargo.toml:4:15
[INFO] [stderr]   |
[INFO] [stderr] 4 | [dependencies.base64]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `base64`
[INFO] [stderr] warning: unused dependency `chrono`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.chrono]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `chrono`
[INFO] [stderr] warning: unused dependency `test-log`
[INFO] [stderr]   --> Cargo.toml:57:15
[INFO] [stderr]    |
[INFO] [stderr] 57 | [dependencies.test-log]
[INFO] [stderr]    |               ^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `test-log`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `rsrpp` (manifest) generated 3 warnings
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 42.29s
[INFO] running `Command { std: "docker" "inspect" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", 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" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]     --> src/parser.rs:1267:17
[INFO] [stderr]      |
[INFO] [stderr] 1267 |             let mut page = Page::new(595.0, 842.0, page_number);
[INFO] [stderr]      |                 ----^^^^
[INFO] [stderr]      |                 |
[INFO] [stderr]      |                 help: remove this `mut`
[INFO] [stderr]      |
[INFO] [stderr]      = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `rsrpp` (lib test) generated 1 warning (run `cargo fix --lib -p rsrpp --tests` to apply 1 suggestion)
[INFO] [stderr] warning: unused dependency `base64`
[INFO] [stderr]  --> Cargo.toml:4:15
[INFO] [stderr]   |
[INFO] [stderr] 4 | [dependencies.base64]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `base64`
[INFO] [stderr] warning: unused dependency `chrono`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.chrono]
[INFO] [stderr]   |               ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `chrono`
[INFO] [stderr] warning: unused dependency `test-log`
[INFO] [stderr]   --> Cargo.toml:57:15
[INFO] [stderr]    |
[INFO] [stderr] 57 | [dependencies.test-log]
[INFO] [stderr]    |               ^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `test-log`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `rsrpp` (manifest) generated 3 warnings
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.35s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/rsrpp/7b6d38d03fcc8b03/out/rsrpp-7b6d38d03fcc8b03)
[INFO] [stdout] 
[INFO] [stdout] running 163 tests
[INFO] [stdout] test cleaner::tests::test_caption_line_span_stops_where_the_leading_changes ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_is_zero_without_a_caption ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_covers_a_whole_caption ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_measures_the_captions_own_font ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_stops_at_the_table ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_survives_a_first_line_without_words ... ok
[INFO] [stdout] test cleaner::tests::test_caption_line_span_stops_below_a_one_line_caption ... ok
[INFO] [stdout] test cleaner::tests::test_classify_blocks ... ok
[INFO] [stdout] test config::tests::test_a_clone_does_not_delete_the_directory_out_from_under_the_original ... ok
[INFO] [stdout] test cleaner::tests::test_is_caption_figure_patterns ... ok
[INFO] [stdout] test cleaner::tests::test_is_caption_non_captions ... ok
[INFO] [stdout] test cleaner::tests::test_is_caption_other_patterns ... ok
[INFO] [stdout] test config::tests::test_clean_files_is_idempotent ... ok
[INFO] [stdout] test config::tests::test_coverage_is_none_before_parsing ... ok
[INFO] [stdout] test config::tests::test_coverage_is_not_polluted_by_a_previous_document ... ok
[INFO] [stdout] test cleaner::tests::test_is_caption_appendix_patterns ... ok
[INFO] [stdout] test config::tests::test_blank_drops_are_not_recorded ... ok
[INFO] [stdout] test config::tests::test_clean_files_removes_files_nothing_tracked ... ok
[INFO] [stdout] test config::tests::test_coverage_counts_only_alphanumerics ... ok
[INFO] [stdout] test config::tests::test_coverage_is_clamped_when_drops_exceed_source ... ok
[INFO] [stdout] test config::tests::test_reset_document_state_keeps_settings ... ok
[INFO] [stdout] test config::tests::test_two_configs_do_not_share_a_directory ... ok
[INFO] [stdout] test converter::tests::test_detect_columns_joins_a_run_of_close_bins ... ok
[INFO] [stdout] test config::tests::test_dropping_the_config_removes_the_directory ... ok
[INFO] [stdout] test config::tests::test_new_creates_a_directory_of_its_own ... ok
[INFO] [stdout] test config::tests::test_reset_document_state_clears_everything_derived_from_a_document ... ok
[INFO] [stdout] test cleaner::tests::test_is_caption_table_patterns ... ok
[INFO] [stdout] test converter::tests::test_check_output_limits_stops_counting_at_the_limit ... ok
[INFO] [stdout] test converter::tests::test_detect_columns_merges_a_margin_split_across_bins ... ok
[INFO] [stdout] test converter::tests::test_detect_columns_reads_the_margins_off_the_body_text ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_rejects_labels_that_only_vouch_for_each_other ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_learns_the_indent_of_numbered_headings ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_keeps_a_heading_centred_on_the_column ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_rejects_a_label_inside_a_figure ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_judges_a_document_at_the_threshold ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_does_not_join_a_heading_across_pages ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_stands_down_on_a_document_with_few_headings ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_stands_down_when_it_would_reject_three_of_five ... ok
[INFO] [stdout] test converter::tests::test_is_arxiv_stamp_matches_the_side_stamp ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_stands_down_on_an_even_split ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_still_joins_small_caps_on_one_line ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_stands_down_one_candidate_short ... ok
[INFO] [stdout] test converter::tests::test_detect_sections_removes_two_of_five_candidates ... ok
[INFO] [stdout] test converter::tests::test_is_figure_sublabel_matches_parenthesised_labels ... ok
[INFO] [stdout] test converter::tests::test_is_section_label_matches_only_a_lone_label ... ok
[INFO] [stdout] test converter::tests::test_poppler_limits_scale_with_the_page_count ... ok
[INFO] [stdout] test converter::tests::test_pdf2html_file ... FAILED
[INFO] [stdout] test converter::tests::test_run_poppler_kills_a_process_that_exceeds_the_byte_limit ... ok
[INFO] [stdout] test converter::tests::test_invalid_pdf_url ... ok
[INFO] [stdout] test converter::tests::test_pdf2html_url ... FAILED
[INFO] [stdout] test converter::tests::test_save_pdf_check_commands ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_judges_each_page_on_its_own ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_keeps_two_bands_of_text_apart ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_lets_the_paper_speak_for_a_thin_page ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_puts_a_single_column_page_in_reading_order ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_reads_a_single_column_paper_as_one_column ... ok
[INFO] [stdout] test extracter::tests::test_adjst_columns_still_reads_a_two_column_paper_as_two ... ok
[INFO] [stdout] test converter::tests::test_run_poppler_returns_output_when_within_limits ... ok
[INFO] [stdout] test extracter::tests::test_coordinate_is_intercept ... ok
[INFO] [stdout] test extracter::tests::test_ninth_decile_takes_the_nearest_rank ... ok
[INFO] [stdout] test extracter::tests::test_rule_cluster_rejects_a_different_horizontal_extent ... ok
[INFO] [stdout] test extracter::tests::test_adjust_columns ... FAILED
[INFO] [stdout] test extracter::tests::test_rule_cluster_rejects_chained_horizontal_drift ... ok
[INFO] [stdout] test extracter::tests::test_rule_cluster_tracks_lowest_rule_for_the_gap_test ... ok
[INFO] [stdout] test llm::tests::test_align_llm_text_to_blocks ... ok
[INFO] [stdout] test extracter::tests::test_rule_normalizes_reversed_endpoints ... ok
[INFO] [stdout] test llm::tests::test_ascii_subscripts ... ok
[INFO] [stdout] test llm::tests::test_contains_math ... ok
[INFO] [stdout] test llm::tests::test_ascii_exponents ... ok
[INFO] [stdout] test llm::tests::test_context_greek_nearby ... ok
[INFO] [stdout] test llm::tests::test_context_equation_preamble ... ok
[INFO] [stdout] test llm::tests::test_convert_latex_to_math_tags_display ... ok
[INFO] [stdout] test llm::tests::test_context_statistical_report ... ok
[INFO] [stdout] test llm::tests::test_convert_latex_to_math_tags_mixed ... ok
[INFO] [stdout] test llm::tests::test_convert_latex_to_math_tags_inline ... ok
[INFO] [stdout] test converter::tests::test_run_poppler_kills_a_process_that_exceeds_the_file_limit ... ok
[INFO] [stdout] test llm::tests::test_find_best_alignment_exact ... ok
[INFO] [stdout] test llm::tests::test_find_best_alignment_no_match ... ok
[INFO] [stdout] test llm::tests::test_get_model_id_custom ... ok
[INFO] [stdout] test llm::tests::test_get_model_id_default ... ok
[INFO] [stdout] test llm::tests::test_heuristic_regression_suite ... ok
[INFO] [stdout] test llm::tests::test_letter_fractions ... ok
[INFO] [stdout] test llm::tests::test_find_best_alignment_fuzzy ... ok
[INFO] [stdout] test llm::tests::test_mark_math_heuristic_no_math ... ok
[INFO] [stdout] test llm::tests::test_mark_math_heuristic_operators ... ok
[INFO] [stdout] test llm::tests::test_mark_math_heuristic_greek ... ok
[INFO] [stdout] test llm::tests::test_multichar_functions ... ok
[INFO] [stdout] test llm::tests::test_no_false_positive_dates ... ok
[INFO] [stdout] test llm::tests::test_norm_notation ... ok
[INFO] [stdout] test llm::tests::test_no_false_positive_parenthetical ... ok
[INFO] [stdout] test llm::tests::test_no_false_positive_statistics ... ok
[INFO] [stdout] test llm::tests::test_parse_references_json_clean ... ok
[INFO] [stdout] test llm::tests::test_parse_references_json_invalid ... ok
[INFO] [stdout] test llm::tests::test_parse_references_json_multiple ... ok
[INFO] [stdout] test llm::tests::test_normalize_for_matching ... ok
[INFO] [stdout] test llm::tests::test_parse_references_json_with_surrounding_text ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_display_block ... ok
[INFO] [stdout] test llm::tests::test_real_math_still_detected ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_already_latex ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_mixed ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_operators ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_preserves_outside ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_all_suffixes ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_greek ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_already_hyphenated ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_direct_connection ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_mixed_cases ... ok
[INFO] [stdout] test llm::tests::test_unicode_to_latex_subscript_superscript ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_multiple_occurrences ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_no_suffix ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_space_separated ... ok
[INFO] [stdout] test models::tests::test_fix_suffix_hyphens_suffix_alone ... ok
[INFO] [stdout] test parser::tests::test_extract_references_conditional ... ok
[INFO] [stdout] test parser::tests::test_filter_table_regions_keeps_captions_inside_the_region ... ok
[INFO] [stdout] test parser::tests::test_filter_table_regions_records_drops_in_document_order ... ok
[INFO] [stdout] test parser::tests::test_filter_table_regions_splits_a_block_holding_caption_and_table ... ok
[INFO] [stdout] test parser::tests::test_filter_table_regions_keeps_prose_over_overlapping_table_region ... ok
[INFO] [stdout] test parser::tests::test_llm_section_validation_conditional ... ok
[INFO] [stdout] test parser::tests::test_filter_table_regions_still_drops_table_cells ... ok
[INFO] [stdout] test parser::tests::test_math_density_estimation ... ok
[INFO] [stdout] test converter::tests::test_run_poppler_kills_a_process_that_exceeds_the_time_limit ... ok
[INFO] [stdout] test converter::tests::test_save_pdf_1 ... FAILED
[INFO] [stdout] test parser::tests::test_merge_sections ... ok
[INFO] [stdout] test parser::tests::test_merge_sections_filters_within_range ... ok
[INFO] [stdout] test parser::tests::test_merge_sections_preserves_unseen_pages ... ok
[INFO] [stdout] test parser::tests::test_normalize_section_key_drops_the_section_number ... ok
[INFO] [stdout] test parser::tests::test_normalize_section_key_keeps_distinct_headings_distinct ... ok
[INFO] [stdout] test parser::tests::test_normalize_section_key_matches_split_small_caps ... ok
[INFO] [stdout] test converter::tests::test_save_pdf_2 ... FAILED
[INFO] [stdout] test converter::tests::test_save_pdf_3 ... FAILED
[INFO] [stdout] test converter::tests::test_save_pdf_4 ... FAILED
[INFO] [stdout] test parser::tests::test_parse_1 ... FAILED
[INFO] [stdout] test parser::tests::test_parse_2 ... FAILED
[INFO] [stdout] test parser::tests::test_math_markup_heuristic_corpus ... FAILED
[INFO] [stdout] test parser::tests::test_parse_extract_textarea_keeps_a_section_title_outside_the_area ... ok
[INFO] [stdout] test parser::tests::test_parse_extract_textarea_spares_a_heading_wherever_it_was_found ... ok
[INFO] [stdout] test parser::tests::test_collect_references_text ... FAILED
[INFO] [stdout] test parser::tests::test_math_markup_latex_format ... FAILED
[INFO] [stdout] test parser::tests::test_math_markup_no_math_flag ... FAILED
[INFO] [stdout] test parser::tests::test_paper_output_format ... FAILED
[INFO] [stdout] test parser::tests::test_parse_extract_sections_1 ... FAILED
[INFO] [stdout] test parser::tests::test_parse_extract_sections_2 ... FAILED
[INFO] [stdout] test parser::tests::test_region_is_prose_accepts_body_paragraph ... ok
[INFO] [stdout] test parser::tests::test_region_is_prose_needs_several_prose_lines ... ok
[INFO] [stdout] test parser::tests::test_region_is_prose_on_empty_region ... ok
[INFO] [stdout] test parser::tests::test_unassigned_section_collects_every_drop ... ok
[INFO] [stdout] test parser::tests::test_region_is_prose_rejects_table_cells ... ok
[INFO] [stdout] test parser::tests::test_unassigned_section_is_none_when_nothing_dropped ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_a_dead_process_directory_is_removed ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_a_missing_root_is_not_an_error ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_a_live_process_directory_is_left_alone ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_a_removal_that_fails_is_not_an_error ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_owner_pid_reads_a_real_config_directory ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_names_that_are_not_ours_are_never_touched ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_the_public_sweep_can_be_called_repeatedly ... ok
[INFO] [stdout] test tempdir_sweep::tests::test_this_process_is_reported_as_alive ... ok
[INFO] [stdout] test parser::tests::test_parse_extract_sections_3 ... FAILED
[INFO] [stdout] test parser::tests::test_parse_llm_disabled_fallback ... FAILED
[INFO] [stdout] test parser::tests::test_parse_long_document ... FAILED
[INFO] [stdout] test parser::tests::test_parse_local_sample ... FAILED
[INFO] [stdout] test parser::tests::test_parse_zep_non_standard_format ... FAILED
[INFO] [stdout] test parser::tests::test_pdf_to_json ... FAILED
[INFO] [stdout] test test_utils::tests::test_download ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_pdf2html_file stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_pdf2html_file' (4341) panicked at src/converter.rs:2139:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: reqwest::Error { kind: Request, url: "https://arxiv.org/pdf/1706.03762", source: hyper_util::client::legacy::Error(Connect, ConnectError("dns error", Custom { kind: Uncategorized, error: "failed to lookup address information: Temporary failure in name resolution" })) }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815075cb52 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_pdf2html_file::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d8150760eee - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_pdf2html_file::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_pdf2html_url stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_pdf2html_url' (4342) panicked at src/converter.rs:2129:24:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     0: client error (Connect)
[INFO] [stdout]     1: dns error
[INFO] [stdout]     2: failed to lookup address information: Temporary failure in name resolution
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<reqwest::error::Error>>::from
[INFO] [stdout]    1: rsrpp::converter::save_pdf::{closure#0}
[INFO] [stdout]    2: rsrpp::converter::pdf2html::{closure#0}
[INFO] [stdout]    3: rsrpp::converter::tests::test_pdf2html_url::{closure#0}
[INFO] [stdout]    4: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    5: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    6: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    7: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    8: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    9: <rsrpp::converter::tests::test_pdf2html_url::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   10: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   11: test::run_test::{closure#0}
[INFO] [stdout]   12: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   13: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15: <unknown>
[INFO] [stdout]   16: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815075be16 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_pdf2html_url::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d8150760d5e - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_pdf2html_url::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- extracter::tests::test_adjust_columns stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'extracter::tests::test_adjust_columns' (4395) panicked at src/extracter.rs:707:65:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: error sending request for url (https://arxiv.org/pdf/2411.19655)
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     0: client error (Connect)
[INFO] [stdout]     1: dns error
[INFO] [stdout]     2: failed to lookup address information: Temporary failure in name resolution
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<reqwest::error::Error>>::from
[INFO] [stdout]    1: rsrpp::extracter::tests::test_adjust_columns::{closure#0}
[INFO] [stdout]    2: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    3: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    4: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    5: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    6: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    7: <rsrpp::extracter::tests::test_adjust_columns::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    8: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]    9: test::run_test::{closure#0}
[INFO] [stdout]   10: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   11: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   12: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   13: <unknown>
[INFO] [stdout]   14: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815078138d - rsrpp[e98d250ac6ea3bcd]::extracter::tests::test_adjust_columns::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815078850e - <rsrpp[e98d250ac6ea3bcd]::extracter::tests::test_adjust_columns::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_save_pdf_1 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:31.778281Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:31.778293Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:31.779015Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:31.779021Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.170940Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.172581Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:32.172588Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_save_pdf_1' (4348) panicked at src/converter.rs:2204:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2601.10527.pdf", "2501.13956.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::converter::tests::test_save_pdf_1::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::converter::tests::test_save_pdf_1::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507570f4 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_1::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d81507607de - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_1::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_save_pdf_2 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:31.879178Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:31.880274Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:31.880280Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:31.881764Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.273288Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.273295Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.273298Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_save_pdf_2' (4368) panicked at src/converter.rs:2245:44:
[INFO] [stdout] setup papers: Failed to download 7 files: ["2024.emnlp-main.1060.pdf", "1706.03762.pdf", "2507.02259.pdf", "2308.10379.pdf", "2501.13956.pdf", "2508.13962.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::converter::tests::test_save_pdf_2::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::converter::tests::test_save_pdf_2::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d8150758744 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_2::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815076093e - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_2::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_save_pdf_3 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:31.878789Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:31.879716Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:31.879722Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:31.879726Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.271692Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.271900Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.274193Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_save_pdf_3' (4369) panicked at src/converter.rs:2285:44:
[INFO] [stdout] setup papers: Failed to download 7 files: ["2507.02259.pdf", "2308.10379.pdf", "2024.emnlp-main.1060.pdf", "1706.03762.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::converter::tests::test_save_pdf_3::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::converter::tests::test_save_pdf_3::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d8150759e54 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_3::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d8150760a9e - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_3::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- converter::tests::test_save_pdf_4 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:31.879112Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:31.879699Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:31.879705Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:31.882884Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.282380Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.282581Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.286938Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'converter::tests::test_save_pdf_4' (4373) panicked at src/converter.rs:2331:44:
[INFO] [stdout] setup papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::converter::tests::test_save_pdf_4::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::converter::tests::test_save_pdf_4::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815075b4b4 - rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_4::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d8150760bfe - <rsrpp[e98d250ac6ea3bcd]::converter::tests::test_save_pdf_4::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_1 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.275884Z[0m [32m INFO[0m [2mrsrpp::parser[0m[2m:[0m Parsing PDF: https://arxiv.org/pdf/2308.10379
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_1' (4491) panicked at src/parser.rs:1458:25:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     0: client error (Connect)
[INFO] [stdout]     1: dns error
[INFO] [stdout]     2: failed to lookup address information: Temporary failure in name resolution
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<reqwest::error::Error>>::from
[INFO] [stdout]    1: rsrpp::parser::parse::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_1::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_1::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071768c - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_1::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072c8ee - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_1::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_2 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.280965Z[0m [32m INFO[0m [2mrsrpp::parser[0m[2m:[0m Parsing PDF: https://arxiv.org/pdf/1706.03762
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_2' (4492) panicked at src/parser.rs:1495:25:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     0: client error (Connect)
[INFO] [stdout]     1: dns error
[INFO] [stdout]     2: failed to lookup address information: Temporary failure in name resolution
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<reqwest::error::Error>>::from
[INFO] [stdout]    1: rsrpp::parser::parse::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_2::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_2::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071891c - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_2::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072ca6e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_2::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_math_markup_heuristic_corpus stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.033116Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.033938Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.033944Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.033947Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.428280Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.428294Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:32.428298Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_math_markup_heuristic_corpus' (4458) panicked at src/parser.rs:2086:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2501.13956.pdf", "2601.10527.pdf", "2508.13962.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_math_markup_heuristic_corpus::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_math_markup_heuristic_corpus::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815072661e - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_heuristic_corpus::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072ecce - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_heuristic_corpus::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_collect_references_text stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.090616Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.090898Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.097463Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.098972Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.484510Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.485803Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.493823Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_collect_references_text' (4449) panicked at src/parser.rs:1951:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_collect_references_text::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_collect_references_text::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071e6a0 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_collect_references_text::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e25e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_collect_references_text::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_math_markup_latex_format stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.084463Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.096377Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.096388Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.096392Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.488038Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:32.490389Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.494909Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_math_markup_latex_format' (4463) panicked at src/parser.rs:2152:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_math_markup_latex_format::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_math_markup_latex_format::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815072033f - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_latex_format::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e48e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_latex_format::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_math_markup_no_math_flag stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.106884Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.106896Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.106899Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.107111Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.503931Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:32.503944Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:32.504160Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_math_markup_no_math_flag' (4472) panicked at src/parser.rs:2122:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["2507.02259.pdf", "2024.emnlp-main.1060.pdf", "1706.03762.pdf", "2308.10379.pdf", "2501.13956.pdf", "2601.10527.pdf", "2508.13962.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_math_markup_no_math_flag::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_math_markup_no_math_flag::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507215f0 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_no_math_flag::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e5ee - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_math_markup_no_math_flag::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_paper_output_format stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.616968Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.616982Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.616986Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.616990Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:33.011228Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.011241Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.011431Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_paper_output_format' (4486) panicked at src/parser.rs:1995:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["2507.02259.pdf", "1706.03762.pdf", "2308.10379.pdf", "2024.emnlp-main.1060.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_paper_output_format::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_paper_output_format::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071b85e - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_paper_output_format::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072df9e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_paper_output_format::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_extract_sections_1 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.717986Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.717994Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.717997Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.718455Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.112878Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.112991Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.112996Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_extract_sections_1' (4494) panicked at src/parser.rs:1365:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2507.02259.pdf", "2024.emnlp-main.1060.pdf", "2308.10379.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_extract_sections_1::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_extract_sections_1::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d8150722773 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_1::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e74e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_1::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_extract_sections_2 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.717230Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.717719Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.717725Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.717729Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.109588Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.113899Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:33.114096Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_extract_sections_2' (4495) panicked at src/parser.rs:1394:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2601.10527.pdf", "2501.13956.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_extract_sections_2::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_extract_sections_2::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507237e3 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_2::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e8ae - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_2::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_extract_sections_3 stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.818661Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.818674Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.818678Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.818681Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.213106Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.215548Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:33.215994Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_extract_sections_3' (4497) panicked at src/parser.rs:1423:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2501.13956.pdf", "2601.10527.pdf", "2508.13962.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_extract_sections_3::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_extract_sections_3::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d8150724863 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_3::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072ea0e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_extract_sections_3::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_llm_disabled_fallback stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.880729Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.880743Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:32.880747Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.880750Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:33.275978Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.276357Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.276363Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_llm_disabled_fallback' (4512) panicked at src/parser.rs:1716:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2308.10379.pdf", "2507.02259.pdf", "2024.emnlp-main.1060.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_llm_disabled_fallback::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_llm_disabled_fallback::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507254f0 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_llm_disabled_fallback::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072eb6e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_llm_disabled_fallback::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_long_document stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.968957Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.968969Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.968973Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.969164Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.361388Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.362702Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.363987Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_long_document' (4518) panicked at src/parser.rs:1657:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_long_document::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_long_document::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071ce7b - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_long_document::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072e0fe - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_long_document::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_local_sample stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:32.976756Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:32.976769Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:32.976773Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:32.976776Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.370768Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:33.372133Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.373700Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_local_sample' (4517) panicked at src/parser.rs:1526:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["2024.emnlp-main.1060.pdf", "2507.02259.pdf", "1706.03762.pdf", "2308.10379.pdf", "2601.10527.pdf", "2508.13962.pdf", "2501.13956.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_local_sample::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_local_sample::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d815071ab9b - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_local_sample::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072de3e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_local_sample::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_parse_zep_non_standard_format stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:33.085124Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:33.086127Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:33.086133Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:33.086137Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.483439Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] [2m2026-10-18T16:05:33.483453Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.483457Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_parse_zep_non_standard_format' (4519) panicked at src/parser.rs:1590:44:
[INFO] [stdout] setup papers: Failed to download 7 files: ["2507.02259.pdf", "2024.emnlp-main.1060.pdf", "1706.03762.pdf", "2308.10379.pdf", "2601.10527.pdf", "2508.13962.pdf", "2501.13956.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_parse_zep_non_standard_format::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_parse_zep_non_standard_format::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507272e2 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_zep_non_standard_format::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072ee2e - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_parse_zep_non_standard_format::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- parser::tests::test_pdf_to_json stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:33.427250Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:33.427613Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:33.427620Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.427814Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:33.824055Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.824069Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.824073Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'parser::tests::test_pdf_to_json' (4532) panicked at src/parser.rs:1543:44:
[INFO] [stdout] setup test papers: Failed to download 7 files: ["1706.03762.pdf", "2024.emnlp-main.1060.pdf", "2308.10379.pdf", "2507.02259.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: <rsrpp::test_utils::TestPapers>::setup::{closure#0}
[INFO] [stdout]    2: rsrpp::parser::tests::test_pdf_to_json::{closure#0}
[INFO] [stdout]    3: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    4: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    6: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    7: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    8: <rsrpp::parser::tests::test_pdf_to_json::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]   10: test::run_test::{closure#0}
[INFO] [stdout]   11: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   12: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   13: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   14: <unknown>
[INFO] [stdout]   15: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d81507191f2 - rsrpp[e98d250ac6ea3bcd]::parser::tests::test_pdf_to_json::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d815072cbee - <rsrpp[e98d250ac6ea3bcd]::parser::tests::test_pdf_to_json::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_utils::tests::test_download stdout ----
[INFO] [stdout] [2m2026-10-18T16:05:33.575421Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2024.emnlp-main.1060.pdf: error sending request for url (https://aclanthology.org/2024.emnlp-main.1060.pdf)
[INFO] [stdout] [2m2026-10-18T16:05:33.575434Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2507.02259.pdf: error sending request for url (https://arxiv.org/pdf/2507.02259)
[INFO] [stdout] [2m2026-10-18T16:05:33.575438Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2308.10379.pdf: error sending request for url (https://arxiv.org/pdf/2308.10379)
[INFO] [stdout] [2m2026-10-18T16:05:33.575442Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 1706.03762.pdf: error sending request for url (https://arxiv.org/pdf/1706.03762)
[INFO] [stdout] [2m2026-10-18T16:05:33.972985Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2508.13962.pdf: error sending request for url (https://arxiv.org/pdf/2508.13962)
[INFO] [stdout] [2m2026-10-18T16:05:33.972998Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2501.13956.pdf: error sending request for url (https://arxiv.org/pdf/2501.13956)
[INFO] [stdout] [2m2026-10-18T16:05:33.973172Z[0m [31mERROR[0m [2mrsrpp::test_utils[0m[2m:[0m FAIL 2601.10527.pdf: error sending request for url (https://arxiv.org/pdf/2601.10527)
[INFO] [stdout] 
[INFO] [stdout] thread 'test_utils::tests::test_download' (4551) panicked at src/test_utils.rs:394:44:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Failed to download 7 files: ["2024.emnlp-main.1060.pdf", "2507.02259.pdf", "2308.10379.pdf", "1706.03762.pdf", "2508.13962.pdf", "2501.13956.pdf", "2601.10527.pdf"]
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]    1: rsrpp::test_utils::tests::test_download::{closure#0}
[INFO] [stdout]    2: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]    3: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]    4: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]    5: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]    6: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]    7: <rsrpp::test_utils::tests::test_download::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    8: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]    9: test::run_test::{closure#0}
[INFO] [stdout]   10: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]   11: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   12: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   13: <unknown>
[INFO] [stdout]   14: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d8150ed6591 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5d8150ef056a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5d8150edbe8c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5d8150eacc46 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5d8150ecbd69 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5d815086d240 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5d8150ecc092 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5d8150eaccf2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5d8150ea4e59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5d8150eadfcd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5d8150ef0d4c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5d8150ef0b22 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5d8150838489 - rsrpp[e98d250ac6ea3bcd]::test_utils::tests::test_download::{closure#0}
[INFO] [stdout]   13:     0x5d81507da583 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   14:     0x5d815078bffc - <tokio[eb16e17c865304aa]::runtime::context::scoped::Scoped<tokio[eb16e17c865304aa]::runtime::scheduler::Context>>::set::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x5d81507dabf7 - <tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   16:     0x5d815078ee8d - tokio[eb16e17c865304aa]::runtime::context::runtime::enter_runtime::<<tokio[eb16e17c865304aa]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5d81507b0335 - <tokio[eb16e17c865304aa]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x5d8150843b3e - <rsrpp[e98d250ac6ea3bcd]::test_utils::tests::test_download::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5d815086068b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   20:     0x5d815086d865 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5d8150867154 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5d81508708f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5d8150ed543f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x731758cd2dfa - <unknown>
[INFO] [stdout]   25:     0x731758d663d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     converter::tests::test_pdf2html_file
[INFO] [stdout]     converter::tests::test_pdf2html_url
[INFO] [stdout]     converter::tests::test_save_pdf_1
[INFO] [stdout]     converter::tests::test_save_pdf_2
[INFO] [stdout]     converter::tests::test_save_pdf_3
[INFO] [stdout]     converter::tests::test_save_pdf_4
[INFO] [stdout]     extracter::tests::test_adjust_columns
[INFO] [stdout]     parser::tests::test_collect_references_text
[INFO] [stdout]     parser::tests::test_math_markup_heuristic_corpus
[INFO] [stdout]     parser::tests::test_math_markup_latex_format
[INFO] [stdout]     parser::tests::test_math_markup_no_math_flag
[INFO] [stdout]     parser::tests::test_paper_output_format
[INFO] [stdout]     parser::tests::test_parse_1
[INFO] [stdout]     parser::tests::test_parse_2
[INFO] [stdout]     parser::tests::test_parse_extract_sections_1
[INFO] [stdout]     parser::tests::test_parse_extract_sections_2
[INFO] [stdout]     parser::tests::test_parse_extract_sections_3
[INFO] [stdout]     parser::tests::test_parse_llm_disabled_fallback
[INFO] [stdout]     parser::tests::test_parse_local_sample
[INFO] [stdout]     parser::tests::test_parse_long_document
[INFO] [stdout]     parser::tests::test_parse_zep_non_standard_format
[INFO] [stdout]     parser::tests::test_pdf_to_json
[INFO] [stdout]     test_utils::tests::test_download
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 140 passed; 23 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.80s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17", kill_on_drop: false }`
[INFO] [stdout] b931949ec4d453a6403b47c6c58bf1e90fa8dd3bb77ec3c2d4272a7ebd51bb17
