Nov 18 05:45:23.317 INFO testing mustache-0.9.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 05:45:23.317 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 05:45:23.499 INFO [stdout] 706c0a1e45ccaeb341e4b5243c83410c8b78fb3fb420620cbb5d5574985b434f
Nov 18 05:45:23.501 INFO running `"docker" "start" "-a" "706c0a1e45ccaeb341e4b5243c83410c8b78fb3fb420620cbb5d5574985b434f"`
Nov 18 05:45:24.118 INFO [stderr] usermod: no changes
Nov 18 05:45:24.155 INFO [stderr] warning: An explicit [[test]] section is specified in Cargo.toml which currently
Nov 18 05:45:24.156 INFO [stderr] disables Cargo from automatically inferring other test targets.
Nov 18 05:45:24.156 INFO [stderr] This inference behavior will change in the Rust 2018 edition and the following
Nov 18 05:45:24.156 INFO [stderr] files will be included as a test target:
Nov 18 05:45:24.156 INFO [stderr] 
Nov 18 05:45:24.157 INFO [stderr] * /source/tests/builder.rs
Nov 18 05:45:24.157 INFO [stderr] * /source/tests/template.rs
Nov 18 05:45:24.157 INFO [stderr] * /source/tests/macros.rs
Nov 18 05:45:24.158 INFO [stderr] 
Nov 18 05:45:24.158 INFO [stderr] This is likely to break cargo build or cargo test as these files may not be
Nov 18 05:45:24.158 INFO [stderr] ready to be compiled as a test target today. You can future-proof yourself
Nov 18 05:45:24.159 INFO [stderr] and disable this warning by adding `autotests = false` to your [package]
Nov 18 05:45:24.159 INFO [stderr] section. You may also move the files to a location where Cargo would not
Nov 18 05:45:24.159 INFO [stderr] automatically infer them to be a target, such as in subfolders.
Nov 18 05:45:24.159 INFO [stderr] 
Nov 18 05:45:24.160 INFO [stderr] For more information on this warning you can consult
Nov 18 05:45:24.160 INFO [stderr] https://github.com/rust-lang/cargo/issues/5330
Nov 18 05:45:24.192 INFO [stderr]    Compiling mustache v0.9.0 (/source)
Nov 18 05:45:26.235 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.10s
Nov 18 05:45:26.239 INFO [stderr] su: No module specific data is present
Nov 18 05:45:26.524 INFO running `"docker" "inspect" "706c0a1e45ccaeb341e4b5243c83410c8b78fb3fb420620cbb5d5574985b434f"`
Nov 18 05:45:26.699 INFO running `"docker" "rm" "-f" "706c0a1e45ccaeb341e4b5243c83410c8b78fb3fb420620cbb5d5574985b434f"`
Nov 18 05:45:26.839 INFO [stdout] 706c0a1e45ccaeb341e4b5243c83410c8b78fb3fb420620cbb5d5574985b434f
Nov 18 05:45:26.846 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 05:45:27.193 INFO [stdout] ce593d30abbfb7d17cd048a17024438012bd54ece5ccb2b4ccd994a865f4bd70
Nov 18 05:45:27.193 INFO running `"docker" "start" "-a" "ce593d30abbfb7d17cd048a17024438012bd54ece5ccb2b4ccd994a865f4bd70"`
Nov 18 05:45:28.115 INFO [stderr] usermod: no changes
Nov 18 05:45:28.179 INFO [stderr] warning: An explicit [[test]] section is specified in Cargo.toml which currently
Nov 18 05:45:28.179 INFO [stderr] disables Cargo from automatically inferring other test targets.
Nov 18 05:45:28.179 INFO [stderr] This inference behavior will change in the Rust 2018 edition and the following
Nov 18 05:45:28.179 INFO [stderr] files will be included as a test target:
Nov 18 05:45:28.179 INFO [stderr] 
Nov 18 05:45:28.179 INFO [stderr] * /source/tests/builder.rs
Nov 18 05:45:28.179 INFO [stderr] * /source/tests/template.rs
Nov 18 05:45:28.179 INFO [stderr] * /source/tests/macros.rs
Nov 18 05:45:28.179 INFO [stderr] 
Nov 18 05:45:28.179 INFO [stderr] This is likely to break cargo build or cargo test as these files may not be
Nov 18 05:45:28.179 INFO [stderr] ready to be compiled as a test target today. You can future-proof yourself
Nov 18 05:45:28.179 INFO [stderr] and disable this warning by adding `autotests = false` to your [package]
Nov 18 05:45:28.179 INFO [stderr] section. You may also move the files to a location where Cargo would not
Nov 18 05:45:28.179 INFO [stderr] automatically infer them to be a target, such as in subfolders.
Nov 18 05:45:28.179 INFO [stderr] 
Nov 18 05:45:28.179 INFO [stderr] For more information on this warning you can consult
Nov 18 05:45:28.179 INFO [stderr] https://github.com/rust-lang/cargo/issues/5330
Nov 18 05:45:28.251 INFO [stderr]    Compiling mustache v0.9.0 (/source)
Nov 18 05:45:34.642 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.48s
Nov 18 05:45:34.650 INFO [stderr] su: No module specific data is present
Nov 18 05:45:34.971 INFO running `"docker" "inspect" "ce593d30abbfb7d17cd048a17024438012bd54ece5ccb2b4ccd994a865f4bd70"`
Nov 18 05:45:35.135 INFO running `"docker" "rm" "-f" "ce593d30abbfb7d17cd048a17024438012bd54ece5ccb2b4ccd994a865f4bd70"`
Nov 18 05:45:35.383 INFO [stdout] ce593d30abbfb7d17cd048a17024438012bd54ece5ccb2b4ccd994a865f4bd70
Nov 18 05:45:35.390 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 05:45:35.853 INFO [stdout] 8c0b26b1ff3646d340a3bb0833914906514d76d31d97a3d8d43a4ea151f4e975
Nov 18 05:45:35.856 INFO running `"docker" "start" "-a" "8c0b26b1ff3646d340a3bb0833914906514d76d31d97a3d8d43a4ea151f4e975"`
Nov 18 05:45:36.854 INFO [stderr] usermod: no changes
Nov 18 05:45:36.911 INFO [stderr] warning: An explicit [[test]] section is specified in Cargo.toml which currently
Nov 18 05:45:36.911 INFO [stderr] disables Cargo from automatically inferring other test targets.
Nov 18 05:45:36.911 INFO [stderr] This inference behavior will change in the Rust 2018 edition and the following
Nov 18 05:45:36.911 INFO [stderr] files will be included as a test target:
Nov 18 05:45:36.911 INFO [stderr] 
Nov 18 05:45:36.911 INFO [stderr] * /source/tests/builder.rs
Nov 18 05:45:36.911 INFO [stderr] * /source/tests/template.rs
Nov 18 05:45:36.911 INFO [stderr] * /source/tests/macros.rs
Nov 18 05:45:36.911 INFO [stderr] 
Nov 18 05:45:36.911 INFO [stderr] This is likely to break cargo build or cargo test as these files may not be
Nov 18 05:45:36.911 INFO [stderr] ready to be compiled as a test target today. You can future-proof yourself
Nov 18 05:45:36.911 INFO [stderr] and disable this warning by adding `autotests = false` to your [package]
Nov 18 05:45:36.911 INFO [stderr] section. You may also move the files to a location where Cargo would not
Nov 18 05:45:36.911 INFO [stderr] automatically infer them to be a target, such as in subfolders.
Nov 18 05:45:36.911 INFO [stderr] 
Nov 18 05:45:36.911 INFO [stderr] For more information on this warning you can consult
Nov 18 05:45:36.911 INFO [stderr] https://github.com/rust-lang/cargo/issues/5330
Nov 18 05:45:36.990 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 18 05:45:37.002 INFO [stderr]      Running /target/debug/deps/mustache-065b8d2a8550e4e7
Nov 18 05:45:37.002 INFO [stdout] 
Nov 18 05:45:37.002 INFO [stdout] running 27 tests
Nov 18 05:45:37.017 INFO [stdout] test compiler::tests::test_compile_partials ... ok
Nov 18 05:45:37.017 INFO [stdout] test compiler::tests::test_compile_etags ... ok
Nov 18 05:45:37.017 INFO [stdout] test compiler::tests::test_compile_delimiters ... ok
Nov 18 05:45:37.019 INFO [stdout] test compiler::tests::test_compile_texts ... ok
Nov 18 05:45:37.019 INFO [stdout] test compiler::tests::test_compile_sections ... ok
Nov 18 05:45:37.019 INFO [stdout] test parser::tests::inverted::unclosed ... ok
Nov 18 05:45:37.019 INFO [stdout] test parser::tests::inverted::sanity ... ok
Nov 18 05:45:37.019 INFO [stdout] test compiler::tests::test_compile_utags ... ok
Nov 18 05:45:37.019 INFO [stdout] test parser::tests::empty_input ... ok
Nov 18 05:45:37.020 INFO [stdout] test parser::tests::bad_closing_tag ... ok
Nov 18 05:45:37.020 INFO [stdout] test parser::tests::empty_tag ... ok
Nov 18 05:45:37.020 INFO [stdout] test parser::tests::sections::early_close ... ok
Nov 18 05:45:37.020 INFO [stdout] test parser::tests::inverted::unclosed_nested ... ok
Nov 18 05:45:37.021 INFO [stdout] test parser::tests::sections::unclosed_nested_with_wrong_closing_tag ... ok
Nov 18 05:45:37.021 INFO [stdout] test parser::tests::sections::sanity ... ok
Nov 18 05:45:37.022 INFO [stdout] test parser::tests::inverted::unclosed_with_path ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::sections::unclosed_nested ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::inverted::unclosed_nested_with_wrong_closing_tag ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::set_delimeter::missing_closing_equals ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::set_delimeter::closing_tag_is_whitespace ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::sections::unclosed_with_path ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::set_delimeter::missing_closing_tag ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::set_delimeter::sanity ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::unbalanced_unescape ... ok
Nov 18 05:45:37.027 INFO [stdout] test parser::tests::sections::unclosed ... ok
Nov 18 05:45:37.029 INFO [stdout] test parser::tests::unclosed_tag ... ok
Nov 18 05:45:37.029 INFO [stdout] test parser::tests::whitespace_only_tag ... ok
Nov 18 05:45:37.029 INFO [stdout] 
Nov 18 05:45:37.029 INFO [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 05:45:37.029 INFO [stdout] 
Nov 18 05:45:37.030 INFO [stderr]      Running /target/debug/deps/test-62073a59b8e9bb7d
Nov 18 05:45:37.031 INFO [stdout] 
Nov 18 05:45:37.031 INFO [stdout] running 37 tests
Nov 18 05:45:37.031 INFO [stdout] test template::context_search::render_nothing_when_not_found ... ok
Nov 18 05:45:37.031 INFO [stdout] test builder::test_builders ... ok
Nov 18 05:45:37.031 INFO [stdout] test template::context_search::from_base ... ok
Nov 18 05:45:37.031 INFO [stdout] test builder::test_vec_fn_builder ... ok
Nov 18 05:45:37.031 INFO [stdout] test builder::test_map_fn_builder ... ok
Nov 18 05:45:37.031 INFO [stdout] test builder::test_empty_builders ... ok
Nov 18 05:45:37.035 INFO [stdout] test template::test_implicit_section ... ok
Nov 18 05:45:37.035 INFO [stdout] test template::test_nested_implicit_render ... ok
Nov 18 05:45:37.035 INFO [stdout] test template::test_render_data_to_string ... ok
Nov 18 05:45:37.035 INFO [stdout] test template::test_implicit_inverted_section ... ok
Nov 18 05:45:37.063 INFO [stdout] test template::test_render_etags ... ok
Nov 18 05:45:37.063 INFO [stdout] test template::test_render_option_nested ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_inverted_sections ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_ampersand_alternative_delimeters ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_complex ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::context_search::from_mid_stack ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_ampersand ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_partial_dot_filename ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_texts ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_to_string ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_utags ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_escaped ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_escaped_alternative_delimeters ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_unescaped ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_sections ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_option_sections_implicit_unescaped_alternative_delimeters ... ok
Nov 18 05:45:37.095 INFO [stdout] test template::test_render_partial ... ok
Nov 18 05:45:37.115 INFO [stdout] test template::test_spec_comments ... ok
Nov 18 05:45:37.131 INFO [stdout] test template::test_spec_delimiters ... ok
Nov 18 05:45:37.131 INFO [stdout] test template::test_write_failure ... ok
Nov 18 05:45:37.131 INFO [stdout] test template::test_spec_lambdas ... ok
Nov 18 05:45:37.137 INFO [stdout] test template::test_spec_partials ... ok
Nov 18 05:45:37.155 INFO [stdout] test template::test_spec_interpolation ... ok
Nov 18 05:45:37.155 INFO [stdout] test template::test_spec_inverted ... ok
Nov 18 05:45:37.155 INFO [stdout] test template::test_spec_sections ... ok
Nov 18 05:45:37.155 INFO [stdout] 
Nov 18 05:45:37.155 INFO [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 05:45:37.155 INFO [stdout] 
Nov 18 05:45:37.157 INFO [stderr]    Doc-tests mustache
Nov 18 05:45:38.178 INFO [stdout] 
Nov 18 05:45:38.179 INFO [stdout] running 12 tests
Nov 18 05:45:41.423 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert_vec (line 82) ... ok
Nov 18 05:45:41.659 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert_fn (line 134) ... ok
Nov 18 05:45:41.758 INFO [stdout] test src/builder.rs - builder::VecBuilder::push (line 174) ... ok
Nov 18 05:45:42.147 INFO [stdout] test src/builder.rs - builder::VecBuilder::push_bool (line 207) ... ok
Nov 18 05:45:42.423 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert (line 24) ... ok
Nov 18 05:45:42.519 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert_str (line 45) ... ok
Nov 18 05:45:42.835 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert_map (line 105) ... ok
Nov 18 05:45:43.283 INFO [stdout] test src/builder.rs - builder::MapBuilder::insert_bool (line 64) ... ok
Nov 18 05:45:44.659 INFO [stdout] test src/builder.rs - builder::VecBuilder::push_fn (line 272) ... ok
Nov 18 05:45:44.831 INFO [stdout] test src/builder.rs - builder::VecBuilder::push_str (line 191) ... ok
Nov 18 05:45:45.025 INFO [stdout] test src/builder.rs - builder::VecBuilder::push_map (line 245) ... ok
Nov 18 05:45:45.059 INFO [stdout] test src/builder.rs - builder::VecBuilder::push_vec (line 223) ... ok
Nov 18 05:45:45.059 INFO [stdout] 
Nov 18 05:45:45.059 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 05:45:45.059 INFO [stdout] 
Nov 18 05:45:45.075 INFO [stderr] su: No module specific data is present
Nov 18 05:45:45.392 INFO running `"docker" "inspect" "8c0b26b1ff3646d340a3bb0833914906514d76d31d97a3d8d43a4ea151f4e975"`
Nov 18 05:45:45.471 INFO running `"docker" "rm" "-f" "8c0b26b1ff3646d340a3bb0833914906514d76d31d97a3d8d43a4ea151f4e975"`
Nov 18 05:45:45.537 INFO [stdout] 8c0b26b1ff3646d340a3bb0833914906514d76d31d97a3d8d43a4ea151f4e975