Dec 07 20:46:44.185 INFO testing chilli-0.4.4 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 20:46:44.185 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 20:46:44.687 INFO [stdout] e639772bc0b0a12087f11dc9ebcb237e679674033880f563c338a8b213c2a067
Dec 07 20:46:44.694 INFO running `"docker" "start" "-a" "e639772bc0b0a12087f11dc9ebcb237e679674033880f563c338a8b213c2a067"`
Dec 07 20:46:46.231 INFO [stderr] usermod: no changes
Dec 07 20:46:46.524 INFO [stderr]    Compiling textnonce v0.6.4
Dec 07 20:46:46.524 INFO [stderr]    Compiling handlebars v0.25.3
Dec 07 20:46:49.301 INFO [stderr]    Compiling mime_multipart v0.5.1
Dec 07 20:47:00.464 INFO [stderr]    Compiling formdata v0.12.2
Dec 07 20:47:05.324 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 07 20:47:15.689 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.41s
Dec 07 20:47:15.705 INFO [stderr] su: No module specific data is present
Dec 07 20:47:16.508 INFO running `"docker" "inspect" "e639772bc0b0a12087f11dc9ebcb237e679674033880f563c338a8b213c2a067"`
Dec 07 20:47:16.884 INFO running `"docker" "rm" "-f" "e639772bc0b0a12087f11dc9ebcb237e679674033880f563c338a8b213c2a067"`
Dec 07 20:47:17.181 INFO [stdout] e639772bc0b0a12087f11dc9ebcb237e679674033880f563c338a8b213c2a067
Dec 07 20:47:17.186 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 20:47:17.605 INFO [stdout] caad7cf57bbaf49a60a902254940e71b4947174eeb94b8b1c5961ad328ad284d
Dec 07 20:47:17.607 INFO running `"docker" "start" "-a" "caad7cf57bbaf49a60a902254940e71b4947174eeb94b8b1c5961ad328ad284d"`
Dec 07 20:47:19.020 INFO [stderr] usermod: no changes
Dec 07 20:47:19.284 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 07 20:47:56.690 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 37.65s
Dec 07 20:47:56.704 INFO [stderr] su: No module specific data is present
Dec 07 20:47:57.504 INFO running `"docker" "inspect" "caad7cf57bbaf49a60a902254940e71b4947174eeb94b8b1c5961ad328ad284d"`
Dec 07 20:47:57.726 INFO running `"docker" "rm" "-f" "caad7cf57bbaf49a60a902254940e71b4947174eeb94b8b1c5961ad328ad284d"`
Dec 07 20:47:58.036 INFO [stdout] caad7cf57bbaf49a60a902254940e71b4947174eeb94b8b1c5961ad328ad284d
Dec 07 20:47:58.042 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 20:47:58.532 INFO [stdout] 70fc873d03f54d78d0dbe216cd09c2af0fef021878abeae10466b51544e6be20
Dec 07 20:47:58.534 INFO running `"docker" "start" "-a" "70fc873d03f54d78d0dbe216cd09c2af0fef021878abeae10466b51544e6be20"`
Dec 07 20:48:00.086 INFO [stderr] usermod: no changes
Dec 07 20:48:00.350 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Dec 07 20:48:00.356 INFO [stderr]      Running /target/debug/deps/chilli-c84848d8145b3708
Dec 07 20:48:00.360 INFO [stdout] 
Dec 07 20:48:00.360 INFO [stdout] running 2 tests
Dec 07 20:48:00.360 INFO [stdout] test httputils::test_get_name_by_http_code ... ok
Dec 07 20:48:00.388 INFO [stdout] test routing::test_basic_routing ... ok
Dec 07 20:48:00.388 INFO [stdout] 
Dec 07 20:48:00.389 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.389 INFO [stdout] 
Dec 07 20:48:00.390 INFO [stderr]      Running /target/debug/deps/test_basic-5979fa47fcb44ba0
Dec 07 20:48:00.398 INFO [stdout] 
Dec 07 20:48:00.398 INFO [stdout] running 1 test
Dec 07 20:48:00.399 INFO [stdout] test foo ... ok
Dec 07 20:48:00.399 INFO [stdout] 
Dec 07 20:48:00.399 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.399 INFO [stdout] 
Dec 07 20:48:00.401 INFO [stderr]      Running /target/debug/deps/test_config-5be569359d0bc69d
Dec 07 20:48:00.403 INFO [stdout] 
Dec 07 20:48:00.403 INFO [stdout] running 4 tests
Dec 07 20:48:00.403 INFO [stdout] test test_config_from_object ... ok
Dec 07 20:48:00.408 INFO [stdout] test test_config_basic_set ... ok
Dec 07 20:48:00.408 INFO [stdout] test test_config_from_file ... ok
Dec 07 20:48:00.408 INFO [stdout] test test_config_from_envvar ... ok
Dec 07 20:48:00.408 INFO [stdout] 
Dec 07 20:48:00.408 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.408 INFO [stdout] 
Dec 07 20:48:00.412 INFO [stderr]      Running /target/debug/deps/test_datastructures-5b3a12f6e7cb1db1
Dec 07 20:48:00.418 INFO [stdout] 
Dec 07 20:48:00.418 INFO [stdout] running 1 test
Dec 07 20:48:00.424 INFO [stdout] test test_multi_dict_basic_interface ... ok
Dec 07 20:48:00.424 INFO [stdout] 
Dec 07 20:48:00.424 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.424 INFO [stdout] 
Dec 07 20:48:00.424 INFO [stderr]      Running /target/debug/deps/test_errors-46f5dabe15cb8c5d
Dec 07 20:48:00.427 INFO [stdout] 
Dec 07 20:48:00.427 INFO [stdout] running 4 tests
Dec 07 20:48:00.427 INFO [stdout] test test_http_error_get_body ... ok
Dec 07 20:48:00.427 INFO [stdout] test test_http_error_to_response ... ok
Dec 07 20:48:00.427 INFO [stdout] test test_http_error_code ... ok
Dec 07 20:48:00.432 INFO [stdout] test test_http_error_name ... ok
Dec 07 20:48:00.432 INFO [stdout] 
Dec 07 20:48:00.432 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.432 INFO [stdout] 
Dec 07 20:48:00.436 INFO [stderr]      Running /target/debug/deps/test_helpers-88b9f8a6a78c8183
Dec 07 20:48:00.439 INFO [stdout] 
Dec 07 20:48:00.439 INFO [stdout] running 4 tests
Dec 07 20:48:00.444 INFO [stdout] test test_abort ... ok
Dec 07 20:48:00.448 INFO [stdout] test test_escape ... ok
Dec 07 20:48:00.452 INFO [stdout] test test_redirect ... ok
Dec 07 20:48:00.452 INFO [stdout] test test_safe_join ... ok
Dec 07 20:48:00.452 INFO [stdout] 
Dec 07 20:48:00.452 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:48:00.452 INFO [stdout] 
Dec 07 20:48:00.452 INFO [stderr]    Doc-tests chilli
Dec 07 20:48:11.808 INFO [stdout] 
Dec 07 20:48:11.808 INFO [stdout] running 16 tests
Dec 07 20:48:11.820 INFO [stdout] test src/app.rs - app::Pencil::route (line 165) ... ignored
Dec 07 20:48:11.820 INFO [stdout] test src/app.rs - app::Pencil::test_client (line 375) ... ignored
Dec 07 20:48:11.820 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 33) ... ignored
Dec 07 20:49:06.796 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 327) ... ok
Dec 07 20:49:11.812 INFO [stdout] test src/app.rs - app::Pencil::httperrorhandler (line 270) ... test src/app.rs - app::Pencil::httperrorhandler (line 270) has been running for over 60 seconds
Dec 07 20:49:11.813 INFO [stdout] test src/app.rs - app::Pencil::new (line 101) ... test src/app.rs - app::Pencil::new (line 101) has been running for over 60 seconds
Dec 07 20:49:11.813 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 294) ... test src/app.rs - app::Pencil::usererrorhandler (line 294) has been running for over 60 seconds
Dec 07 20:49:11.813 INFO [stdout] test src/config.rs - config::Config (line 12) ... test src/config.rs - config::Config (line 12) has been running for over 60 seconds
Dec 07 20:49:11.820 INFO [stdout] test src/config.rs - config::Config (line 20) ... test src/config.rs - config::Config (line 20) has been running for over 60 seconds
Dec 07 20:49:11.821 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 44) ... test src/http_errors.rs - http_errors::HTTPError (line 44) has been running for over 60 seconds
Dec 07 20:49:11.821 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 42) ... test src/helpers.rs - helpers::PathBound::open_resource (line 42) has been running for over 60 seconds
Dec 07 20:49:15.944 INFO [stdout] test src/config.rs - config::Config (line 20) ... ok
Dec 07 20:49:15.945 INFO [stdout] test src/json.rs - json::jsonify (line 13) ... ignored
Dec 07 20:49:18.268 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 294) ... ok
Dec 07 20:49:18.269 INFO [stdout] test src/templating.rs - templating::FileSystemLoader::new (line 70) ... ignored
Dec 07 20:49:18.269 INFO [stdout] test src/wrappers.rs - wrappers::Response::new (line 391) ... ignored
Dec 07 20:49:18.476 INFO [stdout] test src/app.rs - app::Pencil::new (line 101) ... ok
Dec 07 20:49:19.160 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 44) ... ok
Dec 07 20:49:19.432 INFO [stdout] test src/config.rs - config::Config (line 12) ... ok
Dec 07 20:49:20.428 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 42) ... ok
Dec 07 20:49:20.700 INFO [stdout] test src/app.rs - app::Pencil::httperrorhandler (line 270) ... ok
Dec 07 20:49:36.720 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 56) ... ok
Dec 07 20:49:38.056 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Dec 07 20:49:38.056 INFO [stdout] 
Dec 07 20:49:38.056 INFO [stdout] test result: ok. 10 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Dec 07 20:49:38.056 INFO [stdout] 
Dec 07 20:49:38.080 INFO [stderr] su: No module specific data is present
Dec 07 20:49:39.129 INFO running `"docker" "inspect" "70fc873d03f54d78d0dbe216cd09c2af0fef021878abeae10466b51544e6be20"`
Dec 07 20:49:39.462 INFO running `"docker" "rm" "-f" "70fc873d03f54d78d0dbe216cd09c2af0fef021878abeae10466b51544e6be20"`
Dec 07 20:49:39.806 INFO [stdout] 70fc873d03f54d78d0dbe216cd09c2af0fef021878abeae10466b51544e6be20