Dec 15 09:48:56.225 INFO testing gotham-0.3.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 09:48:56.225 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 09:48:56.561 INFO [stdout] 7ed802abb411b38fd86206bf69c2c159a0f7d8b68e452638e108bdad2dc89749
Dec 15 09:48:56.563 INFO running `"docker" "start" "-a" "7ed802abb411b38fd86206bf69c2c159a0f7d8b68e452638e108bdad2dc89749"`
Dec 15 09:48:57.565 INFO [stderr] usermod: no changes
Dec 15 09:48:57.963 INFO [stderr]    Compiling hyper v0.12.18
Dec 15 09:49:10.923 INFO [stderr]    Compiling gotham v0.3.0 (/source)
Dec 15 09:49:27.504 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.91s
Dec 15 09:49:27.531 INFO [stderr] su: No module specific data is present
Dec 15 09:49:28.476 INFO running `"docker" "inspect" "7ed802abb411b38fd86206bf69c2c159a0f7d8b68e452638e108bdad2dc89749"`
Dec 15 09:49:28.793 INFO running `"docker" "rm" "-f" "7ed802abb411b38fd86206bf69c2c159a0f7d8b68e452638e108bdad2dc89749"`
Dec 15 09:49:29.088 INFO [stdout] 7ed802abb411b38fd86206bf69c2c159a0f7d8b68e452638e108bdad2dc89749
Dec 15 09:49:29.092 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 09:49:29.628 INFO [stdout] 21de2b32e6c2d85c4e6399d2645719e4b644debf2e62b87a3d37a005a6884a59
Dec 15 09:49:29.630 INFO running `"docker" "start" "-a" "21de2b32e6c2d85c4e6399d2645719e4b644debf2e62b87a3d37a005a6884a59"`
Dec 15 09:49:31.517 INFO [stderr] usermod: no changes
Dec 15 09:49:31.825 INFO [stderr]    Compiling gotham_derive v0.3.0
Dec 15 09:49:34.311 INFO [stderr]    Compiling gotham v0.3.0 (/source)
Dec 15 09:50:07.149 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.59s
Dec 15 09:50:07.198 INFO [stderr] su: No module specific data is present
Dec 15 09:50:07.625 INFO running `"docker" "inspect" "21de2b32e6c2d85c4e6399d2645719e4b644debf2e62b87a3d37a005a6884a59"`
Dec 15 09:50:07.784 INFO running `"docker" "rm" "-f" "21de2b32e6c2d85c4e6399d2645719e4b644debf2e62b87a3d37a005a6884a59"`
Dec 15 09:50:08.022 INFO [stdout] 21de2b32e6c2d85c4e6399d2645719e4b644debf2e62b87a3d37a005a6884a59
Dec 15 09:50:08.025 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 09:50:08.275 INFO [stdout] 9f9326d6a245691828371a7a72bd8a358627c0ff455a55419a7b30d156d9c7bd
Dec 15 09:50:08.281 INFO running `"docker" "start" "-a" "9f9326d6a245691828371a7a72bd8a358627c0ff455a55419a7b30d156d9c7bd"`
Dec 15 09:50:09.296 INFO [stderr] usermod: no changes
Dec 15 09:50:09.651 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.32s
Dec 15 09:50:09.667 INFO [stdout] 
Dec 15 09:50:09.667 INFO [stdout] running 80 tests
Dec 15 09:50:09.667 INFO [stderr]      Running /target/debug/deps/gotham-139a873e4a85c50a
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok
Dec 15 09:50:09.683 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok
Dec 15 09:50:09.684 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok
Dec 15 09:50:09.684 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok
Dec 15 09:50:09.684 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok
Dec 15 09:50:09.684 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok
Dec 15 09:50:09.685 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok
Dec 15 09:50:09.685 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok
Dec 15 09:50:09.685 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok
Dec 15 09:50:09.685 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single ... ok
Dec 15 09:50:09.685 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single_with_quality ... ok
Dec 15 09:50:09.685 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_values ... ok
Dec 15 09:50:09.699 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_headers ... ok
Dec 15 09:50:09.968 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_exists ... ok
Dec 15 09:50:09.974 INFO [stdout] test handler::assets::tests::assets_if_modified_since ... ok
Dec 15 09:50:10.005 INFO [stdout] test handler::assets::tests::assets_default_cache_control ... ok
Dec 15 09:50:10.036 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_accepted ... ok
Dec 15 09:50:10.056 INFO [stdout] test helpers::http::request::path::tests::request_path_segments_tests ... ok
Dec 15 09:50:10.060 INFO [stdout] test helpers::http::request::query_string::tests::query_string_mapping_tests ... ok
Dec 15 09:50:10.067 INFO [stdout] test helpers::http::tests::ensure_valid_percent_decode ... ok
Dec 15 09:50:10.069 INFO [stdout] test helpers::http::tests::ensure_valid_www_form_url_encoded_value ... ok
Dec 15 09:50:10.077 INFO [stdout] test handler::assets::tests::assets_if_none_match_etag ... ok
Dec 15 09:50:10.147 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok
Dec 15 09:50:10.167 INFO [stdout] test handler::assets::tests::assets_with_cache_control ... ok
Dec 15 09:50:10.167 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok
Dec 15 09:50:10.180 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok
Dec 15 09:50:10.185 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok
Dec 15 09:50:10.203 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok
Dec 15 09:50:10.206 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok
Dec 15 09:50:10.215 INFO [stdout] test middleware::session::tests::existing_session ... ok
Dec 15 09:50:10.223 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok
Dec 15 09:50:10.223 INFO [stdout] test handler::assets::tests::assets_single_file ... ok
Dec 15 09:50:10.235 INFO [stdout] test middleware::session::tests::new_session ... ok
Dec 15 09:50:10.235 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok
Dec 15 09:50:10.295 INFO [stdout] test router::builder::tests::build_router_test ... ok
Dec 15 09:50:10.300 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok
Dec 15 09:50:10.304 INFO [stdout] test handler::assets::tests::assets_weighted_accept_encoding ... ok
Dec 15 09:50:10.319 INFO [stdout] test router::non_match::tests::intersection_tests ... ok
Dec 15 09:50:10.320 INFO [stdout] test router::non_match::tests::union_tests ... ok
Dec 15 09:50:10.339 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok
Dec 15 09:50:10.341 INFO [stdout] test router::route::tests::external_route_tests ... ok
Dec 15 09:50:10.348 INFO [stdout] test router::route::tests::internal_route_tests ... ok
Dec 15 09:50:10.348 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok
Dec 15 09:50:10.361 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok
Dec 15 09:50:10.362 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok
Dec 15 09:50:10.368 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok
Dec 15 09:50:10.387 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok
Dec 15 09:50:10.399 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok
Dec 15 09:50:10.400 INFO [stdout] test handler::assets::tests::assets_compressed_if_accept_and_exists ... ok
Dec 15 09:50:10.401 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok
Dec 15 09:50:10.401 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok
Dec 15 09:50:10.404 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok
Dec 15 09:50:10.411 INFO [stdout] test service::tests::new_handler_closure ... ok
Dec 15 09:50:10.411 INFO [stdout] test router::tree::node::tests::manages_children ... ok
Dec 15 09:50:10.411 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok
Dec 15 09:50:10.456 INFO [stdout] test service::tests::router ... ok
Dec 15 09:50:10.463 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok
Dec 15 09:50:10.465 INFO [stdout] test router::tree::node::tests::traverses_children ... ok
Dec 15 09:50:10.472 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok
Dec 15 09:50:10.482 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok
Dec 15 09:50:10.504 INFO [stdout] test service::trap::tests::error ... ok
Dec 15 09:50:10.508 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok
Dec 15 09:50:10.522 INFO [stdout] test service::trap::tests::success ... ok
Dec 15 09:50:10.543 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok
Dec 15 09:50:10.551 INFO [stdout] test handler::assets::tests::assets_guesses_content_type ... ok
Dec 15 09:50:10.560 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok
Dec 15 09:50:10.560 INFO [stdout] test test::tests::sets_client_addr ... ignored
Dec 15 09:50:10.561 INFO [stdout] test test::tests::times_out ... ignored
Dec 15 09:50:10.608 INFO [stdout] test test::tests::serves_requests ... ok
Dec 15 09:50:10.639 INFO [stdout] test test::tests::async_echo ... ok
Dec 15 09:50:11.132 INFO [stdout] test handler::assets::tests::assets_path_traversal ... ok
Dec 15 09:50:11.179 INFO [stdout] test service::trap::tests::async_panic ... ok
Dec 15 09:50:11.179 INFO [stdout] test service::trap::tests::panic ... ok
Dec 15 09:50:11.179 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok
Dec 15 09:50:11.180 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok
Dec 15 09:50:11.180 INFO [stdout] 
Dec 15 09:50:11.180 INFO [stdout] test result: ok. 78 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 15 09:50:11.180 INFO [stdout] 
Dec 15 09:50:11.205 INFO [stderr]    Doc-tests gotham
Dec 15 09:50:22.376 INFO [stdout] 
Dec 15 09:50:22.376 INFO [stdout] running 101 tests
Dec 15 09:50:24.253 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 45) ... ok
Dec 15 09:51:07.287 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok
Dec 15 09:51:20.892 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 121) ... ok
Dec 15 09:51:20.929 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 71) ... ok
Dec 15 09:51:22.391 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 15 09:51:22.391 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 15 09:51:22.391 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 15 09:51:22.391 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 15 09:51:22.391 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 15 09:51:24.879 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 48) ... ok
Dec 15 09:51:39.050 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok
Dec 15 09:51:39.741 INFO [stdout] test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) ... ok
Dec 15 09:51:42.163 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok
Dec 15 09:51:48.987 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok
Dec 15 09:52:07.288 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 15 09:52:14.231 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 93) ... ok
Dec 15 09:52:20.895 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 15 09:52:20.935 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 15 09:52:24.887 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 15 09:52:39.047 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 15 09:52:39.741 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 15 09:52:42.159 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 15 09:52:46.387 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 177) ... ok
Dec 15 09:52:48.442 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 211) ... ok
Dec 15 09:52:48.987 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 15 09:52:58.835 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 295) ... ok
Dec 15 09:53:14.231 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 15 09:53:23.779 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) ... ok
Dec 15 09:53:24.351 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) ... ok
Dec 15 09:53:31.267 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) ... ok
Dec 15 09:53:37.143 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) ... ok
Dec 15 09:53:46.391 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 15 09:53:48.447 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 15 09:53:58.839 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 15 09:54:23.779 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 15 09:54:24.347 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 15 09:54:30.455 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 140) ... ok
Dec 15 09:54:31.275 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 15 09:54:37.147 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 15 09:54:54.747 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 264) ... ok
Dec 15 09:54:55.728 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 203) ... ok
Dec 15 09:55:00.655 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 30) ... ok
Dec 15 09:55:25.063 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok
Dec 15 09:55:26.919 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 336) ... ok
Dec 15 09:55:30.463 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 15 09:55:30.915 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 79) ... ok
Dec 15 09:55:33.380 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) ... ok
Dec 15 09:55:54.755 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 15 09:55:55.729 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::allow_cross_site_usage (line 696) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::allow_cross_site_usage (line 696) has been running for over 60 seconds
Dec 15 09:56:00.659 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::insecure (line 585) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::insecure (line 585) has been running for over 60 seconds
Dec 15 09:56:20.055 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) ... ok
Dec 15 09:56:24.523 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::allow_cross_site_usage (line 696) ... ok
Dec 15 09:56:25.067 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_domain (line 658) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_domain (line 658) has been running for over 60 seconds
Dec 15 09:56:26.915 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_name (line 627) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_name (line 627) has been running for over 60 seconds
Dec 15 09:56:30.915 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_path (line 553) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_path (line 553) has been running for over 60 seconds
Dec 15 09:56:33.383 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_session_type (line 761) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_session_type (line 761) has been running for over 60 seconds
Dec 15 09:56:41.159 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) ... ok
Dec 15 09:56:55.887 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::insecure (line 585) ... ok
Dec 15 09:57:04.479 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_name (line 627) ... ok
Dec 15 09:57:20.055 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_strict_same_site_enforcement (line 732) ... test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_strict_same_site_enforcement (line 732) has been running for over 60 seconds
Dec 15 09:57:20.939 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_domain (line 658) ... ok
Dec 15 09:57:23.507 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_path (line 553) ... ok
Dec 15 09:57:24.519 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 15 09:57:38.952 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_session_type (line 761) ... ok
Dec 15 09:57:41.155 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 15 09:57:55.891 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 15 09:58:04.483 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 15 09:58:20.947 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 15 09:58:23.515 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 15 09:58:31.739 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_strict_same_site_enforcement (line 732) ... ok
Dec 15 09:58:38.959 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 15 09:58:51.479 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 189) ... ok
Dec 15 09:58:55.083 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok
Dec 15 09:59:02.939 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) ... ok
Dec 15 09:59:03.627 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... ok
Dec 15 09:59:31.739 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 15 09:59:33.591 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 15 09:59:41.395 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::delete (line 609) ... ok
Dec 15 09:59:49.375 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::get (line 434) ... ok
Dec 15 09:59:51.475 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 15 09:59:55.099 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 15 10:00:02.947 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 15 10:00:03.623 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 15 10:00:33.291 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 15 10:00:33.595 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 15 10:00:41.407 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 15 10:00:49.371 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 15 10:00:50.523 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::options (line 652) ... ok
Dec 15 10:01:03.487 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::head (line 341) ... ok
Dec 15 10:01:17.051 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::patch (line 565) ... ok
Dec 15 10:01:18.771 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::post (line 477) ... ok
Dec 15 10:01:33.295 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 15 10:01:35.529 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::put (line 521) ... ok
Dec 15 10:01:50.523 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 15 10:01:50.561 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::request (line 269) ... ok
Dec 15 10:01:51.529 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 15 10:02:03.491 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 15 10:02:17.055 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 15 10:02:18.764 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 15 10:02:35.533 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 15 10:02:35.787 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 15 10:02:50.571 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 15 10:02:51.535 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 15 10:02:53.881 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 786) ... ok
Dec 15 10:02:58.915 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 652) ... ok
Dec 15 10:03:07.494 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 704) ... ok
Dec 15 10:03:11.927 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 287) ... ok
Dec 15 10:03:35.787 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 15 10:03:37.943 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 99) ... ok
Dec 15 10:03:42.059 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 136) ... ok
Dec 15 10:03:53.871 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 15 10:03:53.895 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 55) ... ok
Dec 15 10:03:58.919 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 15 10:04:07.503 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 15 10:04:11.919 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 15 10:04:26.219 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::options (line 324) ... ok
Dec 15 10:04:37.983 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 403) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::request (line 403) has been running for over 60 seconds
Dec 15 10:04:42.067 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 480) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::scope (line 480) has been running for over 60 seconds
Dec 15 10:04:53.939 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 535) ... test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::with_pipeline_chain (line 535) has been running for over 60 seconds
Dec 15 10:04:54.382 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::post (line 173) ... ok
Dec 15 10:04:54.643 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::patch (line 249) ... ok
Dec 15 10:05:05.958 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::put (line 211) ... ok
Dec 15 10:05:08.284 ERRO error running command: command timed out after 900 seconds
Dec 15 10:05:08.284 INFO running `"docker" "inspect" "9f9326d6a245691828371a7a72bd8a358627c0ff455a55419a7b30d156d9c7bd"`
Dec 15 10:05:08.679 INFO running `"docker" "rm" "-f" "9f9326d6a245691828371a7a72bd8a358627c0ff455a55419a7b30d156d9c7bd"`
Dec 15 10:05:10.508 INFO [stdout] 9f9326d6a245691828371a7a72bd8a358627c0ff455a55419a7b30d156d9c7bd