Dec 07 12:02:53.523 INFO testing gotham-0.3.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 07 12:02:53.523 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"` Dec 07 12:02:53.867 INFO [stdout] 063fdebacf818c28fa07f7ed3b590a74af5eda9ad4c7507054c9feb3a09c299f Dec 07 12:02:53.874 INFO running `"docker" "start" "-a" "063fdebacf818c28fa07f7ed3b590a74af5eda9ad4c7507054c9feb3a09c299f"` Dec 07 12:02:54.436 INFO [stderr] usermod: no changes Dec 07 12:02:54.861 INFO [stderr] Compiling cookie v0.11.0 Dec 07 12:02:54.865 INFO [stderr] Compiling rand_pcg v0.1.1 Dec 07 12:02:54.865 INFO [stderr] Compiling rand_chacha v0.1.0 Dec 07 12:02:54.866 INFO [stderr] Compiling rand v0.6.1 Dec 07 12:02:54.905 INFO [stderr] Compiling cargo_metadata v0.6.3 Dec 07 12:02:54.905 INFO [stderr] Compiling parking_lot_core v0.3.1 Dec 07 12:03:01.885 INFO [stderr] Compiling parking_lot v0.6.4 Dec 07 12:03:06.192 INFO [stderr] Compiling skeptic v0.13.4 Dec 07 12:03:07.578 INFO [stderr] Compiling tokio-reactor v0.1.7 Dec 07 12:03:10.714 INFO [stderr] Compiling tokio-threadpool v0.1.9 Dec 07 12:03:12.734 INFO [stderr] Compiling tokio-uds v0.2.4 Dec 07 12:03:12.736 INFO [stderr] Compiling tokio-tcp v0.1.2 Dec 07 12:03:12.737 INFO [stderr] Compiling tokio-udp v0.1.3 Dec 07 12:03:22.160 INFO [stderr] Compiling borrow-bag v1.0.0 Dec 07 12:03:22.359 INFO [stderr] Compiling tokio-fs v0.1.4 Dec 07 12:03:26.848 INFO [stderr] Compiling tokio v0.1.13 Dec 07 12:03:32.398 INFO [stderr] Compiling hyper v0.12.17 Dec 07 12:03:41.988 INFO [stderr] Compiling gotham v0.3.0 (/source) Dec 07 12:03:56.241 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 01s Dec 07 12:03:56.254 INFO [stderr] su: No module specific data is present Dec 07 12:03:57.205 INFO running `"docker" "inspect" "063fdebacf818c28fa07f7ed3b590a74af5eda9ad4c7507054c9feb3a09c299f"` Dec 07 12:03:57.394 INFO running `"docker" "rm" "-f" "063fdebacf818c28fa07f7ed3b590a74af5eda9ad4c7507054c9feb3a09c299f"` Dec 07 12:03:57.795 INFO [stdout] 063fdebacf818c28fa07f7ed3b590a74af5eda9ad4c7507054c9feb3a09c299f Dec 07 12:03:57.796 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"` Dec 07 12:03:57.975 INFO [stdout] 6f8de58fc87a22ab9954959caf8afb4da3818926082c694d0d0f6697015c522e Dec 07 12:03:57.976 INFO running `"docker" "start" "-a" "6f8de58fc87a22ab9954959caf8afb4da3818926082c694d0d0f6697015c522e"` Dec 07 12:03:58.452 INFO [stderr] usermod: no changes Dec 07 12:03:58.682 INFO [stderr] Compiling gotham_derive v0.3.0 Dec 07 12:04:01.230 INFO [stderr] Compiling gotham v0.3.0 (/source) Dec 07 12:04:27.864 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 29.37s Dec 07 12:04:27.876 INFO [stderr] su: No module specific data is present Dec 07 12:04:28.544 INFO running `"docker" "inspect" "6f8de58fc87a22ab9954959caf8afb4da3818926082c694d0d0f6697015c522e"` Dec 07 12:04:28.730 INFO running `"docker" "rm" "-f" "6f8de58fc87a22ab9954959caf8afb4da3818926082c694d0d0f6697015c522e"` Dec 07 12:04:28.876 INFO [stdout] 6f8de58fc87a22ab9954959caf8afb4da3818926082c694d0d0f6697015c522e Dec 07 12:04:28.885 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"` Dec 07 12:04:29.081 INFO [stdout] ffd2c67762772c6873726b1a0d1a72472dd4b7f08dc9c4c4f0a4e6529432d953 Dec 07 12:04:29.083 INFO running `"docker" "start" "-a" "ffd2c67762772c6873726b1a0d1a72472dd4b7f08dc9c4c4f0a4e6529432d953"` Dec 07 12:04:29.720 INFO [stderr] usermod: no changes Dec 07 12:04:30.080 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.32s Dec 07 12:04:30.084 INFO [stderr] Running /target/debug/deps/gotham-4764cf7d1e902c82 Dec 07 12:04:30.089 INFO [stdout] Dec 07 12:04:30.089 INFO [stdout] running 80 tests Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_headers ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok Dec 07 12:04:30.089 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok Dec 07 12:04:30.100 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok Dec 07 12:04:30.100 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok Dec 07 12:04:30.100 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok Dec 07 12:04:30.100 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_values ... ok Dec 07 12:04:30.100 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single_with_quality ... ok Dec 07 12:04:30.100 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single ... ok Dec 07 12:04:30.328 INFO [stdout] test handler::assets::tests::assets_default_cache_control ... ok Dec 07 12:04:30.363 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_accepted ... ok Dec 07 12:04:30.404 INFO [stdout] test handler::assets::tests::assets_if_modified_since ... ok Dec 07 12:04:30.404 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_exists ... ok Dec 07 12:04:30.412 INFO [stdout] test helpers::http::request::path::tests::request_path_segments_tests ... ok Dec 07 12:04:30.420 INFO [stdout] test helpers::http::request::query_string::tests::query_string_mapping_tests ... ok Dec 07 12:04:30.433 INFO [stdout] test helpers::http::tests::ensure_valid_percent_decode ... ok Dec 07 12:04:30.440 INFO [stdout] test helpers::http::tests::ensure_valid_www_form_url_encoded_value ... ok Dec 07 12:04:30.441 INFO [stdout] test handler::assets::tests::assets_if_none_match_etag ... ok Dec 07 12:04:30.457 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok Dec 07 12:04:30.469 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok Dec 07 12:04:30.521 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok Dec 07 12:04:30.526 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok Dec 07 12:04:30.532 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok Dec 07 12:04:30.546 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok Dec 07 12:04:30.555 INFO [stdout] test middleware::session::tests::existing_session ... ok Dec 07 12:04:30.561 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok Dec 07 12:04:30.564 INFO [stdout] test handler::assets::tests::assets_single_file ... ok Dec 07 12:04:30.566 INFO [stdout] test middleware::session::tests::new_session ... ok Dec 07 12:04:30.567 INFO [stdout] test handler::assets::tests::assets_compressed_if_accept_and_exists ... ok Dec 07 12:04:30.577 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok Dec 07 12:04:30.579 INFO [stdout] test handler::assets::tests::assets_weighted_accept_encoding ... ok Dec 07 12:04:30.596 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok Dec 07 12:04:30.597 INFO [stdout] test router::non_match::tests::intersection_tests ... ok Dec 07 12:04:30.604 INFO [stdout] test router::non_match::tests::union_tests ... ok Dec 07 12:04:30.638 INFO [stdout] test router::builder::tests::build_router_test ... ok Dec 07 12:04:30.639 INFO [stdout] test router::route::tests::external_route_tests ... ok Dec 07 12:04:30.640 INFO [stdout] test handler::assets::tests::assets_with_cache_control ... ok Dec 07 12:04:30.640 INFO [stdout] test router::route::tests::internal_route_tests ... ok Dec 07 12:04:30.649 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok Dec 07 12:04:30.650 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok Dec 07 12:04:30.657 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok Dec 07 12:04:30.694 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok Dec 07 12:04:30.696 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok Dec 07 12:04:30.727 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok Dec 07 12:04:30.727 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok Dec 07 12:04:30.732 INFO [stdout] test router::tree::node::tests::manages_children ... ok Dec 07 12:04:30.737 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok Dec 07 12:04:30.744 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok Dec 07 12:04:30.746 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok Dec 07 12:04:30.747 INFO [stdout] test handler::assets::tests::assets_guesses_content_type ... ok Dec 07 12:04:30.766 INFO [stdout] test router::tree::node::tests::traverses_children ... ok Dec 07 12:04:30.797 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok Dec 07 12:04:30.797 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok Dec 07 12:04:30.799 INFO [stdout] test service::tests::router ... ok Dec 07 12:04:30.800 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok Dec 07 12:04:30.804 INFO [stdout] test service::tests::new_handler_closure ... ok Dec 07 12:04:30.804 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok Dec 07 12:04:30.821 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok Dec 07 12:04:30.822 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok Dec 07 12:04:30.823 INFO [stdout] test service::trap::tests::error ... ok Dec 07 12:04:30.824 INFO [stdout] test service::trap::tests::success ... ok Dec 07 12:04:30.828 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok Dec 07 12:04:30.828 INFO [stdout] test test::tests::sets_client_addr ... ignored Dec 07 12:04:30.828 INFO [stdout] test test::tests::times_out ... ignored Dec 07 12:04:30.897 INFO [stdout] test test::tests::async_echo ... ok Dec 07 12:04:30.897 INFO [stdout] test test::tests::serves_requests ... ok Dec 07 12:04:31.284 INFO [stdout] test handler::assets::tests::assets_path_traversal ... ok Dec 07 12:04:31.380 INFO [stdout] test service::trap::tests::async_panic ... ok Dec 07 12:04:31.380 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok Dec 07 12:04:31.396 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok Dec 07 12:04:31.396 INFO [stdout] test service::trap::tests::panic ... ok Dec 07 12:04:31.396 INFO [stdout] Dec 07 12:04:31.396 INFO [stdout] test result: ok. 78 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out Dec 07 12:04:31.396 INFO [stdout] Dec 07 12:04:31.409 INFO [stderr] Doc-tests gotham Dec 07 12:04:44.038 INFO [stdout] Dec 07 12:04:44.038 INFO [stdout] running 101 tests Dec 07 12:04:49.019 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 45) ... ok Dec 07 12:05:44.048 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 Dec 07 12:05:44.048 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 Dec 07 12:05:44.060 INFO [stdout] test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) ... test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) has been running for over 60 seconds Dec 07 12:05:44.064 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 Dec 07 12:05:44.088 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... test src/handler/error.rs - handler::error::IntoHandlerError (line 22) has been running for over 60 seconds Dec 07 12:05:44.121 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 48) ... test src/handler/mod.rs - handler::Handler (line 48) has been running for over 60 seconds Dec 07 12:05:44.121 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 121) ... test src/handler/mod.rs - handler::Handler (line 121) has been running for over 60 seconds Dec 07 12:05:49.028 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 71) ... test src/handler/mod.rs - handler::Handler (line 71) has been running for over 60 seconds Dec 07 12:05:50.332 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 121) ... ok Dec 07 12:05:56.204 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok Dec 07 12:06:00.780 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 48) ... ok Dec 07 12:06:05.540 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 71) ... ok Dec 07 12:06:18.176 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok Dec 07 12:06:19.840 INFO [stdout] test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) ... ok Dec 07 12:06:20.652 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok Dec 07 12:06:29.484 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok Dec 07 12:06:50.332 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 93) ... test src/handler/mod.rs - handler::Handler (line 93) has been running for over 60 seconds Dec 07 12:06:56.204 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 295) ... test src/handler/mod.rs - handler::IntoResponse (line 295) has been running for over 60 seconds Dec 07 12:07:00.784 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 177) ... test src/handler/mod.rs - handler::NewHandler (line 177) has been running for over 60 seconds Dec 07 12:07:05.536 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 211) ... test src/handler/mod.rs - handler::NewHandler (line 211) has been running for over 60 seconds Dec 07 12:07:18.180 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) ... test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) has been running for over 60 seconds Dec 07 12:07:19.840 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) ... test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) has been running for over 60 seconds Dec 07 12:07:20.668 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) ... test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) has been running for over 60 seconds Dec 07 12:07:29.492 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) ... test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) has been running for over 60 seconds Dec 07 12:07:30.318 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 93) ... ok Dec 07 12:07:40.833 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 177) ... ok Dec 07 12:07:46.648 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 295) ... ok Dec 07 12:07:46.652 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 211) ... ok Dec 07 12:08:23.964 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) ... ok Dec 07 12:08:27.961 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) ... ok Dec 07 12:08:30.332 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 140) ... test src/middleware/mod.rs - middleware::Middleware (line 140) has been running for over 60 seconds Dec 07 12:08:31.159 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) ... ok Dec 07 12:08:37.681 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) ... ok Dec 07 12:08:40.840 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 203) ... test src/middleware/mod.rs - middleware::Middleware (line 203) has been running for over 60 seconds Dec 07 12:08:46.645 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 264) ... test src/middleware/mod.rs - middleware::Middleware (line 264) has been running for over 60 seconds Dec 07 12:08:46.649 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 30) ... test src/middleware/mod.rs - middleware::Middleware (line 30) has been running for over 60 seconds Dec 07 12:09:13.642 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 140) ... ok Dec 07 12:09:23.968 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 79) ... test src/middleware/mod.rs - middleware::Middleware (line 79) has been running for over 60 seconds Dec 07 12:09:27.992 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 336) ... test src/middleware/mod.rs - middleware::NewMiddleware (line 336) has been running for over 60 seconds Dec 07 12:09:31.168 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 Dec 07 12:09:37.700 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) has been running for over 60 seconds Dec 07 12:09:46.804 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 203) ... ok Dec 07 12:09:52.756 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 30) ... ok Dec 07 12:09:52.988 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 264) ... ok Dec 07 12:10:13.656 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) has been running for over 60 seconds Dec 07 12:10:14.920 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 79) ... ok Dec 07 12:10:23.236 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 336) ... ok Dec 07 12:10:23.804 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok Dec 07 12:10:30.500 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) ... ok Dec 07 12:10:46.804 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) has been running for over 60 seconds Dec 07 12:10:52.764 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 696) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 696) has been running for over 60 seconds Dec 07 12:10:52.992 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 585) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 585) has been running for over 60 seconds Dec 07 12:11:14.928 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 658) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 658) has been running for over 60 seconds Dec 07 12:11:22.956 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) ... ok Dec 07 12:11:23.240 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 627) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 627) has been running for over 60 seconds Dec 07 12:11:23.808 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 553) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 553) has been running for over 60 seconds Dec 07 12:11:30.509 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 761) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 761) has been running for over 60 seconds Dec 07 12:11:55.988 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) ... ok Dec 07 12:11:59.752 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 585) ... ok Dec 07 12:12:02.896 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 696) ... ok Dec 07 12:12:15.640 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 658) ... ok Dec 07 12:12:22.777 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 553) ... ok Dec 07 12:12:22.964 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 732) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 732) has been running for over 60 seconds Dec 07 12:12:23.514 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 627) ... ok Dec 07 12:12:27.592 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 761) ... ok Dec 07 12:12:56.004 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 189) ... test src/middleware/session/mod.rs - middleware::session::SessionData (line 189) has been running for over 60 seconds Dec 07 12:12:59.752 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 Dec 07 12:13:02.944 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) ... test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) has been running for over 60 seconds Dec 07 12:13:15.644 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 Dec 07 12:13:22.226 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 732) ... ok Dec 07 12:13:22.787 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::add_route_matcher (line 71) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::add_route_matcher (line 71) has been running for over 60 seconds Dec 07 12:13:23.512 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::delete (line 609) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::delete (line 609) has been running for over 60 seconds Dec 07 12:13:27.592 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get (line 434) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get (line 434) has been running for over 60 seconds Dec 07 12:13:58.980 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) ... ok Dec 07 12:14:12.896 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... ok Dec 07 12:14:13.116 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok Dec 07 12:14:13.956 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 189) ... ok Dec 07 12:14:22.228 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get_or_head (line 384) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get_or_head (line 384) has been running for over 60 seconds Dec 07 12:14:32.348 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get (line 434) ... ok Dec 07 12:14:38.855 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::add_route_matcher (line 71) ... ok Dec 07 12:14:39.460 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::delete (line 609) ... ok Dec 07 12:14:58.996 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::head (line 341) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::head (line 341) has been running for over 60 seconds Dec 07 12:15:12.896 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::options (line 652) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::options (line 652) has been running for over 60 seconds Dec 07 12:15:13.120 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::patch (line 565) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::patch (line 565) has been running for over 60 seconds Dec 07 12:15:13.952 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::post (line 477) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::post (line 477) has been running for over 60 seconds Dec 07 12:15:32.358 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::put (line 521) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::put (line 521) has been running for over 60 seconds Dec 07 12:15:32.812 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get_or_head (line 384) ... ok Dec 07 12:15:38.864 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::request (line 269) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::request (line 269) has been running for over 60 seconds Dec 07 12:15:39.460 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_path_extractor (line 139) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_path_extractor (line 139) has been running for over 60 seconds Dec 07 12:16:08.180 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::head (line 341) ... ok Dec 07 12:16:24.156 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::options (line 652) ... ok Dec 07 12:16:25.692 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::post (line 477) ... ok Dec 07 12:16:30.609 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::patch (line 565) ... ok Dec 07 12:16:32.828 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_query_string_extractor (line 203) ... test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_query_string_extractor (line 203) has been running for over 60 seconds Dec 07 12:16:38.865 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::request (line 269) ... ok Dec 07 12:16:39.168 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_path_extractor (line 139) ... ok Dec 07 12:16:42.088 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::put (line 521) ... ok Dec 07 12:17:08.188 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 786) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 786) has been running for over 60 seconds Dec 07 12:17:24.172 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 652) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 652) has been running for over 60 seconds Dec 07 12:17:25.692 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 704) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 704) has been running for over 60 seconds Dec 07 12:17:30.616 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 287) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 287) has been running for over 60 seconds Dec 07 12:17:31.245 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::with_query_string_extractor (line 203) ... ok Dec 07 12:17:38.876 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 99) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 99) has been running for over 60 seconds Dec 07 12:17:39.176 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 55) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 55) has been running for over 60 seconds Dec 07 12:17:42.088 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 136) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 136) has been running for over 60 seconds Dec 07 12:17:51.980 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 786) ... ok Dec 07 12:18:05.328 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 652) ... ok Dec 07 12:18:13.608 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 704) ... ok Dec 07 12:18:23.697 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 287) ... ok Dec 07 12:18:31.288 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 324) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 324) has been running for over 60 seconds Dec 07 12:18:34.048 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 99) ... ok Dec 07 12:18:35.989 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 55) ... ok Dec 07 12:18:38.908 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 136) ... ok Dec 07 12:18:51.992 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 249) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 249) has been running for over 60 seconds Dec 07 12:19:05.332 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 173) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 173) has been running for over 60 seconds Dec 07 12:19:13.628 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 211) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 211) has been running for over 60 seconds Dec 07 12:19:23.700 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 365) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 365) has been running for over 60 seconds Dec 07 12:19:29.086 ERRO error running command: command timed out after 900 seconds Dec 07 12:19:29.086 INFO running `"docker" "inspect" "ffd2c67762772c6873726b1a0d1a72472dd4b7f08dc9c4c4f0a4e6529432d953"` Dec 07 12:19:29.344 INFO running `"docker" "rm" "-f" "ffd2c67762772c6873726b1a0d1a72472dd4b7f08dc9c4c4f0a4e6529432d953"` Dec 07 12:19:31.024 INFO [stdout] ffd2c67762772c6873726b1a0d1a72472dd4b7f08dc9c4c4f0a4e6529432d953