Dec 07 12:02:53.478 INFO testing gotham-0.3.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 12:02:53.478 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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.827 INFO [stdout] 3792996997b7f17cb02ec845258bbb6d33200ba956c5b2d4e3726aab8128b84b
Dec 07 12:02:53.829 INFO running `"docker" "start" "-a" "3792996997b7f17cb02ec845258bbb6d33200ba956c5b2d4e3726aab8128b84b"`
Dec 07 12:02:54.356 INFO [stderr] usermod: no changes
Dec 07 12:02:54.714 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 07 12:02:54.716 INFO [stderr]    Compiling borrow-bag v1.0.0
Dec 07 12:02:58.010 INFO [stderr]    Compiling tokio-reactor v0.1.7
Dec 07 12:03:02.666 INFO [stderr]    Compiling tokio-tcp v0.1.2
Dec 07 12:03:02.681 INFO [stderr]    Compiling tokio-uds v0.2.4
Dec 07 12:03:02.682 INFO [stderr]    Compiling tokio-udp v0.1.3
Dec 07 12:03:09.654 INFO [stderr]    Compiling tokio v0.1.13
Dec 07 12:03:14.677 INFO [stderr]    Compiling hyper v0.12.17
Dec 07 12:03:25.564 INFO [stderr]    Compiling gotham v0.3.0 (/source)
Dec 07 12:03:41.172 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 46.79s
Dec 07 12:03:41.186 INFO [stderr] su: No module specific data is present
Dec 07 12:03:42.019 INFO running `"docker" "inspect" "3792996997b7f17cb02ec845258bbb6d33200ba956c5b2d4e3726aab8128b84b"`
Dec 07 12:03:42.316 INFO running `"docker" "rm" "-f" "3792996997b7f17cb02ec845258bbb6d33200ba956c5b2d4e3726aab8128b84b"`
Dec 07 12:03:42.616 INFO [stdout] 3792996997b7f17cb02ec845258bbb6d33200ba956c5b2d4e3726aab8128b84b
Dec 07 12:03:42.618 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:43.049 INFO [stdout] f417229ec47f2093ba959ce1a4f073dabdbefb8b56d03244734ed279eb3b394e
Dec 07 12:03:43.051 INFO running `"docker" "start" "-a" "f417229ec47f2093ba959ce1a4f073dabdbefb8b56d03244734ed279eb3b394e"`
Dec 07 12:03:45.142 INFO [stderr] usermod: no changes
Dec 07 12:03:45.522 INFO [stderr]    Compiling gotham_derive v0.3.0
Dec 07 12:03:47.963 INFO [stderr]    Compiling gotham v0.3.0 (/source)
Dec 07 12:04:16.020 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 30.85s
Dec 07 12:04:16.039 INFO [stderr] su: No module specific data is present
Dec 07 12:04:16.694 INFO running `"docker" "inspect" "f417229ec47f2093ba959ce1a4f073dabdbefb8b56d03244734ed279eb3b394e"`
Dec 07 12:04:17.006 INFO running `"docker" "rm" "-f" "f417229ec47f2093ba959ce1a4f073dabdbefb8b56d03244734ed279eb3b394e"`
Dec 07 12:04:17.237 INFO [stdout] f417229ec47f2093ba959ce1a4f073dabdbefb8b56d03244734ed279eb3b394e
Dec 07 12:04:17.242 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:17.632 INFO [stdout] 0bbf667a7069113dcdc6aa87c8a5e294bf3e8275a47d40a8a63cbd88a953c7d6
Dec 07 12:04:17.638 INFO running `"docker" "start" "-a" "0bbf667a7069113dcdc6aa87c8a5e294bf3e8275a47d40a8a63cbd88a953c7d6"`
Dec 07 12:04:18.644 INFO [stderr] usermod: no changes
Dec 07 12:04:18.862 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 07 12:04:18.880 INFO [stderr]      Running /target/debug/deps/gotham-29a4a66605146258
Dec 07 12:04:18.884 INFO [stdout] 
Dec 07 12:04:18.884 INFO [stdout] running 80 tests
Dec 07 12:04:18.892 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok
Dec 07 12:04:18.892 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok
Dec 07 12:04:18.892 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok
Dec 07 12:04:18.892 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok
Dec 07 12:04:18.900 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_values ... ok
Dec 07 12:04:18.900 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_headers ... ok
Dec 07 12:04:18.900 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single_with_quality ... ok
Dec 07 12:04:18.904 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok
Dec 07 12:04:18.904 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok
Dec 07 12:04:18.920 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single ... ok
Dec 07 12:04:19.082 INFO [stdout] test handler::assets::tests::assets_if_none_match_etag ... ok
Dec 07 12:04:19.148 INFO [stdout] test handler::assets::tests::assets_if_modified_since ... ok
Dec 07 12:04:19.178 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_exists ... ok
Dec 07 12:04:19.187 INFO [stdout] test handler::assets::tests::assets_default_cache_control ... ok
Dec 07 12:04:19.192 INFO [stdout] test helpers::http::request::path::tests::request_path_segments_tests ... ok
Dec 07 12:04:19.216 INFO [stdout] test helpers::http::request::query_string::tests::query_string_mapping_tests ... ok
Dec 07 12:04:19.266 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_accepted ... ok
Dec 07 12:04:19.266 INFO [stdout] test helpers::http::tests::ensure_valid_www_form_url_encoded_value ... ok
Dec 07 12:04:19.273 INFO [stdout] test helpers::http::tests::ensure_valid_percent_decode ... ok
Dec 07 12:04:19.273 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok
Dec 07 12:04:19.278 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok
Dec 07 12:04:19.325 INFO [stdout] test handler::assets::tests::assets_with_cache_control ... ok
Dec 07 12:04:19.325 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok
Dec 07 12:04:19.329 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok
Dec 07 12:04:19.336 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok
Dec 07 12:04:19.349 INFO [stdout] test middleware::session::tests::new_session ... ok
Dec 07 12:04:19.367 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok
Dec 07 12:04:19.368 INFO [stdout] test middleware::session::tests::existing_session ... ok
Dec 07 12:04:19.368 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok
Dec 07 12:04:19.388 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok
Dec 07 12:04:19.405 INFO [stdout] test handler::assets::tests::assets_single_file ... ok
Dec 07 12:04:19.413 INFO [stdout] test handler::assets::tests::assets_weighted_accept_encoding ... ok
Dec 07 12:04:19.413 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok
Dec 07 12:04:19.420 INFO [stdout] test router::non_match::tests::intersection_tests ... ok
Dec 07 12:04:19.421 INFO [stdout] test router::non_match::tests::union_tests ... ok
Dec 07 12:04:19.424 INFO [stdout] test handler::assets::tests::assets_compressed_if_accept_and_exists ... ok
Dec 07 12:04:19.455 INFO [stdout] test router::builder::tests::build_router_test ... ok
Dec 07 12:04:19.457 INFO [stdout] test router::route::tests::external_route_tests ... ok
Dec 07 12:04:19.470 INFO [stdout] test router::route::tests::internal_route_tests ... ok
Dec 07 12:04:19.471 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok
Dec 07 12:04:19.472 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok
Dec 07 12:04:19.473 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok
Dec 07 12:04:19.474 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok
Dec 07 12:04:19.474 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok
Dec 07 12:04:19.475 INFO [stdout] test router::tree::node::tests::manages_children ... ok
Dec 07 12:04:19.476 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok
Dec 07 12:04:19.480 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok
Dec 07 12:04:19.482 INFO [stdout] test router::tree::node::tests::traverses_children ... ok
Dec 07 12:04:19.483 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok
Dec 07 12:04:19.488 INFO [stdout] test service::tests::new_handler_closure ... ok
Dec 07 12:04:19.501 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok
Dec 07 12:04:19.539 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok
Dec 07 12:04:19.560 INFO [stdout] test service::tests::router ... ok
Dec 07 12:04:19.562 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok
Dec 07 12:04:19.580 INFO [stdout] test service::trap::tests::error ... ok
Dec 07 12:04:19.596 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok
Dec 07 12:04:19.607 INFO [stdout] test service::trap::tests::success ... ok
Dec 07 12:04:19.615 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok
Dec 07 12:04:19.620 INFO [stdout] test handler::assets::tests::assets_guesses_content_type ... ok
Dec 07 12:04:19.620 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok
Dec 07 12:04:19.628 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok
Dec 07 12:04:19.640 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok
Dec 07 12:04:19.664 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok
Dec 07 12:04:19.664 INFO [stdout] test test::tests::sets_client_addr ... ignored
Dec 07 12:04:19.664 INFO [stdout] test test::tests::times_out ... ignored
Dec 07 12:04:19.750 INFO [stdout] test test::tests::async_echo ... ok
Dec 07 12:04:19.750 INFO [stdout] test test::tests::serves_requests ... ok
Dec 07 12:04:20.020 INFO [stdout] test handler::assets::tests::assets_path_traversal ... ok
Dec 07 12:04:20.138 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok
Dec 07 12:04:20.140 INFO [stdout] test service::trap::tests::panic ... ok
Dec 07 12:04:20.152 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok
Dec 07 12:04:20.152 INFO [stdout] test service::trap::tests::async_panic ... ok
Dec 07 12:04:20.152 INFO [stdout] 
Dec 07 12:04:20.152 INFO [stdout] test result: ok. 78 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 07 12:04:20.152 INFO [stdout] 
Dec 07 12:04:20.156 INFO [stderr]    Doc-tests gotham
Dec 07 12:04:31.713 INFO [stdout] 
Dec 07 12:04:31.713 INFO [stdout] running 101 tests
Dec 07 12:04:36.210 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 45) ... ok
Dec 07 12:05:31.716 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:31.716 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:31.716 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:31.716 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:31.716 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:31.716 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:31.732 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:36.232 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:47.004 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 121) ... ok
Dec 07 12:05:50.436 INFO [stdout] test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) ... ok
Dec 07 12:05:52.448 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok
Dec 07 12:05:52.480 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok
Dec 07 12:05:55.577 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 48) ... ok
Dec 07 12:06:00.780 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 71) ... ok
Dec 07 12:06:07.272 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok
Dec 07 12:06:17.368 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok
Dec 07 12:06:47.004 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:50.432 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:06:52.448 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:06:52.480 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:06:55.586 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:00.788 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:07.272 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:17.372 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:29.584 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 211) ... ok
Dec 07 12:07:29.920 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 93) ... ok
Dec 07 12:07:30.717 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 177) ... ok
Dec 07 12:07:59.308 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) ... ok
Dec 07 12:08:00.244 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 295) ... ok
Dec 07 12:08:15.420 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) ... ok
Dec 07 12:08:22.361 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) ... ok
Dec 07 12:08:28.090 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) ... ok
Dec 07 12:08:29.580 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:29.916 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:30.724 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:59.324 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:00.248 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:15.428 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:19.476 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 264) ... ok
Dec 07 12:09:22.364 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:28.090 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:30.528 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 140) ... ok
Dec 07 12:09:33.696 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 203) ... ok
Dec 07 12:09:56.232 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 79) ... ok
Dec 07 12:09:58.444 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 336) ... ok
Dec 07 12:10:03.916 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 30) ... ok
Dec 07 12:10:06.413 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok
Dec 07 12:10:15.441 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) ... ok
Dec 07 12:10:19.480 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:30.532 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:33.696 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 07 12:10:56.236 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 07 12:10:58.444 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 07 12:11:03.920 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 07 12:11:06.420 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 07 12:11:15.444 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 07 12:11:31.480 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) ... ok
Dec 07 12:11:34.317 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::allow_cross_site_usage (line 696) ... ok
Dec 07 12:11:45.184 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) ... ok
Dec 07 12:12:04.212 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::insecure (line 585) ... ok
Dec 07 12:12:06.764 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_domain (line 658) ... ok
Dec 07 12:12:09.569 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_path (line 553) ... ok
Dec 07 12:12:13.324 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_cookie_name (line 627) ... ok
Dec 07 12:12:23.496 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_session_type (line 761) ... ok
Dec 07 12:12:31.480 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 07 12:12:34.328 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:45.192 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:04.216 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:06.776 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:09.576 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:13.340 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:23.500 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:26.384 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware<B, T>::with_strict_same_site_enforcement (line 732) ... ok
Dec 07 12:13:32.977 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::SessionData (line 189) ... ok
Dec 07 12:13:48.834 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok
Dec 07 12:14:02.236 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) ... ok
Dec 07 12:14:08.600 INFO [stdout] test src/pipeline/single.rs - pipeline::single::single_pipeline (line 26) ... ok
Dec 07 12:14:18.610 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:19.448 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:26.380 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.992 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:14:33.674 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:48.841 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:02.240 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:08.604 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:18.612 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:19.446 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:33.692 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:15:40.624 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:47.576 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:01.504 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:13.600 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:13.924 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:20.132 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:16:24.337 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.320 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:40.628 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:47.580 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:01.504 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:13.604 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:13.928 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:17.620 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:20.144 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:24.352 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:30.229 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::associate (line 786) ... ok
Dec 07 12:17:39.324 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:41.365 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate (line 652) ... ok
Dec 07 12:17:57.436 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delete (line 287) ... ok
Dec 07 12:18:11.836 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::delegate_without_pipelines (line 704) ... ok
Dec 07 12:18:13.472 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get (line 99) ... ok
Dec 07 12:18:17.300 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::get_or_head (line 55) ... ok
Dec 07 12:18:17.620 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:30.248 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:18:41.367 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:18:41.816 INFO [stdout] test src/router/builder/draw.rs - router::builder::draw::DrawRoutes::head (line 136) ... ok
Dec 07 12:18:57.440 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:11.864 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:13.508 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 07 12:19:17.300 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 07 12:19:17.644 ERRO error running command: command timed out after 900 seconds
Dec 07 12:19:17.644 INFO running `"docker" "inspect" "0bbf667a7069113dcdc6aa87c8a5e294bf3e8275a47d40a8a63cbd88a953c7d6"`
Dec 07 12:19:18.044 INFO running `"docker" "rm" "-f" "0bbf667a7069113dcdc6aa87c8a5e294bf3e8275a47d40a8a63cbd88a953c7d6"`
Dec 07 12:19:19.977 INFO [stdout] 0bbf667a7069113dcdc6aa87c8a5e294bf3e8275a47d40a8a63cbd88a953c7d6