Nov 18 10:25:03.129 INFO testing gotham-0.2.1 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 18 10:25:03.130 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:25:03.626 INFO [stdout] b7331fceadb411618da37bc717efc2a9f5318466e28ec1c7e0f614845c58d600 Nov 18 10:25:03.628 INFO running `"docker" "start" "-a" "b7331fceadb411618da37bc717efc2a9f5318466e28ec1c7e0f614845c58d600"` Nov 18 10:25:04.479 INFO [stderr] usermod: no changes Nov 18 10:25:04.915 INFO [stderr] Compiling borrow-bag v1.0.0 Nov 18 10:25:10.812 INFO [stderr] Compiling gotham v0.2.1 (/source) Nov 18 10:25:17.443 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:17.443 INFO [stderr] --> src/http/header/x_frame_options.rs:7:5 Nov 18 10:25:17.443 INFO [stderr] | Nov 18 10:25:17.443 INFO [stderr] 7 | use std::ascii::AsciiExt; Nov 18 10:25:17.444 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:17.444 INFO [stderr] | Nov 18 10:25:17.444 INFO [stderr] note: lint level defined here Nov 18 10:25:17.444 INFO [stderr] --> src/lib.rs:7:23 Nov 18 10:25:17.444 INFO [stderr] | Nov 18 10:25:17.445 INFO [stderr] 7 | #![warn(missing_docs, deprecated)] Nov 18 10:25:17.445 INFO [stderr] | ^^^^^^^^^^ Nov 18 10:25:17.445 INFO [stderr] Nov 18 10:25:17.445 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:17.445 INFO [stderr] --> src/http/header/x_content_type_options.rs:6:5 Nov 18 10:25:17.446 INFO [stderr] | Nov 18 10:25:17.446 INFO [stderr] 6 | use std::ascii::AsciiExt; Nov 18 10:25:17.446 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:17.446 INFO [stderr] Nov 18 10:25:21.777 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 17.27s Nov 18 10:25:21.791 INFO [stderr] su: No module specific data is present Nov 18 10:25:22.235 INFO running `"docker" "inspect" "b7331fceadb411618da37bc717efc2a9f5318466e28ec1c7e0f614845c58d600"` Nov 18 10:25:22.531 INFO running `"docker" "rm" "-f" "b7331fceadb411618da37bc717efc2a9f5318466e28ec1c7e0f614845c58d600"` Nov 18 10:25:22.859 INFO [stdout] b7331fceadb411618da37bc717efc2a9f5318466e28ec1c7e0f614845c58d600 Nov 18 10:25:22.864 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:23.284 INFO [stdout] cdcd96efcce851ba3d937e5d98158b70c59d1dbbe4965d9566e3e9b7e0d09156 Nov 18 10:25:23.286 INFO running `"docker" "start" "-a" "cdcd96efcce851ba3d937e5d98158b70c59d1dbbe4965d9566e3e9b7e0d09156"` Nov 18 10:25:24.939 INFO [stderr] usermod: no changes Nov 18 10:25:25.368 INFO [stderr] Compiling gotham v0.2.1 (/source) Nov 18 10:25:30.947 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:30.948 INFO [stderr] --> src/http/header/x_frame_options.rs:7:5 Nov 18 10:25:30.948 INFO [stderr] | Nov 18 10:25:30.948 INFO [stderr] 7 | use std::ascii::AsciiExt; Nov 18 10:25:30.949 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:30.949 INFO [stderr] | Nov 18 10:25:30.949 INFO [stderr] note: lint level defined here Nov 18 10:25:30.949 INFO [stderr] --> src/lib.rs:7:23 Nov 18 10:25:30.950 INFO [stderr] | Nov 18 10:25:30.950 INFO [stderr] 7 | #![warn(missing_docs, deprecated)] Nov 18 10:25:30.950 INFO [stderr] | ^^^^^^^^^^ Nov 18 10:25:30.951 INFO [stderr] Nov 18 10:25:30.951 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead Nov 18 10:25:30.951 INFO [stderr] --> src/http/header/x_content_type_options.rs:6:5 Nov 18 10:25:30.952 INFO [stderr] | Nov 18 10:25:30.952 INFO [stderr] 6 | use std::ascii::AsciiExt; Nov 18 10:25:30.952 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^ Nov 18 10:25:30.953 INFO [stderr] Nov 18 10:25:48.572 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 23.60s Nov 18 10:25:48.589 INFO [stderr] su: No module specific data is present Nov 18 10:25:48.961 INFO running `"docker" "inspect" "cdcd96efcce851ba3d937e5d98158b70c59d1dbbe4965d9566e3e9b7e0d09156"` Nov 18 10:25:49.053 INFO running `"docker" "rm" "-f" "cdcd96efcce851ba3d937e5d98158b70c59d1dbbe4965d9566e3e9b7e0d09156"` Nov 18 10:25:49.207 INFO [stdout] cdcd96efcce851ba3d937e5d98158b70c59d1dbbe4965d9566e3e9b7e0d09156 Nov 18 10:25:49.212 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:49.736 INFO [stdout] 0161ca4b2c733e4924d98d971f370b189484659a44e392f430ca9bca806c5c63 Nov 18 10:25:49.740 INFO running `"docker" "start" "-a" "0161ca4b2c733e4924d98d971f370b189484659a44e392f430ca9bca806c5c63"` Nov 18 10:25:50.526 INFO [stderr] usermod: no changes Nov 18 10:25:50.800 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.25s Nov 18 10:25:50.819 INFO [stderr] Running /target/debug/deps/gotham-fa3ec59a0fac0ec4 Nov 18 10:25:50.821 INFO [stdout] Nov 18 10:25:50.822 INFO [stdout] running 82 tests Nov 18 10:25:50.839 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok Nov 18 10:25:50.839 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok Nov 18 10:25:50.839 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok Nov 18 10:25:50.839 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok Nov 18 10:25:50.839 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok Nov 18 10:25:50.840 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok Nov 18 10:25:50.840 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok Nov 18 10:25:50.840 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok Nov 18 10:25:50.840 INFO [stdout] test http::header::tests::invalid_rws_from_one_header ... ok Nov 18 10:25:50.841 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok Nov 18 10:25:50.841 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok Nov 18 10:25:50.841 INFO [stdout] test http::header::x_content_type_options::tests::parse_fails ... ok Nov 18 10:25:50.843 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_content_type_options::tests::parse_nosniff ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::tests::valid_rws_from_one_header ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_content_type_options::tests::valid_display_formatting ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_frame_options::tests::parse_deny ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_frame_options::tests::parse_allow_from ... ok Nov 18 10:25:50.847 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_xss_protection::tests::valid_display_formatting ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_xss_protection::tests::parse_fails ... ok Nov 18 10:25:50.847 INFO [stdout] test http::request::path::tests::request_path_segments_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test http::tests::ensure_valid_percent_decode ... ok Nov 18 10:25:50.847 INFO [stdout] test http::tests::ensure_valid_www_form_url_encoded_value ... ok Nov 18 10:25:50.847 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok Nov 18 10:25:50.847 INFO [stdout] test http::request::query_string::tests::query_string_mapping_tests ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_xss_protection::tests::parse_enable ... ok Nov 18 10:25:50.847 INFO [stdout] test http::header::x_xss_protection::tests::parse_disable ... ok Nov 18 10:25:50.847 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok Nov 18 10:25:50.847 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok Nov 18 10:25:50.847 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok Nov 18 10:25:50.847 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok Nov 18 10:25:50.848 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok Nov 18 10:25:50.848 INFO [stdout] test http::header::x_frame_options::tests::parse_same_origin ... ok Nov 18 10:25:50.851 INFO [stdout] test http::header::x_frame_options::tests::valid_display_formatting ... ok Nov 18 10:25:50.851 INFO [stdout] test http::header::x_frame_options::tests::parse_fails ... ok Nov 18 10:25:50.855 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok Nov 18 10:25:50.855 INFO [stdout] test middleware::session::tests::existing_session ... ok Nov 18 10:25:50.855 INFO [stdout] test middleware::session::tests::new_session ... ok Nov 18 10:25:50.855 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok Nov 18 10:25:50.855 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok Nov 18 10:25:50.855 INFO [stdout] test router::non_match::tests::intersection_tests ... ok Nov 18 10:25:50.855 INFO [stdout] test router::non_match::tests::union_tests ... ok Nov 18 10:25:50.859 INFO [stdout] test router::route::tests::external_route_tests ... ok Nov 18 10:25:50.859 INFO [stdout] test router::route::tests::internal_route_tests ... ok Nov 18 10:25:50.859 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok Nov 18 10:25:50.859 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok Nov 18 10:25:50.867 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok Nov 18 10:25:50.867 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok Nov 18 10:25:50.871 INFO [stdout] test router::builder::tests::build_router_test ... ok Nov 18 10:25:50.871 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok Nov 18 10:25:50.875 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok Nov 18 10:25:50.875 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok Nov 18 10:25:50.875 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok Nov 18 10:25:50.875 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok Nov 18 10:25:50.875 INFO [stdout] test router::tree::node::tests::manages_children ... ok Nov 18 10:25:50.883 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok Nov 18 10:25:50.883 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok Nov 18 10:25:50.883 INFO [stdout] test router::tree::node::tests::traverses_children ... ok Nov 18 10:25:50.903 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok Nov 18 10:25:50.912 INFO [stdout] test service::tests::new_handler_closure ... ok Nov 18 10:25:50.927 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok Nov 18 10:25:50.933 INFO [stdout] test service::tests::router ... ok Nov 18 10:25:50.933 INFO [stdout] test service::trap::tests::error ... ok Nov 18 10:25:50.934 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok Nov 18 10:25:50.935 INFO [stdout] test service::trap::tests::success ... ok Nov 18 10:25:50.939 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok Nov 18 10:25:50.943 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok Nov 18 10:25:50.943 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok Nov 18 10:25:50.983 INFO [stdout] test test::tests::async_echo ... ok Nov 18 10:25:50.991 INFO [stdout] test test::tests::serves_requests ... ok Nov 18 10:25:50.994 INFO [stdout] test test::tests::sets_client_addr ... ok Nov 18 10:25:51.050 INFO [stdout] test router::tree::node::tests::panics_when_node_with_children_is_provided_delegated_route ... ok Nov 18 10:25:51.050 INFO [stdout] test router::tree::node::tests::panics_when_delegated_node_adds_children ... ok Nov 18 10:25:51.055 INFO [stdout] test router::tree::node::tests::panics_when_node_with_a_route_adds_another ... ok Nov 18 10:25:51.083 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok Nov 18 10:25:51.083 INFO [stdout] test service::trap::tests::async_panic ... ok Nov 18 10:25:51.083 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok Nov 18 10:25:51.084 INFO [stdout] test service::trap::tests::panic ... ok Nov 18 10:25:52.002 INFO [stdout] test test::tests::times_out ... ok Nov 18 10:25:52.002 INFO [stdout] Nov 18 10:25:52.003 INFO [stdout] test result: ok. 82 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 10:25:52.003 INFO [stdout] Nov 18 10:25:52.003 INFO [stderr] Doc-tests gotham Nov 18 10:26:01.670 INFO [stdout] Nov 18 10:26:01.670 INFO [stdout] running 99 tests Nov 18 10:26:05.290 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 43) ... ok Nov 18 10:26:53.647 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok Nov 18 10:26:56.475 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 113) ... ok Nov 18 10:27:01.655 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_request_id::XRequestId (line 43) ... test <::hyper::header::common::header macros> - http::header::x_request_id::XRequestId (line 43) has been running for over 60 seconds Nov 18 10:27:01.655 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:27:01.675 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:27:01.699 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) has been running for over 60 seconds Nov 18 10:27:01.699 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:27:05.295 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 40) ... test src/handler/mod.rs - handler::Handler (line 40) has been running for over 60 seconds Nov 18 10:27:06.675 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_runtime_microseconds::XRuntimeMicroseconds (line 41) ... ok Nov 18 10:27:09.319 INFO [stdout] test <::hyper::header::common::header macros> - http::header::x_request_id::XRequestId (line 43) ... ok Nov 18 10:27:09.747 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 40) ... ok Nov 18 10:27:19.107 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok Nov 18 10:27:20.595 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok Nov 18 10:27:20.919 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok Nov 18 10:27:37.235 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 63) ... ok Nov 18 10:27:55.459 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 85) ... ok Nov 18 10:27:56.871 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 159) ... ok Nov 18 10:27:57.967 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 193) ... ok Nov 18 10:28:06.673 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:28:16.687 INFO [stdout] test src/http/header/x_xss_protection.rs - http::header::x_xss_protection::XXssProtection (line 21) ... ok Nov 18 10:28:19.107 INFO [stdout] test src/http/header/x_content_type_options.rs - http::header::x_content_type_options::XContentTypeOptions (line 25) ... test src/http/header/x_content_type_options.rs - http::header::x_content_type_options::XContentTypeOptions (line 25) has been running for over 60 seconds Nov 18 10:28:19.463 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 277) ... ok Nov 18 10:28:20.595 INFO [stdout] test src/http/header/x_frame_options.rs - http::header::x_frame_options::XFrameOptions (line 41) ... test src/http/header/x_frame_options.rs - http::header::x_frame_options::XFrameOptions (line 41) has been running for over 60 seconds Nov 18 10:28:22.810 INFO [stdout] test src/http/header/x_frame_options.rs - http::header::x_frame_options::XFrameOptions (line 41) ... ok Nov 18 10:28:28.687 INFO [stdout] test src/http/header/x_content_type_options.rs - http::header::x_content_type_options::XContentTypeOptions (line 25) ... ok Nov 18 10:28:37.232 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:55.471 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:56.880 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:57.967 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:58.551 INFO [stdout] test src/http/response/mod.rs - http::response::create_response (line 20) ... ok Nov 18 10:29:16.705 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:29:19.495 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:29:22.811 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:29:28.715 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:32.183 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 166) ... ok Nov 18 10:29:32.899 INFO [stdout] test src/http/response/mod.rs - http::response::extend_response (line 80) ... ok Nov 18 10:29:33.975 INFO [stdout] test src/http/response/mod.rs - http::response::set_headers (line 228) ... ok Nov 18 10:29:53.079 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 136) ... ok Nov 18 10:29:58.323 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 26) ... ok Nov 18 10:29:58.555 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:02.576 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 210) ... ok Nov 18 10:30:15.163 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 271) ... ok Nov 18 10:30:32.195 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:32.903 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:33.987 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:41.948 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 75) ... ok Nov 18 10:30:53.077 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:55.135 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok Nov 18 10:30:58.323 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:31:02.579 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:31:08.403 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 416) ... ok Nov 18 10:31:12.359 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 343) ... ok Nov 18 10:31:15.163 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:31:30.903 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 427) ... ok Nov 18 10:31:36.791 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 438) ... ok Nov 18 10:31:37.683 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 698) ... ok Nov 18 10:31:41.967 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:45.799 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 587) ... ok Nov 18 10:31:55.131 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:32:03.723 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 660) ... ok Nov 18 10:32:08.407 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:32:12.375 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:32:22.919 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 629) ... ok Nov 18 10:32:30.503 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 763) ... ok Nov 18 10:32:30.911 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:32:34.735 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 555) ... ok Nov 18 10:32:36.795 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:32:37.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:45.803 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:33:03.727 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:03.863 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 186) ... ok Nov 18 10:33:04.286 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 734) ... ok Nov 18 10:33:08.587 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 175) ... ok Nov 18 10:33:09.083 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok Nov 18 10:33:22.927 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:27.967 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... ok Nov 18 10:33:30.515 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:34.735 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:34:01.903 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 690) ... ok Nov 18 10:34:03.886 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:34:04.311 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:34:08.599 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:34:09.091 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:34:17.647 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 556) ... ok Nov 18 10:34:26.508 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 608) ... ok Nov 18 10:34:27.975 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:38.335 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 87) ... ok Nov 18 10:34:42.360 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 124) ... ok Nov 18 10:34:50.524 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 43) ... ok Nov 18 10:34:53.687 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 275) ... ok Nov 18 10:35:01.911 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:35:14.775 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 312) ... ok Nov 18 10:35:17.639 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:35:26.487 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:35:38.343 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:42.375 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:50.527 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:53.691 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:55.363 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 237) ... ok Nov 18 10:36:08.527 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 161) ... ok Nov 18 10:36:11.223 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 199) ... ok Nov 18 10:36:14.791 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:27.687 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 414) ... ok Nov 18 10:36:28.807 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 354) ... ok Nov 18 10:36:42.403 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::delete (line 804) ... ok Nov 18 10:36:42.651 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 469) ... ok Nov 18 10:36:55.375 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:37:06.295 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::get (line 637) ... ok Nov 18 10:37:08.503 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:37:11.223 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:37:27.687 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:37:28.807 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:42.431 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:42.651 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:56.599 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:38:06.307 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:07.891 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::head (line 548) ... ok Nov 18 10:38:16.087 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::options (line 845) ... ok Nov 18 10:38:29.992 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::post (line 678) ... ok Nov 18 10:38:32.069 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::patch (line 762) ... ok Nov 18 10:38:53.538 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::request (line 475) ... ok Nov 18 10:38:53.543 INFO [stdout] test src/router/builder/mod.rs - router::builder::AssociatedRouteBuilder<'a, C, P, PE, QSE>::put (line 720) ... ok Nov 18 10:38:56.604 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:39:07.899 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:39:13.923 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:16.091 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:39:30.015 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:39:32.069 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:39:52.519 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:53.543 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:53.543 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:40:06.867 INFO [stdout] test src/router/builder/mod.rs - router::builder::RouterBuilder<'a, C, P>::add_response_extender (line 168) ... ok Nov 18 10:40:12.827 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_router (line 37) ... ok Nov 18 10:40:13.927 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:18.391 INFO [stdout] test src/router/builder/mod.rs - router::builder::build_simple_router (line 109) ... ok Nov 18 10:40:27.591 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to_new_handler (line 110) ... ok Nov 18 10:40:29.375 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute (line 18) ... ok Nov 18 10:40:37.397 INFO [stdout] test src/router/builder/single.rs - router::builder::single::DefineSingleRoute::to (line 63) ... ok Nov 18 10:40:49.753 ERRO error running command: command timed out after 900 seconds Nov 18 10:40:49.753 INFO running `"docker" "inspect" "0161ca4b2c733e4924d98d971f370b189484659a44e392f430ca9bca806c5c63"` Nov 18 10:40:50.113 INFO running `"docker" "rm" "-f" "0161ca4b2c733e4924d98d971f370b189484659a44e392f430ca9bca806c5c63"` Nov 18 10:40:51.503 INFO [stdout] 0161ca4b2c733e4924d98d971f370b189484659a44e392f430ca9bca806c5c63