Dec 15 19:07:19.173 INFO testing chilli-0.4.4 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 19:07:19.173 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 19:07:19.543 INFO [stdout] 6d345474a5ab4f455bd50d8b646fa54db91e00c059b581f62ad07c7e33c4c6b3
Dec 15 19:07:19.545 INFO running `"docker" "start" "-a" "6d345474a5ab4f455bd50d8b646fa54db91e00c059b581f62ad07c7e33c4c6b3"`
Dec 15 19:07:20.931 INFO [stderr] usermod: no changes
Dec 15 19:07:21.187 INFO [stderr]    Compiling textnonce v0.6.4
Dec 15 19:07:21.187 INFO [stderr]    Compiling handlebars v0.25.3
Dec 15 19:07:23.767 INFO [stderr]    Compiling mime_multipart v0.5.1
Dec 15 19:07:35.267 INFO [stderr]    Compiling formdata v0.12.2
Dec 15 19:07:40.143 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 15 19:07:50.550 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.60s
Dec 15 19:07:50.575 INFO [stderr] su: No module specific data is present
Dec 15 19:07:51.519 INFO running `"docker" "inspect" "6d345474a5ab4f455bd50d8b646fa54db91e00c059b581f62ad07c7e33c4c6b3"`
Dec 15 19:07:51.895 INFO running `"docker" "rm" "-f" "6d345474a5ab4f455bd50d8b646fa54db91e00c059b581f62ad07c7e33c4c6b3"`
Dec 15 19:07:52.262 INFO [stdout] 6d345474a5ab4f455bd50d8b646fa54db91e00c059b581f62ad07c7e33c4c6b3
Dec 15 19:07:52.271 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 19:07:52.738 INFO [stdout] ed8eddb12e1961865c3f7cb4d61c21b3d4dcde79d8cfb5095c072eacbebac9e3
Dec 15 19:07:52.741 INFO running `"docker" "start" "-a" "ed8eddb12e1961865c3f7cb4d61c21b3d4dcde79d8cfb5095c072eacbebac9e3"`
Dec 15 19:07:54.514 INFO [stderr] usermod: no changes
Dec 15 19:07:54.750 INFO [stderr]    Compiling chilli v0.4.4 (/source)
Dec 15 19:08:30.008 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.46s
Dec 15 19:08:30.021 INFO [stderr] su: No module specific data is present
Dec 15 19:08:31.663 INFO running `"docker" "inspect" "ed8eddb12e1961865c3f7cb4d61c21b3d4dcde79d8cfb5095c072eacbebac9e3"`
Dec 15 19:08:32.117 INFO running `"docker" "rm" "-f" "ed8eddb12e1961865c3f7cb4d61c21b3d4dcde79d8cfb5095c072eacbebac9e3"`
Dec 15 19:08:32.535 INFO [stdout] ed8eddb12e1961865c3f7cb4d61c21b3d4dcde79d8cfb5095c072eacbebac9e3
Dec 15 19:08:32.543 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 19:08:32.919 INFO [stdout] ff6c9a5d744abbbd77a2a6cc8be113e2fca72b7d5ae17fb8173a8e3b21ac5dda
Dec 15 19:08:32.925 INFO running `"docker" "start" "-a" "ff6c9a5d744abbbd77a2a6cc8be113e2fca72b7d5ae17fb8173a8e3b21ac5dda"`
Dec 15 19:08:34.655 INFO [stderr] usermod: no changes
Dec 15 19:08:34.919 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Dec 15 19:08:34.935 INFO [stderr]      Running /target/debug/deps/chilli-d0cd76986b9e3262
Dec 15 19:08:34.943 INFO [stdout] 
Dec 15 19:08:34.943 INFO [stdout] running 2 tests
Dec 15 19:08:34.947 INFO [stdout] test httputils::test_get_name_by_http_code ... ok
Dec 15 19:08:34.951 INFO [stdout] test routing::test_basic_routing ... ok
Dec 15 19:08:34.951 INFO [stdout] 
Dec 15 19:08:34.951 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:34.951 INFO [stdout] 
Dec 15 19:08:34.951 INFO [stderr]      Running /target/debug/deps/test_basic-83aff1107f4cf505
Dec 15 19:08:34.954 INFO [stdout] 
Dec 15 19:08:34.954 INFO [stdout] running 1 test
Dec 15 19:08:34.954 INFO [stdout] test foo ... ok
Dec 15 19:08:34.954 INFO [stdout] 
Dec 15 19:08:34.954 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:34.954 INFO [stdout] 
Dec 15 19:08:34.954 INFO [stderr]      Running /target/debug/deps/test_config-9da013c6652e3cf8
Dec 15 19:08:34.959 INFO [stdout] 
Dec 15 19:08:34.959 INFO [stdout] running 4 tests
Dec 15 19:08:34.984 INFO [stdout] test test_config_from_envvar ... ok
Dec 15 19:08:34.984 INFO [stdout] test test_config_basic_set ... ok
Dec 15 19:08:34.984 INFO [stdout] test test_config_from_file ... ok
Dec 15 19:08:34.985 INFO [stdout] test test_config_from_object ... ok
Dec 15 19:08:34.985 INFO [stdout] 
Dec 15 19:08:34.985 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:34.985 INFO [stdout] 
Dec 15 19:08:34.986 INFO [stderr]      Running /target/debug/deps/test_datastructures-c2addbb54d605e1c
Dec 15 19:08:34.991 INFO [stdout] 
Dec 15 19:08:34.991 INFO [stdout] running 1 test
Dec 15 19:08:34.991 INFO [stdout] test test_multi_dict_basic_interface ... ok
Dec 15 19:08:34.991 INFO [stdout] 
Dec 15 19:08:34.991 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:34.991 INFO [stdout] 
Dec 15 19:08:34.992 INFO [stderr]      Running /target/debug/deps/test_errors-08c7d08195e9df9b
Dec 15 19:08:34.995 INFO [stdout] 
Dec 15 19:08:34.995 INFO [stdout] running 4 tests
Dec 15 19:08:35.003 INFO [stderr]      Running /target/debug/deps/test_helpers-98ecef7a6070612b
Dec 15 19:08:35.003 INFO [stdout] test test_http_error_code ... ok
Dec 15 19:08:35.003 INFO [stdout] test test_http_error_name ... ok
Dec 15 19:08:35.003 INFO [stdout] test test_http_error_to_response ... ok
Dec 15 19:08:35.003 INFO [stdout] test test_http_error_get_body ... ok
Dec 15 19:08:35.003 INFO [stdout] 
Dec 15 19:08:35.003 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:35.003 INFO [stdout] 
Dec 15 19:08:35.011 INFO [stdout] 
Dec 15 19:08:35.011 INFO [stdout] running 4 tests
Dec 15 19:08:35.011 INFO [stdout] test test_safe_join ... ok
Dec 15 19:08:35.011 INFO [stdout] test test_abort ... ok
Dec 15 19:08:35.019 INFO [stdout] test test_redirect ... ok
Dec 15 19:08:35.019 INFO [stdout] test test_escape ... ok
Dec 15 19:08:35.019 INFO [stdout] 
Dec 15 19:08:35.019 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:08:35.019 INFO [stdout] 
Dec 15 19:08:35.019 INFO [stderr]    Doc-tests chilli
Dec 15 19:08:45.357 INFO [stdout] 
Dec 15 19:08:45.357 INFO [stdout] running 16 tests
Dec 15 19:08:45.367 INFO [stdout] test src/app.rs - app::Pencil::route (line 165) ... ignored
Dec 15 19:08:45.367 INFO [stdout] test src/app.rs - app::Pencil::test_client (line 375) ... ignored
Dec 15 19:08:45.368 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 33) ... ignored
Dec 15 19:09:39.011 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 44) ... ok
Dec 15 19:09:42.107 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 294) ... ok
Dec 15 19:09:42.107 INFO [stdout] test src/json.rs - json::jsonify (line 13) ... ignored
Dec 15 19:09:45.367 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 15 19:09:45.367 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 15 19:09:45.367 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 15 19:09:45.368 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 15 19:09:45.368 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 15 19:09:45.368 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 15 19:09:49.743 INFO [stdout] test src/config.rs - config::Config (line 12) ... ok
Dec 15 19:09:49.743 INFO [stdout] test src/templating.rs - templating::FileSystemLoader::new (line 70) ... ignored
Dec 15 19:09:49.743 INFO [stdout] test src/wrappers.rs - wrappers::Response::new (line 391) ... ignored
Dec 15 19:09:49.815 INFO [stdout] test src/config.rs - config::Config (line 20) ... ok
Dec 15 19:09:51.047 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 327) ... ok
Dec 15 19:09:51.071 INFO [stdout] test src/app.rs - app::Pencil::httperrorhandler (line 270) ... ok
Dec 15 19:09:51.151 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 42) ... ok
Dec 15 19:09:51.231 INFO [stdout] test src/app.rs - app::Pencil::new (line 101) ... ok
Dec 15 19:10:07.795 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 56) ... ok
Dec 15 19:10:07.795 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Dec 15 19:10:07.795 INFO [stdout] 
Dec 15 19:10:07.795 INFO [stdout] test result: ok. 10 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Dec 15 19:10:07.795 INFO [stdout] 
Dec 15 19:10:07.867 INFO [stderr] su: No module specific data is present
Dec 15 19:10:09.380 INFO running `"docker" "inspect" "ff6c9a5d744abbbd77a2a6cc8be113e2fca72b7d5ae17fb8173a8e3b21ac5dda"`
Dec 15 19:10:09.791 INFO running `"docker" "rm" "-f" "ff6c9a5d744abbbd77a2a6cc8be113e2fca72b7d5ae17fb8173a8e3b21ac5dda"`
Dec 15 19:10:10.119 INFO [stdout] ff6c9a5d744abbbd77a2a6cc8be113e2fca72b7d5ae17fb8173a8e3b21ac5dda