Dec 15 22:54:46.681 INFO testing aitch-0.1.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 22:54:46.681 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:54:47.295 INFO [stdout] 9b5a493f64c10a6ff8d8c1824e8c5140cb2caad4ee6586cb74231b5c6fe693af
Dec 15 22:54:47.297 INFO running `"docker" "start" "-a" "9b5a493f64c10a6ff8d8c1824e8c5140cb2caad4ee6586cb74231b5c6fe693af"`
Dec 15 22:54:48.791 INFO [stderr] usermod: no changes
Dec 15 22:54:49.264 INFO [stderr]    Compiling aitch v0.1.1 (/source)
Dec 15 22:55:00.019 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.20s
Dec 15 22:55:00.034 INFO [stderr] su: No module specific data is present
Dec 15 22:55:00.778 INFO running `"docker" "inspect" "9b5a493f64c10a6ff8d8c1824e8c5140cb2caad4ee6586cb74231b5c6fe693af"`
Dec 15 22:55:01.137 INFO running `"docker" "rm" "-f" "9b5a493f64c10a6ff8d8c1824e8c5140cb2caad4ee6586cb74231b5c6fe693af"`
Dec 15 22:55:01.451 INFO [stdout] 9b5a493f64c10a6ff8d8c1824e8c5140cb2caad4ee6586cb74231b5c6fe693af
Dec 15 22:55:01.453 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:55:02.043 INFO [stdout] 1a5577f30bc790f43ea1cd1686913efa0494bb68abd3a9468130fe0a9c9b4e38
Dec 15 22:55:02.045 INFO running `"docker" "start" "-a" "1a5577f30bc790f43ea1cd1686913efa0494bb68abd3a9468130fe0a9c9b4e38"`
Dec 15 22:55:03.494 INFO [stderr] usermod: no changes
Dec 15 22:55:03.943 INFO [stderr]    Compiling aitch v0.1.1 (/source)
Dec 15 22:57:16.003 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 12s
Dec 15 22:57:16.020 INFO [stderr] su: No module specific data is present
Dec 15 22:57:16.762 INFO running `"docker" "inspect" "1a5577f30bc790f43ea1cd1686913efa0494bb68abd3a9468130fe0a9c9b4e38"`
Dec 15 22:57:17.110 INFO running `"docker" "rm" "-f" "1a5577f30bc790f43ea1cd1686913efa0494bb68abd3a9468130fe0a9c9b4e38"`
Dec 15 22:57:17.410 INFO [stdout] 1a5577f30bc790f43ea1cd1686913efa0494bb68abd3a9468130fe0a9c9b4e38
Dec 15 22:57:17.415 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:57:17.865 INFO [stdout] 788b6e408f3efe2786744558a80a1283576956ba879c8489e607711084926c52
Dec 15 22:57:17.871 INFO running `"docker" "start" "-a" "788b6e408f3efe2786744558a80a1283576956ba879c8489e607711084926c52"`
Dec 15 22:57:19.123 INFO [stderr] usermod: no changes
Dec 15 22:57:19.431 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Dec 15 22:57:19.508 INFO [stderr]      Running /target/debug/deps/aitch-b0d614e0a9c0e7ad
Dec 15 22:57:19.508 INFO [stderr]      Running /target/debug/deps/hyper-ba96f363b922d7ef
Dec 15 22:57:19.508 INFO [stdout] 
Dec 15 22:57:19.508 INFO [stdout] running 2 tests
Dec 15 22:57:19.508 INFO [stdout] test handlers::static_files::test::match_file_directory_traversal_attack ... ok
Dec 15 22:57:19.508 INFO [stdout] test handlers::static_files::test::match_file_in_directory ... ok
Dec 15 22:57:19.508 INFO [stdout] 
Dec 15 22:57:19.508 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:57:19.508 INFO [stdout] 
Dec 15 22:57:19.508 INFO [stdout] 
Dec 15 22:57:19.508 INFO [stdout] running 6 tests
Dec 15 22:57:19.756 INFO [stdout] test status_code ... ok
Dec 15 22:57:19.773 INFO [stdout] test echo_server ... ok
Dec 15 22:57:19.787 INFO [stdout] test json ... ok
Dec 15 22:57:19.803 INFO [stdout] test static_files_handler ... ok
Dec 15 22:57:19.805 INFO [stdout] test static_file_handler ... ok
Dec 15 22:57:19.885 INFO [stdout] test router ... ok
Dec 15 22:57:19.885 INFO [stdout] 
Dec 15 22:57:19.886 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:57:19.886 INFO [stdout] 
Dec 15 22:57:19.895 INFO [stdout] 
Dec 15 22:57:19.895 INFO [stderr]      Running /target/debug/deps/tiny_http-7842b2a01c6021b2
Dec 15 22:57:19.895 INFO [stdout] running 1 test
Dec 15 22:57:19.923 INFO [stdout] test echo_server ... ok
Dec 15 22:57:19.923 INFO [stdout] 
Dec 15 22:57:19.923 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:57:19.923 INFO [stdout] 
Dec 15 22:57:19.925 INFO [stderr]    Doc-tests aitch
Dec 15 22:57:32.329 INFO [stdout] 
Dec 15 22:57:32.329 INFO [stdout] running 20 tests
Dec 15 22:57:32.363 INFO [stdout] test src/handler.rs - handler::Handler (line 13) ... ignored
Dec 15 22:57:32.363 INFO [stdout] test src/lib.rs -  (line 23) ... ignored
Dec 15 22:58:26.839 INFO [stdout] test src/handler.rs - handler::Handler (line 24) ... ok
Dec 15 22:58:32.339 INFO [stdout] test src/body.rs - body::Body (line 40) ... test src/body.rs - body::Body (line 40) has been running for over 60 seconds
Dec 15 22:58:32.339 INFO [stdout] test src/handler.rs - handler::BoxedHandler (line 102) ... test src/handler.rs - handler::BoxedHandler (line 102) has been running for over 60 seconds
Dec 15 22:58:32.343 INFO [stdout] test src/handler.rs - handler::Handler (line 38) ... test src/handler.rs - handler::Handler (line 38) has been running for over 60 seconds
Dec 15 22:58:32.359 INFO [stdout] test src/json.rs - json::Json (line 30) ... test src/json.rs - json::Json (line 30) has been running for over 60 seconds
Dec 15 22:58:32.371 INFO [stdout] test src/lib.rs -  (line 35) ... test src/lib.rs -  (line 35) has been running for over 60 seconds
Dec 15 22:58:32.371 INFO [stdout] test src/lib.rs -  (line 137) ... test src/lib.rs -  (line 137) has been running for over 60 seconds
Dec 15 22:58:32.371 INFO [stdout] test src/lib.rs -  (line 124) ... test src/lib.rs -  (line 124) has been running for over 60 seconds
Dec 15 22:58:32.547 INFO [stdout] test src/json.rs - json::Json (line 30) ... ok
Dec 15 22:58:33.159 INFO [stdout] test src/handler.rs - handler::Handler (line 38) ... ok
Dec 15 22:58:36.227 INFO [stdout] test src/handler.rs - handler::BoxedHandler (line 102) ... ok
Dec 15 22:58:36.228 INFO [stdout] test src/middlewares/mod.rs - middlewares::with_context (line 92) ... ignored
Dec 15 22:58:37.463 INFO [stdout] test src/body.rs - body::Body (line 40) ... ok
Dec 15 22:58:37.463 INFO [stdout] test src/middlewares/mod.rs - middlewares::with_stdout_logging (line 50) ... ignored
Dec 15 22:58:39.255 INFO [stdout] test src/lib.rs -  (line 124) ... ok
Dec 15 22:58:39.255 INFO [stdout] test src/responder.rs - responder::Responder (line 14) ... ignored
Dec 15 22:58:40.623 INFO [stdout] test src/lib.rs -  (line 137) ... ok
Dec 15 22:58:43.347 INFO [stdout] test src/lib.rs -  (line 35) ... ok
Dec 15 22:59:26.843 INFO [stdout] test src/lib.rs -  (line 79) ... test src/lib.rs -  (line 79) has been running for over 60 seconds
Dec 15 22:59:28.281 INFO [stdout] test src/middlewares/mod.rs - middlewares::with_context (line 107) ... ok
Dec 15 22:59:29.499 INFO [stdout] test src/lib.rs -  (line 79) ... ok
Dec 15 22:59:32.555 INFO [stdout] test src/lib.rs - ResponseBuilder (line 204) ... test src/lib.rs - ResponseBuilder (line 204) has been running for over 60 seconds
Dec 15 22:59:35.789 INFO [stdout] test src/middlewares/mod.rs - middlewares::with_stdout_logging (line 27) ... ok
Dec 15 22:59:35.856 INFO [stdout] test src/lib.rs - ResponseBuilder (line 204) ... ok
Dec 15 22:59:36.095 INFO [stdout] test src/middlewares/router.rs - middlewares::router::SimpleRouter (line 28) ... ok
Dec 15 22:59:37.171 INFO [stdout] test src/servers/tiny_http/mod.rs - servers::tiny_http::Server (line 53) ... ok
Dec 15 22:59:38.210 INFO [stdout] test src/servers/hyper.rs - servers::hyper::Server (line 54) ... ok
Dec 15 22:59:38.211 INFO [stdout] 
Dec 15 22:59:38.211 INFO [stdout] test result: ok. 15 passed; 0 failed; 5 ignored; 0 measured; 0 filtered out
Dec 15 22:59:38.211 INFO [stdout] 
Dec 15 22:59:38.251 INFO [stderr] su: No module specific data is present
Dec 15 22:59:38.955 INFO running `"docker" "inspect" "788b6e408f3efe2786744558a80a1283576956ba879c8489e607711084926c52"`
Dec 15 22:59:39.205 INFO running `"docker" "rm" "-f" "788b6e408f3efe2786744558a80a1283576956ba879c8489e607711084926c52"`
Dec 15 22:59:39.482 INFO [stdout] 788b6e408f3efe2786744558a80a1283576956ba879c8489e607711084926c52