[INFO] fetching crate lsp-server-tokio 0.4.0...
[INFO] testing lsp-server-tokio-0.4.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate lsp-server-tokio 0.4.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate lsp-server-tokio 0.4.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate lsp-server-tokio 0.4.0
[INFO] tweaked toml for crates.io crate lsp-server-tokio 0.4.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate lsp-server-tokio 0.4.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 lsp-server-tokio 0.4.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] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `lsp-server-tokio` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d
[INFO] running `Command { std: "docker" "start" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", 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" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d" "/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" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", 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" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `lsp-server-tokio` (manifest) generated 1 warning
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling futures-io v0.3.32
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling lsp-server-tokio v0.4.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<i64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]    --> src/client_sender.rs:188:14
[INFO] [stdout]     |
[INFO] [stdout] 188 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |              ^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]     |
[INFO] [stdout] 188 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 188 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 5.89s
[INFO] running `Command { std: "docker" "inspect" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", 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" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d" "/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] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `lsp-server-tokio` (manifest) generated 1 warning
[INFO] [stderr]    Compiling fluent-uri v0.1.4
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling serde_repr v0.1.20
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling lsp-types v0.97.0
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling lsp-server-tokio v0.4.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<i64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]    --> src/client_sender.rs:188:14
[INFO] [stdout]     |
[INFO] [stdout] 188 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |              ^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]     |
[INFO] [stdout] 188 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 188 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<i64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]    --> src/client_sender.rs:188:14
[INFO] [stdout]     |
[INFO] [stdout] 188 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |              ^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]     |
[INFO] [stdout] 188 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 188 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]     |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 30.81s
[INFO] running `Command { std: "docker" "inspect" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", 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" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `lsp-server-tokio` (manifest) generated 1 warning
[INFO] [stderr] warning: use of deprecated method `std::sync::atomic::Atomic::<i64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stderr]    --> src/client_sender.rs:188:14
[INFO] [stderr]     |
[INFO] [stderr] 188 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]     |              ^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(deprecated)]` on by default
[INFO] [stderr] help: replace the use of the deprecated method
[INFO] [stderr]     |
[INFO] [stderr] 188 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr] 188 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]     |
[INFO] [stderr] 
[INFO] [stderr] warning: `lsp-server-tokio` (lib) generated 1 warning (run `cargo fix --lib -p lsp-server-tokio` to apply 1 suggestion)
[INFO] [stderr] warning: `lsp-server-tokio` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/lsp-server-tokio/ab4305c065d5c406/out/lsp_server_tokio-ab4305c065d5c406)
[INFO] [stdout] 
[INFO] [stdout] running 193 tests
[INFO] [stdout] test client_sender::tests::client_sender_request_auto_id ... ok
[INFO] [stdout] test client_sender::tests::client_sender_notify_sends_notification ... ok
[INFO] [stdout] test client_sender::tests::client_sender_request_disconnected ... ok
[INFO] [stdout] test client_sender::tests::response_map_cancel_ignores_unknown_id ... ok
[INFO] [stdout] test client_sender::tests::route_response_map_takes_priority ... ok
[INFO] [stdout] test client_sender::tests::route_delivers_to_response_map ... ok
[INFO] [stdout] test codec::tests::case_insensitive_header_parsing ... ok
[INFO] [stdout] test client_sender::tests::client_sender_wraparound_does_not_reuse_pending_id ... ok
[INFO] [stdout] test client_sender::tests::client_sender_aborted_request_does_not_swallow_late_response ... ok
[INFO] [stdout] test client_sender::tests::client_sender_multiple_clones_share_state ... ok
[INFO] [stdout] test client_sender::tests::client_sender_is_clone_send_sync ... ok
[INFO] [stdout] test client_sender::tests::client_sender_respond_sends_response ... ok
[INFO] [stdout] test client_sender::tests::client_sender_concurrent_requests ... ok
[INFO] [stdout] test client_sender::tests::client_sender_request_gets_response ... ok
[INFO] [stdout] test codec::tests::decode_complete_message_test ... ok
[INFO] [stdout] test codec::tests::decode_invalid_json_returns_error ... ok
[INFO] [stdout] test connection::tests::client_sender_can_be_called_multiple_times ... ok
[INFO] [stdout] test client_sender::tests::client_sender_request_timeout ... ok
[INFO] [stdout] test codec::tests::decode_missing_content_length_returns_error ... ok
[INFO] [stdout] test codec::tests::content_length_byte_count_test ... ok
[INFO] [stdout] test codec::tests::response_error_roundtrip ... ok
[INFO] [stdout] test connection::tests::connection_bidirectional_test ... ok
[INFO] [stdout] test codec::tests::decode_multiple_messages_test ... ok
[INFO] [stdout] test codec::tests::encode_response_test ... ok
[INFO] [stdout] test codec::tests::decode_partial_body_test ... ok
[INFO] [stdout] test client_sender::tests::client_sender_notify_after_close ... ok
[INFO] [stdout] test codec::tests::decode_partial_header_test ... ok
[INFO] [stdout] test codec::tests::encode_request_test ... ok
[INFO] [stdout] test connection::tests::connection_multiple_messages_test ... ok
[INFO] [stdout] test connection::tests::connection_outgoing_request_queue_test ... ok
[INFO] [stdout] test connection::tests::route_cancel_request_unknown_id_still_returns_cancel_handled ... ok
[INFO] [stdout] test connection::tests::connection_with_request_queue_test ... ok
[INFO] [stdout] test codec::tests::encode_decode_roundtrip_test ... ok
[INFO] [stdout] test connection::tests::route_cancel_request_returns_cancel_handled_and_cancels_pending ... ok
[INFO] [stdout] test connection::tests::route_multiple_responses_to_different_requests ... ok
[INFO] [stdout] test connection::tests::route_error_response_to_pending_request ... ok
[INFO] [stdout] test connection::tests::route_notification_returns_incoming_notification ... ok
[INFO] [stdout] test connection::tests::route_prefers_response_map_over_outgoing_queue ... ok
[INFO] [stdout] test codec::tests::encode_notification_test ... ok
[INFO] [stdout] test connection::tests::route_regular_notification_not_affected ... ok
[INFO] [stdout] test connection::tests::cancellation_propagates_to_spawned_handler ... ok
[INFO] [stdout] test connection::tests::client_sender_request_routed_through_response_map ... ok
[INFO] [stdout] test connection::tests::route_request_auto_registers_and_cancellation_works ... ok
[INFO] [stdout] test connection::tests::route_request_returns_incoming_request ... ok
[INFO] [stdout] test connection::tests::connection_has_request_queue_test ... ok
[INFO] [stdout] test connection::tests::route_response_to_pending_outgoing_request ... ok
[INFO] [stdout] test connection::tests::connection_from_transport_test ... ok
[INFO] [stdout] test connection::tests::connection_from_duplex_test ... ok
[INFO] [stdout] test connection::tests::route_response_with_string_id ... ok
[INFO] [stdout] test connection::tests::test_exit_without_shutdown ... ok
[INFO] [stdout] test connection::tests::test_initialize_drops_notifications ... ok
[INFO] [stdout] test connection::tests::sender_receiver_independent_test ... ok
[INFO] [stdout] test connection::tests::test_exit_during_init_disconnects ... ok
[INFO] [stdout] test connection::tests::send_works_before_and_after_client_sender ... ok
[INFO] [stdout] test connection::tests::test_cancel_outgoing_request ... ok
[INFO] [stdout] test connection::tests::stdio_transport_constructible ... ok
[INFO] [stdout] test connection::tests::test_cancel_unknown_outgoing_request ... ok
[INFO] [stdout] test connection::tests::test_cancel_with_string_id ... ok
[INFO] [stdout] test connection::tests::test_on_shutdown_future ... ok
[INFO] [stdout] test connection::tests::test_shutdown_then_exit ... ok
[INFO] [stdout] test error::tests::error_code_to_i32 ... ok
[INFO] [stdout] test connection::tests::test_initialize_finish_times_out_without_initialized ... ok
[INFO] [stdout] test error::tests::i32_to_error_code ... ok
[INFO] [stdout] test error::tests::response_error_construction ... ok
[INFO] [stdout] test error::tests::response_error_deserialization ... ok
[INFO] [stdout] test connection::tests::route_response_for_unknown_id_returns_response_unknown ... ok
[INFO] [stdout] test connection::tests::route_response_with_null_id_returns_response_unknown ... ok
[INFO] [stdout] test connection::tests::send_works_after_client_sender ... ok
[INFO] [stdout] test error::tests::response_error_serialization_without_data ... ok
[INFO] [stdout] test connection::tests::stdio_connection_alias_constructs_with_custom_metadata ... ok
[INFO] [stdout] test error::tests::response_error_with_data ... ok
[INFO] [stdout] test connection::tests::test_initialize_handshake ... ok
[INFO] [stdout] test error::tests::skip_serializing_if_works ... ok
[INFO] [stdout] test client_sender::tests::client_sender_timeout_preserves_other_pending_requests ... ok
[INFO] [stdout] test client_sender::tests::client_sender_request_timeout_does_not_swallow_late_response ... ok
[INFO] [stdout] test lifecycle::tests::exit_code_success_is_zero ... ok
[INFO] [stdout] test lifecycle::tests::lifecycle_state_default_is_uninitialized ... ok
[INFO] [stdout] test lifecycle::tests::lifecycle_state_is_copy ... ok
[INFO] [stdout] test lifecycle::tests::exited_allows_no_notifications ... ok
[INFO] [stdout] test lifecycle::tests::exited_allows_no_requests ... ok
[INFO] [stdout] test lifecycle::tests::initializing_allows_only_initialized_notification ... ok
[INFO] [stdout] test error::tests::response_error_deserialization_with_data ... ok
[INFO] [stdout] test error::tests::response_error_raw_code ... ok
[INFO] [stdout] test error::tests::response_error_serialization_with_data ... ok
[INFO] [stdout] test lifecycle::tests::exit_code_error_is_one ... ok
[INFO] [stdout] test connection::tests::client_sender_messages_arrive_on_receiver ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_after_shutdown_message ... ok
[INFO] [stdout] test lifecycle::tests::exit_code_is_copy ... ok
[INFO] [stdout] test lifecycle::tests::initializing_allows_no_requests ... ok
[INFO] [stdout] test connection::tests::test_initialize_rejects_non_init_requests ... ok
[INFO] [stdout] test lifecycle::tests::uninitialized_allows_only_initialize_request ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_request_timeout_message ... ok
[INFO] [stdout] test message::tests::message_discriminates_notification ... ok
[INFO] [stdout] test lifecycle::tests::running_allows_all_notifications ... ok
[INFO] [stdout] test message::tests::message_discriminates_request_with_params ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_is_debug ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_disconnected_message ... ok
[INFO] [stdout] test message::tests::message_discriminates_notification_with_params ... ok
[INFO] [stdout] test message::tests::message_discriminates_request ... ok
[INFO] [stdout] test lifecycle::tests::running_allows_all_requests_except_initialize ... ok
[INFO] [stdout] test lifecycle::tests::shutting_down_allows_no_requests ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_expected_initialize_message ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_expected_initialized_message ... ok
[INFO] [stdout] test message::tests::message_discriminates_response_err ... ok
[INFO] [stdout] test message::tests::message_roundtrip_notification ... ok
[INFO] [stdout] test message::tests::message_discriminates_response_ok ... ok
[INFO] [stdout] test message::tests::notification_roundtrip ... ok
[INFO] [stdout] test message::tests::notification_deserialization_rejects_wrong_jsonrpc_version ... ok
[INFO] [stdout] test message::tests::request_deserialization_rejects_missing_jsonrpc ... ok
[INFO] [stdout] test message::tests::request_roundtrip ... ok
[INFO] [stdout] test message::tests::request_with_params_roundtrip ... ok
[INFO] [stdout] test message::tests::request_with_string_id ... ok
[INFO] [stdout] test message::tests::response_both_result_and_error_prefers_error ... ok
[INFO] [stdout] test message::tests::notification_without_params_omits_field ... ok
[INFO] [stdout] test message::tests::request_without_params_omits_field ... ok
[INFO] [stdout] test message::tests::response_err_omits_result_field ... ok
[INFO] [stdout] test message::tests::request_deserialization_rejects_wrong_jsonrpc_version ... ok
[INFO] [stdout] test message::tests::request_with_string_id_discrimination ... ok
[INFO] [stdout] test message::tests::response_err_roundtrip ... ok
[INFO] [stdout] test message::tests::response_error_null_with_result_treats_as_success ... ok
[INFO] [stdout] test message::tests::response_ok_omits_error_field ... ok
[INFO] [stdout] test message::tests::response_serialization_hardcodes_jsonrpc_version ... ok
[INFO] [stdout] test message::tests::response_parse_error_has_null_id ... ok
[INFO] [stdout] test message::tests::notification_with_params_roundtrip ... ok
[INFO] [stdout] test message::tests::response_deserialization_rejects_missing_jsonrpc ... ok
[INFO] [stdout] test lifecycle::tests::shutting_down_allows_only_exit_notification ... ok
[INFO] [stdout] test message::tests::response_with_complex_result ... ok
[INFO] [stdout] test message::tests::response_with_explicit_null_id ... ok
[INFO] [stdout] test message::tests::response_result_null_is_valid_success ... ok
[INFO] [stdout] test message::tests::response_ok_roundtrip ... ok
[INFO] [stdout] test message::tests::response_error_null_without_result_rejected ... ok
[INFO] [stdout] test message::tests::response_neither_result_nor_error_rejected ... ok
[INFO] [stdout] test message::tests::response_with_null_id_discrimination ... ok
[INFO] [stdout] test request_id::tests::display_string ... ok
[INFO] [stdout] test request_id::tests::integer_and_string_with_same_value_are_not_equal ... ok
[INFO] [stdout] test request_id::tests::integer_deserialization_from_json_number ... ok
[INFO] [stdout] test request_id::tests::integer_serialization_roundtrip ... ok
[INFO] [stdout] test message::tests::message_roundtrip_request ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_initialize_timeout_message ... ok
[INFO] [stdout] test request_id::tests::string_serialization_roundtrip ... ok
[INFO] [stdout] test request_id::tests::string_deserialization_from_json_string ... ok
[INFO] [stdout] test message::tests::message_roundtrip_response ... ok
[INFO] [stdout] test lifecycle::tests::uninitialized_allows_only_exit_notification ... ok
[INFO] [stdout] test request_id::tests::hash_works_for_hashmap_keys ... ok
[INFO] [stdout] test request_queue::tests::incoming_cancel_idempotent ... ok
[INFO] [stdout] test message::tests::response_error_with_data ... ok
[INFO] [stdout] test request_queue::tests::incoming_is_pending ... ok
[INFO] [stdout] test request_queue::tests::incoming_get_token_returns_clone ... ok
[INFO] [stdout] test request_queue::tests::incoming_cancel_triggers_token ... ok
[INFO] [stdout] test request_queue::tests::incoming_get_token_unknown_returns_none ... ok
[INFO] [stdout] test request_queue::tests::incoming_cancel_unknown_returns_false ... ok
[INFO] [stdout] test request_queue::tests::outgoing_cancel_drops_sender ... ok
[INFO] [stdout] test request_queue::tests::incoming_complete_unknown_returns_none ... ok
[INFO] [stdout] test request_queue::tests::incoming_default ... ok
[INFO] [stdout] test lifecycle::tests::protocol_error_io_conversion ... ok
[INFO] [stdout] test request_queue::tests::outgoing_cancel_unknown_returns_false ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_integer_id ... ok
[INFO] [stdout] test request_queue::tests::incoming_complete_after_cancel_returns_data ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_invalid_id_type ... ok
[INFO] [stdout] test request_queue::tests::outgoing_default ... ok
[INFO] [stdout] test request_queue::tests::outgoing_is_pending ... ok
[INFO] [stdout] test request_queue::tests::outgoing_complete_unknown_returns_false ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_string_id ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_missing_id_field ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_null_id ... ok
[INFO] [stdout] test request_queue::tests::queue_default ... ok
[INFO] [stdout] test request_queue::tests::outgoing_register_and_complete ... ok
[INFO] [stdout] test request_queue::tests::queue_incoming_outgoing_independent ... ok
[INFO] [stdout] test request_queue::tests::incoming_pending_count ... ok
[INFO] [stdout] test request_id::tests::display_integer ... ok
[INFO] [stdout] test request_queue::tests::incoming_register_and_complete ... ok
[INFO] [stdout] test request_queue::tests::queue_with_string_request_id ... ok
[INFO] [stdout] test request_id::tests::from_string ... ok
[INFO] [stdout] test request_queue::tests::outgoing_pending_count ... ok
[INFO] [stdout] test routing::tests::cancelled_response_with_string_id ... ok
[INFO] [stdout] test routing::tests::incoming_message_accessors_match_variants ... ok
[INFO] [stdout] test routing::tests::method_not_found_response_includes_method_name ... ok
[INFO] [stdout] test routing::tests::method_not_found_response_with_string_id ... ok
[INFO] [stdout] test transport::tests::duplex_bidirectional_concurrent_test ... ok
[INFO] [stdout] test request_id::tests::from_i32 ... ok
[INFO] [stdout] test request_queue::tests::queue_new_creates_empty ... ok
[INFO] [stdout] test routing::tests::incoming_message_is_debug ... ok
[INFO] [stdout] test request_id::tests::from_str ... ok
[INFO] [stdout] test routing::tests::cancelled_response_creates_correct_error ... ok
[INFO] [stdout] test routing::tests::incoming_message_variants_constructible ... ok
[INFO] [stdout] test request_queue::tests::parse_cancel_params_missing_params ... ok
[INFO] [stdout] test routing::tests::method_not_found_response_creates_correct_error ... ok
[INFO] [stdout] test transport::tests::duplex_multiple_messages_test ... ok
[INFO] [stdout] test transport::tests::duplex_notification_test ... ok
[INFO] [stdout] test transport::tests::large_message_test ... ok
[INFO] [stdout] test transport::tests::duplex_request_response_test ... ok
[INFO] [stdout] test transport::tests::response_with_error_test ... ok
[INFO] [stdout] test message::tests::response_with_string_id ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 193 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/e2e_formatter.rs (/opt/rustwide/target/release/build/lsp-server-tokio/d5638aa4aaa6706b/out/e2e_formatter-d5638aa4aaa6706b)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test common::tests::test_encode_lsp_message ... ok
[INFO] [stdout] test common::tests::test_parse_content_length_invalid ... ok
[INFO] [stdout] test common::tests::test_parse_content_length_valid ... ok
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warningwarning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] : `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:57:12
[INFO] [stderr]    |
[INFO] [stderr] 57 | homepage = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/VladimirMakaev/lsp-server-tokio"
[INFO] [stderr]    |              ----------------------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stdout] test test_format_document_preserves_trailing_newline_range ... FAILED
[INFO] [stdout] test test_format_document ... FAILED
[INFO] [stdout] test test_incremental_changes_insert_after_unicode_scalar ... FAILED
[INFO] [stdout] test test_unknown_method ... FAILED
[INFO] [stdout] test test_cancellation ... FAILED
[INFO] [stdout] test test_format_document_unicode_range_uses_utf16_units ... FAILED
[INFO] [stdout] test test_full_lifecycle ... FAILED
[INFO] [stdout] test test_format_unknown_document ... FAILED
[INFO] [stdout] test test_multi_document_workspace ... FAILED
[INFO] [stdout] test test_incremental_changes ... FAILED
[INFO] [stdout] test test_incremental_changes_crlf_line_offsets ... FAILED
[INFO] [stdout] test test_shutdown_during_request ... FAILED
[INFO] [stdout] test test_incremental_changes_replace_unicode_scalar ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_format_document_preserves_trailing_newline_range stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_format_document_preserves_trailing_newline_range' (987) panicked at tests/e2e_formatter.rs:185:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d57a6 - e2e_formatter[1f004d86b011a552]::test_format_document_preserves_trailing_newline_range::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d91ce - <e2e_formatter[1f004d86b011a552]::test_format_document_preserves_trailing_newline_range::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_format_document stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_format_document' (986) panicked at tests/e2e_formatter.rs:145:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4cf680 - e2e_formatter[1f004d86b011a552]::test_format_document::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d856e - <e2e_formatter[1f004d86b011a552]::test_format_document::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_incremental_changes_insert_after_unicode_scalar stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_incremental_changes_insert_after_unicode_scalar' (995) panicked at tests/e2e_formatter.rs:398:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d4e49 - e2e_formatter[1f004d86b011a552]::test_incremental_changes_insert_after_unicode_scalar::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d906e - <e2e_formatter[1f004d86b011a552]::test_incremental_changes_insert_after_unicode_scalar::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_unknown_method stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_unknown_method' (1003) panicked at tests/e2e_formatter.rs:625:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4cef1a - e2e_formatter[1f004d86b011a552]::test_unknown_method::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d840e - <e2e_formatter[1f004d86b011a552]::test_unknown_method::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_cancellation stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_cancellation' (985) panicked at tests/e2e_formatter.rs:580:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4ce371 - e2e_formatter[1f004d86b011a552]::test_cancellation::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d814e - <e2e_formatter[1f004d86b011a552]::test_cancellation::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_format_document_unicode_range_uses_utf16_units stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_format_document_unicode_range_uses_utf16_units' (989) panicked at tests/e2e_formatter.rs:236:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d44c3 - e2e_formatter[1f004d86b011a552]::test_format_document_unicode_range_uses_utf16_units::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d8f0e - <e2e_formatter[1f004d86b011a552]::test_format_document_unicode_range_uses_utf16_units::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_full_lifecycle stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_full_lifecycle' (991) panicked at tests/e2e_formatter.rs:123:55:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4ce989 - e2e_formatter[1f004d86b011a552]::test_full_lifecycle::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d82ae - <e2e_formatter[1f004d86b011a552]::test_full_lifecycle::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_format_unknown_document stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_format_unknown_document' (990) panicked at tests/e2e_formatter.rs:713:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d0ac8 - e2e_formatter[1f004d86b011a552]::test_format_unknown_document::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d882e - <e2e_formatter[1f004d86b011a552]::test_format_unknown_document::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_multi_document_workspace stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_multi_document_workspace' (1001) panicked at tests/e2e_formatter.rs:503:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d256e - e2e_formatter[1f004d86b011a552]::test_multi_document_workspace::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d8aee - <e2e_formatter[1f004d86b011a552]::test_multi_document_workspace::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_incremental_changes stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_incremental_changes' (993) panicked at tests/e2e_formatter.rs:284:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d03a8 - e2e_formatter[1f004d86b011a552]::test_incremental_changes::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d86ce - <e2e_formatter[1f004d86b011a552]::test_incremental_changes::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_incremental_changes_crlf_line_offsets stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_incremental_changes_crlf_line_offsets' (994) panicked at tests/e2e_formatter.rs:449:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d322c - e2e_formatter[1f004d86b011a552]::test_incremental_changes_crlf_line_offsets::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d8c4e - <e2e_formatter[1f004d86b011a552]::test_incremental_changes_crlf_line_offsets::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_shutdown_during_request stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_shutdown_during_request' (1002) panicked at tests/e2e_formatter.rs:649:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d11be - e2e_formatter[1f004d86b011a552]::test_shutdown_during_request::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d898e - <e2e_formatter[1f004d86b011a552]::test_shutdown_during_request::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_incremental_changes_replace_unicode_scalar stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_incremental_changes_replace_unicode_scalar' (1000) panicked at tests/e2e_formatter.rs:347:35:
[INFO] [stdout] Initialize failed: Custom { kind: TimedOut, error: "Read timeout" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56338d598ab1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56338d5ad71a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56338d59cebc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56338d5745a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56338d590f89 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56338d5037b0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56338d5912b2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56338d574652 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56338d56d369 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56338d57525d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56338d5ade7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56338d5adc52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x56338d4d3b68 - e2e_formatter[1f004d86b011a552]::test_incremental_changes_replace_unicode_scalar::{closure#0}
[INFO] [stdout]   13:     0x56338d4f3d03 - <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1dfe91683e35ef50]::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:     0x56338d4eec39 - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[1dfe91683e35ef50]::runtime::context::Context>>::with::<tokio[1dfe91683e35ef50]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[1dfe91683e35ef50]::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}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]   15:     0x56338d4f4307 - <tokio[1dfe91683e35ef50]::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:     0x56338d4f1d17 - tokio[1dfe91683e35ef50]::runtime::context::runtime::enter_runtime::<<tokio[1dfe91683e35ef50]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   17:     0x56338d4f0f4a - <tokio[1dfe91683e35ef50]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]   18:     0x56338d4d8dae - <e2e_formatter[1f004d86b011a552]::test_incremental_changes_replace_unicode_scalar::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x56338d4f6bfb - 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:     0x56338d503dd5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   21:     0x56338d4fd6c4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x56338d506e62 - <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:     0x56338d5982ff - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x76877a4c3dfa - <unknown>
[INFO] [stdout]   25:     0x76877a5573d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_cancellation
[INFO] [stdout]     test_format_document
[INFO] [stdout]     test_format_document_preserves_trailing_newline_range
[INFO] [stdout]     test_format_document_unicode_range_uses_utf16_units
[INFO] [stdout]     test_format_unknown_document
[INFO] [stdout]     test_full_lifecycle
[INFO] [stdout]     test_incremental_changes
[INFO] [stdout]     test_incremental_changes_crlf_line_offsets
[INFO] [stdout]     test_incremental_changes_insert_after_unicode_scalar
[INFO] [stdout]     test_incremental_changes_replace_unicode_scalar
[INFO] [stdout]     test_multi_document_workspace
[INFO] [stdout]     test_shutdown_during_request
[INFO] [stdout]     test_unknown_method
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 3 passed; 13 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.03s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test e2e_formatter`
[INFO] running `Command { std: "docker" "inspect" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d", kill_on_drop: false }`
[INFO] [stdout] 2d6ead3207be0a8caf6234c6254a58bcc1ccd9ad8969bccfc8770396186d320d
