Dec 15 09:48:57.971 INFO testing gotham-0.3.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 09:48:57.971 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:58.320 INFO [stdout] bbbacb96c5e79378f8817044a2f2bb95e44498174199c76733775addd9cf8190 Dec 15 09:48:58.322 INFO running `"docker" "start" "-a" "bbbacb96c5e79378f8817044a2f2bb95e44498174199c76733775addd9cf8190"` Dec 15 09:48:59.257 INFO [stderr] usermod: no changes Dec 15 09:48:59.700 INFO [stderr] Compiling failure_derive v0.1.3 Dec 15 09:48:59.700 INFO [stderr] Compiling regex v1.1.0 Dec 15 09:48:59.715 INFO [stderr] Compiling memchr v2.1.2 Dec 15 09:48:59.730 INFO [stderr] Compiling syn v0.15.22 Dec 15 09:48:59.735 INFO [stderr] Compiling cookie v0.11.0 Dec 15 09:48:59.736 INFO [stderr] Compiling tokio-threadpool v0.1.9 Dec 15 09:48:59.737 INFO [stderr] Compiling tokio-reactor v0.1.7 Dec 15 09:49:09.391 INFO [stderr] Compiling aho-corasick v0.6.9 Dec 15 09:49:12.893 INFO [stderr] Compiling tokio-uds v0.2.4 Dec 15 09:49:12.894 INFO [stderr] Compiling tokio-tcp v0.1.2 Dec 15 09:49:12.894 INFO [stderr] Compiling tokio-udp v0.1.3 Dec 15 09:49:16.993 INFO [stderr] Compiling tokio-fs v0.1.4 Dec 15 09:49:26.975 INFO [stderr] Compiling tokio v0.1.13 Dec 15 09:49:29.987 INFO [stderr] Compiling serde_derive v1.0.82 Dec 15 09:49:29.987 INFO [stderr] Compiling synstructure v0.10.1 Dec 15 09:49:40.296 INFO [stderr] Compiling hyper v0.12.18 Dec 15 09:49:43.587 INFO [stderr] Compiling failure v0.1.3 Dec 15 09:49:52.795 INFO [stderr] Compiling cargo_metadata v0.6.3 Dec 15 09:49:59.746 INFO [stderr] Compiling skeptic v0.13.4 Dec 15 09:50:05.742 INFO [stderr] Compiling borrow-bag v1.0.0 Dec 15 09:50:09.763 INFO [stderr] Compiling gotham v0.3.0 (/source) Dec 15 09:50:22.579 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 23s Dec 15 09:50:22.595 INFO [stderr] su: No module specific data is present Dec 15 09:50:23.312 INFO running `"docker" "inspect" "bbbacb96c5e79378f8817044a2f2bb95e44498174199c76733775addd9cf8190"` Dec 15 09:50:23.627 INFO running `"docker" "rm" "-f" "bbbacb96c5e79378f8817044a2f2bb95e44498174199c76733775addd9cf8190"` Dec 15 09:50:23.999 INFO [stdout] bbbacb96c5e79378f8817044a2f2bb95e44498174199c76733775addd9cf8190 Dec 15 09:50:24.003 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:50:24.363 INFO [stdout] e1bb44817ef78d3842a0f0188f733c6a0e0df182eaf867081fecc4570e6eb194 Dec 15 09:50:24.364 INFO running `"docker" "start" "-a" "e1bb44817ef78d3842a0f0188f733c6a0e0df182eaf867081fecc4570e6eb194"` Dec 15 09:50:25.563 INFO [stderr] usermod: no changes Dec 15 09:50:25.819 INFO [stderr] Compiling gotham_derive v0.3.0 Dec 15 09:50:28.099 INFO [stderr] Compiling gotham v0.3.0 (/source) Dec 15 09:51:01.794 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 36.21s Dec 15 09:51:01.835 INFO [stderr] su: No module specific data is present Dec 15 09:51:03.041 INFO running `"docker" "inspect" "e1bb44817ef78d3842a0f0188f733c6a0e0df182eaf867081fecc4570e6eb194"` Dec 15 09:51:03.308 INFO running `"docker" "rm" "-f" "e1bb44817ef78d3842a0f0188f733c6a0e0df182eaf867081fecc4570e6eb194"` Dec 15 09:51:03.637 INFO [stdout] e1bb44817ef78d3842a0f0188f733c6a0e0df182eaf867081fecc4570e6eb194 Dec 15 09:51:03.647 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:51:04.124 INFO [stdout] 8edaa1f2e214da3cda4e7582204501f0e58cebac51012a40ea307883a3294118 Dec 15 09:51:04.127 INFO running `"docker" "start" "-a" "8edaa1f2e214da3cda4e7582204501f0e58cebac51012a40ea307883a3294118"` Dec 15 09:51:06.215 INFO [stderr] usermod: no changes Dec 15 09:51:06.554 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.30s Dec 15 09:51:06.562 INFO [stderr] Running /target/debug/deps/gotham-09de52d9a8e1f81f Dec 15 09:51:06.570 INFO [stdout] Dec 15 09:51:06.570 INFO [stdout] running 80 tests Dec 15 09:51:06.635 INFO [stdout] test extractor::internal::tests::borrowed_bytes_query_tests ... ok Dec 15 09:51:06.635 INFO [stdout] test extractor::internal::tests::borrowed_str_path_tests ... ok Dec 15 09:51:06.635 INFO [stdout] test extractor::internal::tests::borrowed_bytes_path_tests ... ok Dec 15 09:51:06.655 INFO [stdout] test extractor::internal::tests::borrowed_str_query_tests ... ok Dec 15 09:51:06.656 INFO [stdout] test extractor::internal::tests::byte_buf_values_query_tests ... ok Dec 15 09:51:06.656 INFO [stdout] test extractor::internal::tests::enum_path_tests ... ok Dec 15 09:51:06.675 INFO [stdout] test extractor::internal::tests::byte_buf_values_path_tests ... ok Dec 15 09:51:06.676 INFO [stdout] test extractor::internal::tests::newtype_struct_query_tests ... ok Dec 15 09:51:06.676 INFO [stdout] test extractor::internal::tests::newtype_struct_path_tests ... ok Dec 15 09:51:06.677 INFO [stdout] test extractor::internal::tests::enum_query_tests ... ok Dec 15 09:51:06.694 INFO [stdout] test extractor::internal::tests::seq_query_tests ... ok Dec 15 09:51:06.694 INFO [stdout] test extractor::internal::tests::seq_path_tests ... ok Dec 15 09:51:06.694 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_values ... ok Dec 15 09:51:06.694 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_multiple_headers ... ok Dec 15 09:51:06.703 INFO [stdout] test extractor::internal::tests::simple_values_path_tests ... ok Dec 15 09:51:06.704 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single ... ok Dec 15 09:51:06.704 INFO [stdout] test extractor::internal::tests::simple_values_query_tests ... ok Dec 15 09:51:06.705 INFO [stdout] test handler::assets::accepted_encoding::tests::accepted_encoding_single_with_quality ... ok Dec 15 09:51:07.039 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_exists ... ok Dec 15 09:51:07.133 INFO [stdout] test handler::assets::tests::assets_default_cache_control ... ok Dec 15 09:51:07.160 INFO [stdout] test handler::assets::tests::assets_if_modified_since ... ok Dec 15 09:51:07.175 INFO [stdout] test handler::assets::tests::assets_no_compression_if_not_accepted ... ok Dec 15 09:51:07.189 INFO [stdout] test helpers::http::request::path::tests::request_path_segments_tests ... ok Dec 15 09:51:07.216 INFO [stdout] test helpers::http::request::query_string::tests::query_string_mapping_tests ... ok Dec 15 09:51:07.223 INFO [stdout] test helpers::http::tests::ensure_valid_percent_decode ... ok Dec 15 09:51:07.227 INFO [stdout] test helpers::http::tests::ensure_valid_www_form_url_encoded_value ... ok Dec 15 09:51:07.235 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_join_test ... ok Dec 15 09:51:07.243 INFO [stdout] test middleware::session::backend::memory::tests::cleanup_test ... ok Dec 15 09:51:07.251 INFO [stdout] test handler::assets::tests::assets_if_none_match_etag ... ok Dec 15 09:51:07.271 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_refresh_test ... ok Dec 15 09:51:07.289 INFO [stdout] test middleware::session::tests::enforce_host_cookie_prefix_attributes ... ok Dec 15 09:51:07.298 INFO [stdout] test middleware::session::backend::memory::tests::memory_backend_test ... ok Dec 15 09:51:07.300 INFO [stdout] test handler::assets::tests::assets_single_file ... ok Dec 15 09:51:07.307 INFO [stdout] test middleware::session::tests::new_session ... ok Dec 15 09:51:07.309 INFO [stdout] test handler::assets::tests::assets_compressed_if_accept_and_exists ... ok Dec 15 09:51:07.311 INFO [stdout] test middleware::session::tests::new_session_custom_settings ... ok Dec 15 09:51:07.319 INFO [stdout] test pipeline::single::tests::test_pipeline_construction ... ok Dec 15 09:51:07.320 INFO [stdout] test middleware::session::tests::existing_session ... ok Dec 15 09:51:07.331 INFO [stdout] test middleware::session::tests::enforce_secure_cookie_prefix_attributes ... ok Dec 15 09:51:07.409 INFO [stdout] test router::builder::tests::build_router_test ... ok Dec 15 09:51:07.433 INFO [stdout] test router::non_match::tests::deconstruct_tests ... ok Dec 15 09:51:07.462 INFO [stdout] test handler::assets::tests::assets_weighted_accept_encoding ... ok Dec 15 09:51:07.462 INFO [stdout] test handler::assets::tests::assets_with_cache_control ... ok Dec 15 09:51:07.462 INFO [stdout] test router::non_match::tests::intersection_tests ... ok Dec 15 09:51:07.462 INFO [stdout] test router::builder::draw::tests::delegate_includes_pipelines ... ok Dec 15 09:51:07.462 INFO [stdout] test router::builder::draw::tests::delegate_without_pipelines_skips_pipelines ... ok Dec 15 09:51:07.464 INFO [stdout] test router::tests::custom_error_if_leaf_found_but_matching_route_not_found ... ok Dec 15 09:51:07.464 INFO [stdout] test router::route::tests::external_route_tests ... ok Dec 15 09:51:07.475 INFO [stdout] test router::route::tests::internal_route_tests ... ok Dec 15 09:51:07.475 INFO [stdout] test router::tests::delegates_to_secondary_router ... ok Dec 15 09:51:07.491 INFO [stdout] test router::tests::executes_response_finalizer_when_present ... ok Dec 15 09:51:07.503 INFO [stdout] test pipeline::tests::pipeline_ordering_test ... ok Dec 15 09:51:07.527 INFO [stdout] test router::non_match::tests::union_tests ... ok Dec 15 09:51:07.551 INFO [stdout] test router::tree::node::tests::node_traversal_tests ... ok Dec 15 09:51:07.565 INFO [stdout] test router::tree::node::tests::non_matching_routes_allow_list_tests ... ok Dec 15 09:51:07.567 INFO [stdout] test router::tests::success_if_leaf_and_route_found ... ok Dec 15 09:51:07.579 INFO [stdout] test router::tree::tests::tree_traversal_tests ... ok Dec 15 09:51:07.593 INFO [stdout] test router::tests::not_found_error_if_request_path_is_not_found ... ok Dec 15 09:51:07.593 INFO [stdout] test router::tests::internal_server_error_if_no_request_path_segments ... ok Dec 15 09:51:07.599 INFO [stdout] test service::tests::new_handler_closure ... ok Dec 15 09:51:07.612 INFO [stdout] test router::tree::node::tests::manages_children ... ok Dec 15 09:51:07.638 INFO [stdout] test router::tree::node::tests::traverses_children ... ok Dec 15 09:51:07.641 INFO [stdout] test service::tests::router ... ok Dec 15 09:51:07.649 INFO [stdout] test service::trap::tests::async_success_repeat_poll ... ok Dec 15 09:51:07.655 INFO [stdout] test handler::assets::tests::assets_guesses_content_type ... ok Dec 15 09:51:07.656 INFO [stdout] test state::request_id::tests::does_not_overwrite_existant_request_id ... ok Dec 15 09:51:07.675 INFO [stdout] test service::trap::tests::error ... ok Dec 15 09:51:07.677 INFO [stdout] test service::trap::tests::success ... ok Dec 15 09:51:07.677 INFO [stdout] test state::request_id::tests::uses_an_external_request_id ... ok Dec 15 09:51:07.684 INFO [stdout] test state::request_id::tests::sets_a_unique_request_id ... ok Dec 15 09:51:07.815 INFO [stdout] test router::route::dispatch::tests::pipeline_chain_ordering_test ... ok Dec 15 09:51:07.815 INFO [stdout] test test::tests::sets_client_addr ... ignored Dec 15 09:51:07.815 INFO [stdout] test test::tests::times_out ... ignored Dec 15 09:51:07.815 INFO [stdout] test test::tests::async_echo ... ok Dec 15 09:51:07.823 INFO [stdout] test test::tests::serves_requests ... ok Dec 15 09:51:08.247 INFO [stdout] test handler::assets::tests::assets_path_traversal ... ok Dec 15 09:51:08.283 INFO [stdout] test service::trap::tests::async_panic_repeat_poll ... ok Dec 15 09:51:08.283 INFO [stdout] test service::trap::tests::panic ... ok Dec 15 09:51:08.287 INFO [stdout] test state::request_id::tests::panics_before_request_id_set ... ok Dec 15 09:51:08.287 INFO [stdout] test service::trap::tests::async_panic ... ok Dec 15 09:51:08.287 INFO [stdout] Dec 15 09:51:08.287 INFO [stdout] test result: ok. 78 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out Dec 15 09:51:08.287 INFO [stdout] Dec 15 09:51:08.319 INFO [stderr] Doc-tests gotham Dec 15 09:51:21.519 INFO [stdout] Dec 15 09:51:21.519 INFO [stdout] running 101 tests Dec 15 09:51:28.475 INFO [stdout] test src/extractor/internal.rs - extractor::internal::ExtractorError::UnexpectedEnumVariantType (line 45) ... ok Dec 15 09:52:21.527 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:52:21.527 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:52:21.527 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:52:21.535 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 15 09:52:21.535 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:52:21.535 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 15 09:52:21.535 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:52:28.151 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 48) ... ok Dec 15 09:52:28.479 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 15 09:52:43.609 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 121) ... ok Dec 15 09:52:54.250 INFO [stdout] test src/handler/assets/mod.rs - handler::assets::FileOptions (line 56) ... ok Dec 15 09:52:56.039 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 71) ... ok Dec 15 09:52:56.415 INFO [stdout] test src/handler/error.rs - handler::error::HandlerError::with_status (line 92) ... ok Dec 15 09:52:57.184 INFO [stdout] test src/handler/error.rs - handler::error::IntoHandlerError (line 22) ... ok Dec 15 09:53:05.891 INFO [stdout] test src/extractor/path.rs - extractor::path::PathExtractor (line 21) ... ok Dec 15 09:53:12.427 INFO [stdout] test src/extractor/query_string.rs - extractor::query_string::QueryStringExtractor (line 21) ... ok Dec 15 09:53:28.163 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:53:43.611 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:53:54.275 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:53:56.043 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:53:56.419 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:53:57.191 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:53:58.675 INFO [stdout] test src/handler/mod.rs - handler::Handler (line 93) ... ok Dec 15 09:54:05.891 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:54:12.431 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:54:31.331 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 211) ... ok Dec 15 09:54:38.388 INFO [stdout] test src/handler/mod.rs - handler::NewHandler (line 177) ... ok Dec 15 09:54:47.663 INFO [stdout] test src/handler/mod.rs - handler::IntoResponse (line 295) ... ok Dec 15 09:54:58.687 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:55:06.279 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_permanent_redirect (line 133) ... ok Dec 15 09:55:07.911 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_empty_response (line 89) ... ok Dec 15 09:55:09.271 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_response (line 19) ... ok Dec 15 09:55:14.119 INFO [stdout] test src/helpers/http/response/mod.rs - helpers::http::response::create_temporary_redirect (line 177) ... ok Dec 15 09:55:31.339 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:55:38.391 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:55:45.667 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 140) ... ok Dec 15 09:55:47.699 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:56:06.275 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:56:07.923 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:56:09.283 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:56:14.119 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:56:43.380 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 203) ... ok Dec 15 09:56:45.683 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:56:51.035 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 30) ... ok Dec 15 09:56:54.321 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 264) ... ok Dec 15 09:57:03.915 INFO [stdout] test src/middleware/mod.rs - middleware::NewMiddleware (line 336) ... ok Dec 15 09:57:08.067 INFO [stdout] test src/middleware/session/backend/memory.rs - middleware::session::backend::memory::MemoryBackend::new (line 41) ... ok Dec 15 09:57:09.627 INFO [stdout] test src/middleware/mod.rs - middleware::Middleware (line 79) ... ok Dec 15 09:57:10.583 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 414) ... ok Dec 15 09:57:43.379 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:57:51.039 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 15 09:57:54.323 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 15 09:57:58.479 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 425) ... ok Dec 15 09:58:03.922 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 15 09:58:08.073 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 15 09:58:09.639 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 15 09:58:10.591 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 15 09:58:49.040 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware (line 436) ... ok Dec 15 09:58:51.655 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::allow_cross_site_usage (line 696) ... ok Dec 15 09:58:57.267 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::insecure (line 585) ... ok Dec 15 09:58:58.487 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 15 09:59:01.487 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_name (line 627) ... ok Dec 15 09:59:04.435 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_session_type (line 761) ... ok Dec 15 09:59:06.047 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_domain (line 658) ... ok Dec 15 09:59:08.159 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_cookie_path (line 553) ... ok Dec 15 09:59:49.039 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:59:51.659 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:59:51.783 INFO [stdout] test src/middleware/session/mod.rs - middleware::session::NewSessionMiddleware::with_strict_same_site_enforcement (line 732) ... ok Dec 15 09:59:57.267 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 10:00:01.494 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 10:00:04.459 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 10:00:06.051 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 10:00:08.151 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 10:00:51.783 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 10:00:55.429 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::delete (line 609) ... FAILED Dec 15 10:00:55.455 INFO [stdout] test src/router/builder/associated.rs - router::builder::associated::AssociatedRouteBuilder<'a, M, C, P, PE, QSE>::add_route_matcher (line 71) ... FAILED Dec 15 10:00:56.056 INFO [stdout] test src/pipeline/mod.rs - pipeline::Pipeline (line 23) ... ok Dec 15 10:01:01.303 INFO [stdout] test src/pipeline/mod.rs - pipeline::PipelineBuilder (line 185) ... ok Dec 15 10:01:03.117 INFO [stderr] su: No module specific data is present Dec 15 10:01:04.523 INFO running `"docker" "inspect" "8edaa1f2e214da3cda4e7582204501f0e58cebac51012a40ea307883a3294118"` Dec 15 10:01:04.875 INFO running `"docker" "rm" "-f" "8edaa1f2e214da3cda4e7582204501f0e58cebac51012a40ea307883a3294118"` Dec 15 10:01:05.229 INFO [stdout] 8edaa1f2e214da3cda4e7582204501f0e58cebac51012a40ea307883a3294118