Nov 18 10:24:58.084 INFO testing gotham-0.2.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982 Nov 18 10:24:58.085 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Nov 18 10:24:58.517 INFO [stdout] 32c259176498163d9e27c2d9e31e6f71215604c5af7f164a33d6a4d8aa78008b Nov 18 10:24:58.519 INFO running `"docker" "start" "-a" "32c259176498163d9e27c2d9e31e6f71215604c5af7f164a33d6a4d8aa78008b"` Nov 18 10:24:58.931 INFO [stderr] usermod: no changes Nov 18 10:24:59.230 INFO [stderr] Compiling borrow-bag v1.0.0 Nov 18 10:25:04.739 INFO [stderr] Compiling gotham v0.2.1 (/source) Nov 18 10:25:10.807 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:10.807 INFO [stderr] --> src/http/header/x_frame_options.rs:7:5 Nov 18 10:25:10.807 INFO [stderr] | Nov 18 10:25:10.807 INFO [stderr] 7 | use std::ascii::AsciiExt; Nov 18 10:25:10.807 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:10.807 INFO [stderr] | Nov 18 10:25:10.807 INFO [stderr] note: lint level defined here Nov 18 10:25:10.807 INFO [stderr] --> src/lib.rs:7:23 Nov 18 10:25:10.807 INFO [stderr] | Nov 18 10:25:10.807 INFO [stderr] 7 | #![warn(missing_docs, deprecated)] Nov 18 10:25:10.807 INFO [stderr] | ^^^^^^^^^^ Nov 18 10:25:10.807 INFO [stderr] Nov 18 10:25:10.807 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:10.807 INFO [stderr] --> src/http/header/x_content_type_options.rs:6:5 Nov 18 10:25:10.807 INFO [stderr] | Nov 18 10:25:10.807 INFO [stderr] 6 | use std::ascii::AsciiExt; Nov 18 10:25:10.807 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:10.807 INFO [stderr] Nov 18 10:25:15.975 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 17.02s Nov 18 10:25:15.984 INFO [stderr] su: No module specific data is present Nov 18 10:25:16.639 INFO running `"docker" "inspect" "32c259176498163d9e27c2d9e31e6f71215604c5af7f164a33d6a4d8aa78008b"` Nov 18 10:25:16.825 INFO running `"docker" "rm" "-f" "32c259176498163d9e27c2d9e31e6f71215604c5af7f164a33d6a4d8aa78008b"` Nov 18 10:25:17.006 INFO [stdout] 32c259176498163d9e27c2d9e31e6f71215604c5af7f164a33d6a4d8aa78008b Nov 18 10:25:17.016 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Nov 18 10:25:17.381 INFO [stdout] c5c76f0ad78cb4e026b8c2668fb15586e046f14de599ac903079c57ec8b25bfa Nov 18 10:25:17.383 INFO running `"docker" "start" "-a" "c5c76f0ad78cb4e026b8c2668fb15586e046f14de599ac903079c57ec8b25bfa"` Nov 18 10:25:17.995 INFO [stderr] usermod: no changes Nov 18 10:25:18.206 INFO [stderr] Compiling gotham v0.2.1 (/source) Nov 18 10:25:23.623 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:23.623 INFO [stderr] --> src/http/header/x_frame_options.rs:7:5 Nov 18 10:25:23.623 INFO [stderr] | Nov 18 10:25:23.623 INFO [stderr] 7 | use std::ascii::AsciiExt; Nov 18 10:25:23.623 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:23.623 INFO [stderr] | Nov 18 10:25:23.623 INFO [stderr] note: lint level defined here Nov 18 10:25:23.623 INFO [stderr] --> src/lib.rs:7:23 Nov 18 10:25:23.623 INFO [stderr] | Nov 18 10:25:23.623 INFO [stderr] 7 | #![warn(missing_docs, deprecated)] Nov 18 10:25:23.623 INFO [stderr] | ^^^^^^^^^^ Nov 18 10:25:23.623 INFO [stderr] Nov 18 10:25:23.623 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:23.623 INFO [stderr] --> src/http/header/x_content_type_options.rs:6:5 Nov 18 10:25:23.623 INFO [stderr] | Nov 18 10:25:23.623 INFO [stderr] 6 | use std::ascii::AsciiExt; Nov 18 10:25:23.623 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:23.623 INFO [stderr] Nov 18 10:25:38.951 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 20.92s Nov 18 10:25:38.976 INFO [stderr] su: No module specific data is present Nov 18 10:25:39.602 INFO running `"docker" "inspect" "c5c76f0ad78cb4e026b8c2668fb15586e046f14de599ac903079c57ec8b25bfa"` Nov 18 10:25:39.817 INFO running `"docker" "rm" "-f" "c5c76f0ad78cb4e026b8c2668fb15586e046f14de599ac903079c57ec8b25bfa"` Nov 18 10:25:40.023 INFO [stdout] c5c76f0ad78cb4e026b8c2668fb15586e046f14de599ac903079c57ec8b25bfa Nov 18 10:25:40.030 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Nov 18 10:25:40.335 INFO [stdout] f4254969343a931353a8079c464a08455d9ce53d08a684498b813fe7bec909e3 Nov 18 10:25:40.340 INFO running `"docker" "start" "-a" "f4254969343a931353a8079c464a08455d9ce53d08a684498b813fe7bec909e3"` Nov 18 10:25:41.279 INFO [stderr] usermod: no changes Nov 18 10:25:41.767 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.45s Nov 18 10:25:41.771 INFO [stderr] Running /target/debug/deps/gotham-48047f9df4a3bad5 Nov 18 10:25:41.778 INFO [stdout] Nov 18 10:25:41.778 INFO [stdout] running 82 tests Nov 18 10:25:41.778 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok Nov 18 10:25:41.779 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok Nov 18 10:25:41.783 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok Nov 18 10:25:41.783 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok Nov 18 10:25:41.783 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok Nov 18 10:25:41.784 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok Nov 18 10:25:41.784 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok Nov 18 10:25:41.784 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok Nov 18 10:25:41.785 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok Nov 18 10:25:41.785 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok Nov 18 10:25:41.785 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok Nov 18 10:25:41.785 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok Nov 18 10:25:41.786 INFO [stdout] test http::header::x_content_type_options::tests::parse_nosniff ... ok Nov 18 10:25:41.786 INFO [stdout] test http::header::tests::valid_rws_from_one_header ... ok Nov 18 10:25:41.786 INFO [stdout] test http::header::x_content_type_options::tests::parse_fails ... ok Nov 18 10:25:41.787 INFO [stdout] test http::header::tests::invalid_rws_from_one_header ... ok Nov 18 10:25:41.787 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok Nov 18 10:25:41.787 INFO [stdout] test http::header::x_xss_protection::tests::parse_disable ... ok Nov 18 10:25:41.788 INFO [stdout] test http::header::x_frame_options::tests::parse_deny ... ok Nov 18 10:25:41.788 INFO [stdout] test http::header::x_frame_options::tests::parse_fails ... ok Nov 18 10:25:41.788 INFO [stdout] test http::header::x_frame_options::tests::parse_same_origin ... ok Nov 18 10:25:41.788 INFO [stdout] test http::header::x_xss_protection::tests::parse_fails ... ok Nov 18 10:25:41.789 INFO [stdout] test http::header::x_frame_options::tests::parse_allow_from ... ok Nov 18 10:25:41.789 INFO [stdout] test http::header::x_frame_options::tests::valid_display_formatting ... ok Nov 18 10:25:41.789 INFO [stdout] test http::header::x_xss_protection::tests::parse_enable ... ok Nov 18 10:25:41.789 INFO [stdout] test http::request::query_string::tests::query_string_mapping_tests ... ok Nov 18 10:25:41.790 INFO [stdout] test http::request::path::tests::request_path_segments_tests ... ok Nov 18 10:25:41.803 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok Nov 18 10:25:41.803 INFO [stdout] test http::header::x_content_type_options::tests::valid_display_formatting ... ok Nov 18 10:25:41.804 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok Nov 18 10:25:41.804 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok Nov 18 10:25:41.804 INFO [stdout] test http::header::x_xss_protection::tests::valid_display_formatting ... ok Nov 18 10:25:41.804 INFO [stdout] test http::tests::ensure_valid_www_form_url_encoded_value ... ok Nov 18 10:25:41.805 INFO [stdout] test http::tests::ensure_valid_percent_decode ... ok Nov 18 10:25:41.805 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok Nov 18 10:25:41.805 INFO [stdout] test middleware::session::tests::existing_session ... ok Nov 18 10:25:41.811 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok Nov 18 10:25:41.811 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok Nov 18 10:25:41.812 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok Nov 18 10:25:41.812 INFO [stdout] test middleware::session::tests::new_session ... ok Nov 18 10:25:41.812 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok Nov 18 10:25:41.816 INFO [stdout] test router::non_match::tests::intersection_tests ... ok Nov 18 10:25:41.816 INFO [stdout] test router::non_match::tests::union_tests ... ok Nov 18 10:25:41.839 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok Nov 18 10:25:41.839 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok Nov 18 10:25:41.842 INFO [stdout] test router::route::tests::external_route_tests ... ok Nov 18 10:25:41.854 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok Nov 18 10:25:41.871 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok Nov 18 10:25:41.871 INFO [stdout] test router::route::tests::internal_route_tests ... ok Nov 18 10:25:41.871 INFO [stdout] test router::builder::tests::build_router_test ... ok Nov 18 10:25:41.871 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok Nov 18 10:25:41.874 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok Nov 18 10:25:41.882 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok Nov 18 10:25:41.886 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok Nov 18 10:25:41.887 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok Nov 18 10:25:41.898 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok Nov 18 10:25:41.899 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok Nov 18 10:25:41.900 INFO [stdout] test router::tree::node::tests::traverses_children ... ok Nov 18 10:25:41.906 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok Nov 18 10:25:41.911 INFO [stdout] test router::tree::node::tests::manages_children ... ok Nov 18 10:25:41.911 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok Nov 18 10:25:41.914 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok Nov 18 10:25:41.918 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok Nov 18 10:25:41.943 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok Nov 18 10:25:41.943 INFO [stdout] test service::tests::router ... ok Nov 18 10:25:41.946 INFO [stdout] test service::tests::new_handler_closure ... ok Nov 18 10:25:41.947 INFO [stdout] test service::trap::tests::error ... ok Nov 18 10:25:41.947 INFO [stdout] test service::trap::tests::success ... ok Nov 18 10:25:41.955 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok Nov 18 10:25:41.967 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok Nov 18 10:25:41.975 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok Nov 18 10:25:42.010 INFO [stdout] test test::tests::async_echo ... ok Nov 18 10:25:42.055 INFO [stdout] test test::tests::serves_requests ... ok Nov 18 10:25:42.083 INFO [stdout] test test::tests::sets_client_addr ... ok Nov 18 10:25:42.237 INFO [stdout] test router::tree::node::tests::panics_when_node_with_a_route_adds_another ... ok Nov 18 10:25:42.237 INFO [stdout] test router::tree::node::tests::panics_when_delegated_node_adds_children ... ok Nov 18 10:25:42.275 INFO [stdout] test router::tree::node::tests::panics_when_node_with_children_is_provided_delegated_route ... ok Nov 18 10:25:42.303 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok Nov 18 10:25:42.303 INFO [stdout] test service::trap::tests::panic ... ok Nov 18 10:25:42.303 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok Nov 18 10:25:42.303 INFO [stdout] test service::trap::tests::async_panic ... ok Nov 18 10:25:43.095 INFO [stdout] test test::tests::times_out ... ok Nov 18 10:25:43.095 INFO [stdout] Nov 18 10:25:43.095 INFO [stdout] test result: ok. 82 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 10:25:43.095 INFO [stdout] Nov 18 10:25:43.098 INFO [stderr] Doc-tests gotham Nov 18 10:25:51.545 INFO [stdout] Nov 18 10:25:51.545 INFO [stdout] running 99 tests Nov 18 10:25:53.435 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 43) ... ok Nov 18 10:26:27.899 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 40) ... ok Nov 18 10:26:33.871 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 113) ... ok Nov 18 10:26:39.695 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_request_id::XRequestId (line 43) ... ok Nov 18 10:26:41.699 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok Nov 18 10:26:47.859 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok Nov 18 10:26:51.563 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_runtime_microseconds::XRuntimeMicroseconds (line 41) ... test <::hyper::header::common::header macros> - http::header::x_runtime_microseconds::XRuntimeMicroseconds (line 41) has been running for over 60 seconds Nov 18 10:26:51.563 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... test src/extractor/path.rs - extractor::path::PathExtractor (line 21) has been running for over 60 seconds Nov 18 10:26:51.563 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) has been running for over 60 seconds Nov 18 10:26:53.687 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok Nov 18 10:26:56.499 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_runtime_microseconds::XRuntimeMicroseconds (line 41) ... ok Nov 18 10:26:57.134 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok Nov 18 10:27:27.915 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 63) ... test src/handler/mod.rs - handler::Handler (line 63) has been running for over 60 seconds Nov 18 10:27:28.851 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 63) ... ok Nov 18 10:27:30.776 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 159) ... ok Nov 18 10:27:31.375 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 193) ... ok Nov 18 10:27:33.875 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 85) ... test src/handler/mod.rs - handler::Handler (line 85) has been running for over 60 seconds Nov 18 10:27:34.431 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 85) ... ok Nov 18 10:27:39.699 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 277) ... test src/handler/mod.rs - handler::IntoResponse (line 277) has been running for over 60 seconds Nov 18 10:27:41.151 INFO [stdout] test src/http/header/x_frame_options.rs - http::header::x_frame_options::XFrameOptions (line 41) ... ok Nov 18 10:27:41.614 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 277) ... ok Nov 18 10:27:49.403 INFO [stdout] test src/http/header/x_content_type_options.rs - http::header::x_content_type_options::XContentTypeOptions (line 25) ... ok Nov 18 10:27:55.332 INFO [stdout] test src/http/header/x_xss_protection.rs - http::header::x_xss_protection::XXssProtection (line 21) ... ok Nov 18 10:28:28.863 INFO [stdout] test src/http/response/mod.rs - http::response::create_response (line 20) ... test src/http/response/mod.rs - http::response::create_response (line 20) has been running for over 60 seconds Nov 18 10:28:30.784 INFO [stdout] test src/http/response/mod.rs - http::response::extend_response (line 80) ... test src/http/response/mod.rs - http::response::extend_response (line 80) has been running for over 60 seconds Nov 18 10:28:31.383 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 166) ... test src/http/response/mod.rs - http::response::set_headers (line 166) has been running for over 60 seconds Nov 18 10:28:34.435 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 228) ... test src/http/response/mod.rs - http::response::set_headers (line 228) has been running for over 60 seconds Nov 18 10:28:41.155 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 136) ... test src/middleware/mod.rs - middleware::Middleware (line 136) has been running for over 60 seconds Nov 18 10:28:41.619 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 210) ... test src/middleware/mod.rs - middleware::Middleware (line 210) has been running for over 60 seconds Nov 18 10:28:49.407 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 26) ... test src/middleware/mod.rs - middleware::Middleware (line 26) has been running for over 60 seconds Nov 18 10:28:55.351 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 271) ... test src/middleware/mod.rs - middleware::Middleware (line 271) has been running for over 60 seconds Nov 18 10:29:02.967 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 228) ... ok Nov 18 10:29:07.430 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 166) ... ok Nov 18 10:29:12.127 INFO [stdout] test src/http/response/mod.rs - http::response::create_response (line 20) ... ok Nov 18 10:29:14.859 INFO [stdout] test src/http/response/mod.rs - http::response::extend_response (line 80) ... ok Nov 18 10:29:19.751 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 210) ... ok Nov 18 10:29:21.167 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 136) ... ok Nov 18 10:29:21.520 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 26) ... ok Nov 18 10:29:46.599 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 271) ... ok Nov 18 10:30:02.971 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 75) ... test src/middleware/mod.rs - middleware::Middleware (line 75) has been running for over 60 seconds Nov 18 10:30:07.443 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 343) ... test src/middleware/mod.rs - middleware::NewMiddleware (line 343) has been running for over 60 seconds Nov 18 10:30:12.147 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) has been running for over 60 seconds Nov 18 10:30:14.883 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 416) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 416) has been running for over 60 seconds Nov 18 10:30:19.747 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 427) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 427) has been running for over 60 seconds Nov 18 10:30:21.167 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 438) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 438) has been running for over 60 seconds Nov 18 10:30:21.523 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 698) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 698) has been running for over 60 seconds Nov 18 10:30:37.915 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 343) ... ok Nov 18 10:30:38.623 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok Nov 18 10:30:40.314 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 438) ... ok Nov 18 10:30:40.939 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 416) ... ok Nov 18 10:30:45.603 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 75) ... ok Nov 18 10:30:46.603 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 587) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 587) has been running for over 60 seconds Nov 18 10:30:53.731 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 427) ... ok Nov 18 10:30:54.687 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 698) ... ok Nov 18 10:31:22.713 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 587) ... ok Nov 18 10:31:37.927 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 660) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 660) has been running for over 60 seconds Nov 18 10:31:38.639 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 629) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 629) has been running for over 60 seconds Nov 18 10:31:40.322 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 555) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 555) has been running for over 60 seconds Nov 18 10:31:40.942 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 763) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 763) has been running for over 60 seconds Nov 18 10:31:45.614 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 734) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 734) has been running for over 60 seconds Nov 18 10:31:53.735 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 186) ... test src/middleware/session/mod.rs - middleware::session::SessionData (line 186) has been running for over 60 seconds Nov 18 10:31:54.687 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... test src/pipeline/mod.rs - pipeline::Pipeline (line 23) has been running for over 60 seconds Nov 18 10:32:05.029 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 555) ... ok Nov 18 10:32:06.531 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 660) ... ok Nov 18 10:32:07.806 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 763) ... ok Nov 18 10:32:12.254 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 734) ... ok Nov 18 10:32:15.559 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 629) ... ok Nov 18 10:32:22.731 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 175) ... test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 175) has been running for over 60 seconds Nov 18 10:32:37.611 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok Nov 18 10:32:40.163 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 186) ... ok Nov 18 10:32:48.851 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 175) ... ok Nov 18 10:33:05.035 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) has been running for over 60 seconds Nov 18 10:33:06.527 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 690) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 690) has been running for over 60 seconds Nov 18 10:33:07.811 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 556) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 556) has been running for over 60 seconds Nov 18 10:33:12.260 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 608) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 608) has been running for over 60 seconds Nov 18 10:33:15.563 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 275) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 275) has been running for over 60 seconds Nov 18 10:33:33.367 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... ok Nov 18 10:33:37.599 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 690) ... ok Nov 18 10:33:37.603 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 87) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 87) has been running for over 60 seconds Nov 18 10:33:40.159 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 43) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 43) has been running for over 60 seconds Nov 18 10:33:48.867 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 124) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 124) has been running for over 60 seconds Nov 18 10:33:50.107 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 608) ... ok Nov 18 10:33:50.819 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 275) ... ok Nov 18 10:33:52.011 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 556) ... ok Nov 18 10:34:16.975 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 87) ... ok Nov 18 10:34:20.755 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 43) ... ok Nov 18 10:34:33.371 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 312) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 312) has been running for over 60 seconds Nov 18 10:34:33.907 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 124) ... ok Nov 18 10:34:37.599 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 237) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 237) has been running for over 60 seconds Nov 18 10:34:50.111 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 161) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 161) has been running for over 60 seconds Nov 18 10:34:50.831 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 199) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 199) has been running for over 60 seconds Nov 18 10:34:52.012 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 354) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 354) has been running for over 60 seconds Nov 18 10:35:16.983 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 414) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 414) has been running for over 60 seconds Nov 18 10:35:20.779 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 469) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 469) has been running for over 60 seconds Nov 18 10:35:24.603 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 312) ... ok Nov 18 10:35:29.335 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 237) ... ok Nov 18 10:35:33.911 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::delete (line 804) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::delete (line 804) has been running for over 60 seconds Nov 18 10:35:33.991 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 161) ... ok Nov 18 10:35:38.795 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 354) ... ok Nov 18 10:35:49.595 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 199) ... ok Nov 18 10:35:55.842 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 469) ... ok Nov 18 10:36:01.215 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 414) ... ok Nov 18 10:36:24.597 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get (line 637) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get (line 637) has been running for over 60 seconds Nov 18 10:36:29.339 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get_or_head (line 589) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get_or_head (line 589) has been running for over 60 seconds Nov 18 10:36:29.719 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::delete (line 804) ... ok Nov 18 10:36:33.991 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::head (line 548) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::head (line 548) has been running for over 60 seconds Nov 18 10:36:38.803 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::options (line 845) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::options (line 845) has been running for over 60 seconds Nov 18 10:36:49.611 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::patch (line 762) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::patch (line 762) has been running for over 60 seconds Nov 18 10:36:55.844 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::post (line 678) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::post (line 678) has been running for over 60 seconds Nov 18 10:37:01.222 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::put (line 720) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::put (line 720) has been running for over 60 seconds Nov 18 10:37:15.555 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get (line 637) ... ok Nov 18 10:37:16.019 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::head (line 548) ... ok Nov 18 10:37:21.423 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::options (line 845) ... ok Nov 18 10:37:22.216 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get_or_head (line 589) ... ok Nov 18 10:37:29.723 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::request (line 475) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::request (line 475) has been running for over 60 seconds Nov 18 10:37:41.923 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::patch (line 762) ... ok Nov 18 10:37:46.544 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::post (line 678) ... ok Nov 18 10:37:48.195 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::put (line 720) ... ok Nov 18 10:38:15.583 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_path_extractor (line 349) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_path_extractor (line 349) has been running for over 60 seconds Nov 18 10:38:16.043 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_query_string_extractor (line 410) ... test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_query_string_extractor (line 410) has been running for over 60 seconds Nov 18 10:38:21.427 INFO [stdout] test src/router/builder/mod.rs - router::builder::RouterBuilder<'a, C, P>::add_response_extender (line 168) ... test src/router/builder/mod.rs - router::builder::RouterBuilder<'a, C, P>::add_response_extender (line 168) has been running for over 60 seconds Nov 18 10:38:22.223 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_router (line 37) ... test src/router/builder/mod.rs - router::builder::build_router (line 37) has been running for over 60 seconds Nov 18 10:38:34.195 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::request (line 475) ... ok Nov 18 10:38:41.927 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_simple_router (line 109) ... test src/router/builder/mod.rs - router::builder::build_simple_router (line 109) has been running for over 60 seconds Nov 18 10:38:46.555 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute (line 18) ... test src/router/builder/single.rs - router::builder::single::DefineSingleRoute (line 18) has been running for over 60 seconds Nov 18 10:38:48.203 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to (line 63) ... test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to (line 63) has been running for over 60 seconds Nov 18 10:39:20.068 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_path_extractor (line 349) ... ok Nov 18 10:39:20.911 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::with_query_string_extractor (line 410) ... ok Nov 18 10:39:26.667 INFO [stdout] test src/router/builder/mod.rs - router::builder::RouterBuilder<'a, C, P>::add_response_extender (line 168) ... ok Nov 18 10:39:26.759 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_router (line 37) ... ok Nov 18 10:39:34.203 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to_new_handler (line 110) ... test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to_new_handler (line 110) has been running for over 60 seconds Nov 18 10:39:44.473 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_simple_router (line 109) ... ok Nov 18 10:39:55.503 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to (line 63) ... ok Nov 18 10:39:56.207 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute (line 18) ... ok Nov 18 10:40:20.079 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::with_path_extractor (line 174) ... test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::with_path_extractor (line 174) has been running for over 60 seconds Nov 18 10:40:20.915 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::with_query_string_extractor (line 243) ... test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::with_query_string_extractor (line 243) has been running for over 60 seconds Nov 18 10:40:22.863 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to_new_handler (line 110) ... ok Nov 18 10:40:26.699 INFO [stdout] test src/router/non_match.rs - router::non_match::RouteNonMatch (line 12) ... test src/router/non_match.rs - router::non_match::RouteNonMatch (line 12) has been running for over 60 seconds Nov 18 10:40:26.767 INFO [stdout] test src/router/route/matcher/accept.rs - router::route::matcher::accept::AcceptHeaderRouteMatcher (line 19) ... test src/router/route/matcher/accept.rs - router::route::matcher::accept::AcceptHeaderRouteMatcher (line 19) has been running for over 60 seconds Nov 18 10:40:40.352 ERRO error running command: command timed out after 900 seconds Nov 18 10:40:40.352 INFO running `"docker" "inspect" "f4254969343a931353a8079c464a08455d9ce53d08a684498b813fe7bec909e3"` Nov 18 10:40:40.759 INFO running `"docker" "rm" "-f" "f4254969343a931353a8079c464a08455d9ce53d08a684498b813fe7bec909e3"` Nov 18 10:40:42.996 INFO [stdout] f4254969343a931353a8079c464a08455d9ce53d08a684498b813fe7bec909e3