Nov 18 04:06:39.619 INFO testing pencil-0.3.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 04:06:39.619 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 04:06:39.822 INFO [stdout] 47daec0777c4afe08e1ffda61f3747d6dbd71f46f56e6c85f1f8871cba624541
Nov 18 04:06:39.823 INFO running `"docker" "start" "-a" "47daec0777c4afe08e1ffda61f3747d6dbd71f46f56e6c85f1f8871cba624541"`
Nov 18 04:06:40.287 INFO [stderr] usermod: no changes
Nov 18 04:06:40.490 INFO [stderr]    Compiling textnonce v0.4.1
Nov 18 04:06:40.490 INFO [stderr]    Compiling hyper v0.9.18
Nov 18 04:06:40.490 INFO [stderr]    Compiling handlebars v0.16.1
Nov 18 04:06:50.417 INFO [stderr]    Compiling mime_multipart v0.3.6
Nov 18 04:06:53.079 INFO [stderr]    Compiling formdata v0.11.0
Nov 18 04:06:55.813 INFO [stderr]    Compiling pencil v0.3.0 (/source)
Nov 18 04:07:03.207 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.90s
Nov 18 04:07:03.219 INFO [stderr] su: No module specific data is present
Nov 18 04:07:03.793 INFO running `"docker" "inspect" "47daec0777c4afe08e1ffda61f3747d6dbd71f46f56e6c85f1f8871cba624541"`
Nov 18 04:07:04.081 INFO running `"docker" "rm" "-f" "47daec0777c4afe08e1ffda61f3747d6dbd71f46f56e6c85f1f8871cba624541"`
Nov 18 04:07:04.394 INFO [stdout] 47daec0777c4afe08e1ffda61f3747d6dbd71f46f56e6c85f1f8871cba624541
Nov 18 04:07:04.403 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 04:07:04.808 INFO [stdout] 7db8ede695c69e4b70d2db1cbefe7900b7a4ff7e6016724bd559551caf778cc3
Nov 18 04:07:04.810 INFO running `"docker" "start" "-a" "7db8ede695c69e4b70d2db1cbefe7900b7a4ff7e6016724bd559551caf778cc3"`
Nov 18 04:07:05.838 INFO [stderr] usermod: no changes
Nov 18 04:07:06.071 INFO [stderr]    Compiling pencil v0.3.0 (/source)
Nov 18 04:07:25.848 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.97s
Nov 18 04:07:25.872 INFO [stderr] su: No module specific data is present
Nov 18 04:07:27.053 INFO running `"docker" "inspect" "7db8ede695c69e4b70d2db1cbefe7900b7a4ff7e6016724bd559551caf778cc3"`
Nov 18 04:07:27.271 INFO running `"docker" "rm" "-f" "7db8ede695c69e4b70d2db1cbefe7900b7a4ff7e6016724bd559551caf778cc3"`
Nov 18 04:07:27.770 INFO [stdout] 7db8ede695c69e4b70d2db1cbefe7900b7a4ff7e6016724bd559551caf778cc3
Nov 18 04:07:27.774 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 04:07:28.180 INFO [stdout] acc3f463311c0214bfc284b30d6a03d1b10e26bb93a2b945a37e57b52a410514
Nov 18 04:07:28.184 INFO running `"docker" "start" "-a" "acc3f463311c0214bfc284b30d6a03d1b10e26bb93a2b945a37e57b52a410514"`
Nov 18 04:07:29.075 INFO [stderr] usermod: no changes
Nov 18 04:07:29.370 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Nov 18 04:07:29.374 INFO [stderr]      Running /target/debug/deps/pencil-06f0ba834c4d07e8
Nov 18 04:07:29.375 INFO [stderr]      Running /target/debug/deps/test_basic-be1b2bcc393a1b38
Nov 18 04:07:29.375 INFO [stderr]      Running /target/debug/deps/test_config-c7571e438b0da18f
Nov 18 04:07:29.376 INFO [stderr]      Running /target/debug/deps/test_datastructures-59832480ad11003a
Nov 18 04:07:29.376 INFO [stdout] 
Nov 18 04:07:29.376 INFO [stdout] running 2 tests
Nov 18 04:07:29.377 INFO [stdout] test httputils::test_get_name_by_http_code ... ok
Nov 18 04:07:29.377 INFO [stdout] test routing::test_basic_routing ... ok
Nov 18 04:07:29.377 INFO [stdout] 
Nov 18 04:07:29.377 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.378 INFO [stdout] 
Nov 18 04:07:29.378 INFO [stdout] 
Nov 18 04:07:29.378 INFO [stdout] running 1 test
Nov 18 04:07:29.387 INFO [stdout] test foo ... ok
Nov 18 04:07:29.387 INFO [stdout] 
Nov 18 04:07:29.387 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.388 INFO [stdout] 
Nov 18 04:07:29.388 INFO [stdout] 
Nov 18 04:07:29.388 INFO [stdout] running 4 tests
Nov 18 04:07:29.388 INFO [stdout] test test_config_basic_set ... ok
Nov 18 04:07:29.389 INFO [stdout] test test_config_from_file ... ok
Nov 18 04:07:29.389 INFO [stdout] test test_config_from_object ... ok
Nov 18 04:07:29.389 INFO [stdout] test test_config_from_envvar ... ok
Nov 18 04:07:29.389 INFO [stdout] 
Nov 18 04:07:29.390 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.390 INFO [stdout] 
Nov 18 04:07:29.390 INFO [stdout] 
Nov 18 04:07:29.391 INFO [stdout] running 1 test
Nov 18 04:07:29.391 INFO [stdout] test test_multi_dict_basic_interface ... ok
Nov 18 04:07:29.391 INFO [stdout] 
Nov 18 04:07:29.391 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.392 INFO [stdout] 
Nov 18 04:07:29.392 INFO [stdout] 
Nov 18 04:07:29.392 INFO [stdout] running 4 tests
Nov 18 04:07:29.392 INFO [stdout] test test_http_error_code ... ok
Nov 18 04:07:29.393 INFO [stdout] test test_http_error_name ... ok
Nov 18 04:07:29.393 INFO [stdout] test test_http_error_to_response ... ok
Nov 18 04:07:29.393 INFO [stdout] test test_http_error_get_body ... ok
Nov 18 04:07:29.393 INFO [stdout] 
Nov 18 04:07:29.394 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.394 INFO [stdout] 
Nov 18 04:07:29.394 INFO [stderr]      Running /target/debug/deps/test_errors-6808d3c1fa2d8bd6
Nov 18 04:07:29.395 INFO [stderr]      Running /target/debug/deps/test_helpers-76b15ccc5c17886e
Nov 18 04:07:29.395 INFO [stdout] 
Nov 18 04:07:29.395 INFO [stdout] running 4 tests
Nov 18 04:07:29.407 INFO [stdout] test test_abort ... ok
Nov 18 04:07:29.407 INFO [stderr]    Doc-tests pencil
Nov 18 04:07:29.407 INFO [stdout] test test_redirect ... ok
Nov 18 04:07:29.407 INFO [stdout] test test_escape ... ok
Nov 18 04:07:29.407 INFO [stdout] test test_safe_join ... ok
Nov 18 04:07:29.407 INFO [stdout] 
Nov 18 04:07:29.407 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:07:29.407 INFO [stdout] 
Nov 18 04:07:35.975 INFO [stdout] 
Nov 18 04:07:35.975 INFO [stdout] running 16 tests
Nov 18 04:07:35.975 INFO [stdout] test src/app.rs - app::Pencil::route (line 159) ... ignored
Nov 18 04:07:35.975 INFO [stdout] test src/app.rs - app::Pencil::test_client (line 356) ... ignored
Nov 18 04:07:35.975 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 28) ... ignored
Nov 18 04:08:04.081 INFO [stdout] test src/app.rs - app::Pencil::httperrorhandler (line 251) ... ok
Nov 18 04:08:05.267 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 308) ... ok
Nov 18 04:08:05.267 INFO [stdout] test src/json.rs - json::jsonify (line 13) ... ignored
Nov 18 04:08:05.543 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 44) ... ok
Nov 18 04:08:05.543 INFO [stdout] test src/templating.rs - templating::FileSystemLoader::new (line 65) ... ignored
Nov 18 04:08:05.544 INFO [stdout] test src/wrappers.rs - wrappers::Response::new (line 382) ... ignored
Nov 18 04:08:07.575 INFO [stdout] test src/helpers.rs - helpers::PathBound::open_resource (line 37) ... ok
Nov 18 04:08:07.671 INFO [stdout] test src/config.rs - config::Config (line 14) ... ok
Nov 18 04:08:08.123 INFO [stdout] test src/app.rs - app::Pencil::new (line 96) ... ok
Nov 18 04:08:08.946 INFO [stdout] test src/config.rs - config::Config (line 22) ... ok
Nov 18 04:08:10.607 INFO [stdout] test src/app.rs - app::Pencil::usererrorhandler (line 275) ... ok
Nov 18 04:08:16.983 INFO [stdout] test src/http_errors.rs - http_errors::HTTPError (line 56) ... ok
Nov 18 04:08:17.470 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Nov 18 04:08:17.470 INFO [stdout] 
Nov 18 04:08:17.470 INFO [stdout] test result: ok. 10 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Nov 18 04:08:17.470 INFO [stdout] 
Nov 18 04:08:17.493 INFO [stderr] su: No module specific data is present
Nov 18 04:08:18.189 INFO running `"docker" "inspect" "acc3f463311c0214bfc284b30d6a03d1b10e26bb93a2b945a37e57b52a410514"`
Nov 18 04:08:18.509 INFO running `"docker" "rm" "-f" "acc3f463311c0214bfc284b30d6a03d1b10e26bb93a2b945a37e57b52a410514"`
Nov 18 04:08:18.799 INFO [stdout] acc3f463311c0214bfc284b30d6a03d1b10e26bb93a2b945a37e57b52a410514