Dec 07 20:46:39.557 INFO testing chilli-0.4.4 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 20:46:39.557 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:46:40.066 INFO [stdout] 43edfa40699225e729a8e6321fa394e943c8c798ae031fb540f0daebdfe6fb71
Dec 07 20:46:40.068 INFO running `"docker" "start" "-a" "43edfa40699225e729a8e6321fa394e943c8c798ae031fb540f0daebdfe6fb71"`
Dec 07 20:46:42.192 INFO [stderr] usermod: no changes
Dec 07 20:46:42.484 INFO [stderr]    Compiling handlebars v0.25.3
Dec 07 20:46:42.488 INFO [stderr]    Compiling textnonce v0.6.4
Dec 07 20:46:44.888 INFO [stderr]    Compiling mime_multipart v0.5.1
Dec 07 20:46:56.484 INFO [stderr]    Compiling formdata v0.12.2
Dec 07 20:47:01.268 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 07 20:47:12.548 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 30.33s
Dec 07 20:47:12.566 INFO [stderr] su: No module specific data is present
Dec 07 20:47:14.217 INFO running `"docker" "inspect" "43edfa40699225e729a8e6321fa394e943c8c798ae031fb540f0daebdfe6fb71"`
Dec 07 20:47:14.564 INFO running `"docker" "rm" "-f" "43edfa40699225e729a8e6321fa394e943c8c798ae031fb540f0daebdfe6fb71"`
Dec 07 20:47:14.952 INFO [stdout] 43edfa40699225e729a8e6321fa394e943c8c798ae031fb540f0daebdfe6fb71
Dec 07 20:47:14.958 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:47:15.524 INFO [stdout] 379bbd2af4718a2711d0c23d1d5bd4f30bbf34d7fda583f3cfa5db2f295a7a9a
Dec 07 20:47:15.526 INFO running `"docker" "start" "-a" "379bbd2af4718a2711d0c23d1d5bd4f30bbf34d7fda583f3cfa5db2f295a7a9a"`
Dec 07 20:47:16.904 INFO [stderr] usermod: no changes
Dec 07 20:47:17.124 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 07 20:47:53.553 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 36.63s
Dec 07 20:47:53.569 INFO [stderr] su: No module specific data is present
Dec 07 20:47:55.607 INFO running `"docker" "inspect" "379bbd2af4718a2711d0c23d1d5bd4f30bbf34d7fda583f3cfa5db2f295a7a9a"`
Dec 07 20:47:55.988 INFO running `"docker" "rm" "-f" "379bbd2af4718a2711d0c23d1d5bd4f30bbf34d7fda583f3cfa5db2f295a7a9a"`
Dec 07 20:47:56.324 INFO [stdout] 379bbd2af4718a2711d0c23d1d5bd4f30bbf34d7fda583f3cfa5db2f295a7a9a
Dec 07 20:47:56.326 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:47:56.802 INFO [stdout] dd8033f806f95559063744d28d3a064dd506bd827a6be516d8a64f5422f8c1be
Dec 07 20:47:56.807 INFO running `"docker" "start" "-a" "dd8033f806f95559063744d28d3a064dd506bd827a6be516d8a64f5422f8c1be"`
Dec 07 20:47:57.872 INFO [stderr] usermod: no changes
Dec 07 20:47:58.140 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.23s
Dec 07 20:47:58.152 INFO [stderr]      Running /target/debug/deps/chilli-cf020354ecc4e70f
Dec 07 20:47:58.152 INFO [stdout] 
Dec 07 20:47:58.152 INFO [stdout] running 2 tests
Dec 07 20:47:58.156 INFO [stdout] test httputils::test_get_name_by_http_code ... ok
Dec 07 20:47:58.177 INFO [stdout] test routing::test_basic_routing ... ok
Dec 07 20:47:58.177 INFO [stdout] 
Dec 07 20:47:58.177 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.177 INFO [stdout] 
Dec 07 20:47:58.180 INFO [stderr]      Running /target/debug/deps/test_basic-a4e86bc673d7c036
Dec 07 20:47:58.204 INFO [stderr]      Running /target/debug/deps/test_config-d1e7222e4d99dbfa
Dec 07 20:47:58.204 INFO [stdout] 
Dec 07 20:47:58.204 INFO [stdout] running 1 test
Dec 07 20:47:58.204 INFO [stdout] test foo ... ok
Dec 07 20:47:58.204 INFO [stdout] 
Dec 07 20:47:58.204 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.204 INFO [stdout] 
Dec 07 20:47:58.208 INFO [stdout] 
Dec 07 20:47:58.208 INFO [stdout] running 4 tests
Dec 07 20:47:58.208 INFO [stdout] test test_config_from_envvar ... ok
Dec 07 20:47:58.208 INFO [stdout] test test_config_from_file ... ok
Dec 07 20:47:58.212 INFO [stdout] test test_config_from_object ... ok
Dec 07 20:47:58.212 INFO [stderr]      Running /target/debug/deps/test_datastructures-b69244f4b3e53545
Dec 07 20:47:58.212 INFO [stdout] test test_config_basic_set ... ok
Dec 07 20:47:58.212 INFO [stdout] 
Dec 07 20:47:58.212 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.212 INFO [stdout] 
Dec 07 20:47:58.212 INFO [stdout] 
Dec 07 20:47:58.212 INFO [stdout] running 1 test
Dec 07 20:47:58.212 INFO [stdout] test test_multi_dict_basic_interface ... ok
Dec 07 20:47:58.212 INFO [stdout] 
Dec 07 20:47:58.212 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.212 INFO [stdout] 
Dec 07 20:47:58.216 INFO [stdout] 
Dec 07 20:47:58.216 INFO [stderr]      Running /target/debug/deps/test_errors-3ded6428421eebdc
Dec 07 20:47:58.216 INFO [stdout] running 4 tests
Dec 07 20:47:58.216 INFO [stdout] test test_http_error_to_response ... ok
Dec 07 20:47:58.216 INFO [stdout] test test_http_error_name ... ok
Dec 07 20:47:58.216 INFO [stdout] test test_http_error_get_body ... ok
Dec 07 20:47:58.224 INFO [stdout] test test_http_error_code ... ok
Dec 07 20:47:58.224 INFO [stdout] 
Dec 07 20:47:58.224 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.224 INFO [stdout] 
Dec 07 20:47:58.228 INFO [stderr]      Running /target/debug/deps/test_helpers-01b7218efad48c62
Dec 07 20:47:58.231 INFO [stdout] 
Dec 07 20:47:58.231 INFO [stdout] running 4 tests
Dec 07 20:47:58.231 INFO [stdout] test test_safe_join ... ok
Dec 07 20:47:58.231 INFO [stdout] test test_redirect ... ok
Dec 07 20:47:58.233 INFO [stdout] test test_abort ... ok
Dec 07 20:47:58.240 INFO [stdout] test test_escape ... ok
Dec 07 20:47:58.240 INFO [stdout] 
Dec 07 20:47:58.240 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:47:58.241 INFO [stdout] 
Dec 07 20:47:58.242 INFO [stderr]    Doc-tests chilli
Dec 07 20:48:09.490 INFO [stdout] 
Dec 07 20:48:09.490 INFO [stdout] running 16 tests
Dec 07 20:48:09.528 INFO [stdout] test src/app.rs - app::Pencil::route (line 165) ... ignored
Dec 07 20:48:09.528 INFO [stdout] test src/app.rs - app::Pencil::test_client (line 375) ... ignored
Dec 07 20:48:09.528 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 33) ... ignored
Dec 07 20:49:00.484 INFO [stdout] test src/app.rs - app::Pencil::new (line 101) ... ok
Dec 07 20:49:00.888 INFO [stdout] test src/config.rs - config::Config (line 20) ... ok
Dec 07 20:49:00.888 INFO [stdout] test src/json.rs - json::jsonify (line 13) ... ignored
Dec 07 20:49:09.492 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 327) ... test src/app.rs - app::Pencil::usererrorhandler (line 327) has been running for over 60 seconds
Dec 07 20:49:09.493 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:09.493 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:09.508 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:09.528 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:09.529 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:13.192 INFO [stdout] test src/app.rs - app::Pencil::httperrorhandler (line 270) ... ok
Dec 07 20:49:13.192 INFO [stdout] test src/templating.rs - templating::FileSystemLoader::new (line 70) ... ignored
Dec 07 20:49:13.192 INFO [stdout] test src/wrappers.rs - wrappers::Response::new (line 391) ... ignored
Dec 07 20:49:14.724 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 294) ... ok
Dec 07 20:49:14.784 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 327) ... ok
Dec 07 20:49:16.550 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 44) ... ok
Dec 07 20:49:18.505 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 42) ... ok
Dec 07 20:49:18.591 INFO [stdout] test src/config.rs - config::Config (line 12) ... ok
Dec 07 20:49:32.995 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 56) ... ok
Dec 07 20:49:33.065 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Dec 07 20:49:33.065 INFO [stdout] 
Dec 07 20:49:33.065 INFO [stdout] test result: ok. 10 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Dec 07 20:49:33.065 INFO [stdout] 
Dec 07 20:49:33.101 INFO [stderr] su: No module specific data is present
Dec 07 20:49:34.539 INFO running `"docker" "inspect" "dd8033f806f95559063744d28d3a064dd506bd827a6be516d8a64f5422f8c1be"`
Dec 07 20:49:34.926 INFO running `"docker" "rm" "-f" "dd8033f806f95559063744d28d3a064dd506bd827a6be516d8a64f5422f8c1be"`
Dec 07 20:49:35.296 INFO [stdout] dd8033f806f95559063744d28d3a064dd506bd827a6be516d8a64f5422f8c1be