Dec 07 11:31:05.275 INFO testing handlebars-1.1.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 11:31:05.275 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 11:31:05.707 INFO [stdout] 090a9a2be89cfa4bd6baf2b277cd17f9ac9b2a1234443834a2c9e87c5fe39a81
Dec 07 11:31:05.709 INFO running `"docker" "start" "-a" "090a9a2be89cfa4bd6baf2b277cd17f9ac9b2a1234443834a2c9e87c5fe39a81"`
Dec 07 11:31:06.844 INFO [stderr] usermod: no changes
Dec 07 11:31:07.008 INFO [stderr]    Compiling handlebars v1.1.0 (/source)
Dec 07 11:31:16.027 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.16s
Dec 07 11:31:16.038 INFO [stderr] su: No module specific data is present
Dec 07 11:31:16.661 INFO running `"docker" "inspect" "090a9a2be89cfa4bd6baf2b277cd17f9ac9b2a1234443834a2c9e87c5fe39a81"`
Dec 07 11:31:16.795 INFO running `"docker" "rm" "-f" "090a9a2be89cfa4bd6baf2b277cd17f9ac9b2a1234443834a2c9e87c5fe39a81"`
Dec 07 11:31:16.893 INFO [stdout] 090a9a2be89cfa4bd6baf2b277cd17f9ac9b2a1234443834a2c9e87c5fe39a81
Dec 07 11:31:16.895 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 11:31:17.156 INFO [stdout] c9eb9544dfebc7bbcae4b3506982fbc94617373920921bd82bc6220189f01a71
Dec 07 11:31:17.162 INFO running `"docker" "start" "-a" "c9eb9544dfebc7bbcae4b3506982fbc94617373920921bd82bc6220189f01a71"`
Dec 07 11:31:17.799 INFO [stderr] usermod: no changes
Dec 07 11:31:17.969 INFO [stderr]    Compiling handlebars v1.1.0 (/source)
Dec 07 11:32:03.604 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 45.78s
Dec 07 11:32:03.612 INFO [stderr] su: No module specific data is present
Dec 07 11:32:04.234 INFO running `"docker" "inspect" "c9eb9544dfebc7bbcae4b3506982fbc94617373920921bd82bc6220189f01a71"`
Dec 07 11:32:04.442 INFO running `"docker" "rm" "-f" "c9eb9544dfebc7bbcae4b3506982fbc94617373920921bd82bc6220189f01a71"`
Dec 07 11:32:04.676 INFO [stdout] c9eb9544dfebc7bbcae4b3506982fbc94617373920921bd82bc6220189f01a71
Dec 07 11:32:04.682 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 11:32:05.092 INFO [stdout] 5fe3ace5330607013b784261391e7b3229a1851b052384ece9d8ee1c168646e7
Dec 07 11:32:05.094 INFO running `"docker" "start" "-a" "5fe3ace5330607013b784261391e7b3229a1851b052384ece9d8ee1c168646e7"`
Dec 07 11:32:06.736 INFO [stderr] usermod: no changes
Dec 07 11:32:06.928 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 07 11:32:06.934 INFO [stderr]      Running /target/debug/deps/handlebars-7bf9cb520e9a4ff5
Dec 07 11:32:06.946 INFO [stdout] 
Dec 07 11:32:06.946 INFO [stdout] running 99 tests
Dec 07 11:32:07.002 INFO [stdout] test context::test::test_render ... ok
Dec 07 11:32:07.028 INFO [stdout] test context::test::test_merge_json ... ok
Dec 07 11:32:07.029 INFO [stdout] test context::test::test_key_name_with_this ... ok
Dec 07 11:32:07.029 INFO [stdout] test context::test::test_this ... ok
Dec 07 11:32:07.029 INFO [stdout] test context::test::test_serialize_error ... ok
Dec 07 11:32:07.029 INFO [stdout] test context::test::test_navigation ... ok
Dec 07 11:32:07.068 INFO [stdout] test context::test::test_root ... ok
Dec 07 11:32:07.068 INFO [stdout] test grammar::test_block_param ... ok
Dec 07 11:32:07.068 INFO [stdout] test grammar::test_comment ... ok
Dec 07 11:32:07.068 INFO [stdout] test directives::inline::test::test_inline ... ok
Dec 07 11:32:07.080 INFO [stdout] test directives::test::test_local_helper_with_decorator ... ok
Dec 07 11:32:07.080 INFO [stdout] test grammar::test_hash ... ok
Dec 07 11:32:07.080 INFO [stdout] test grammar::test_expression ... ok
Dec 07 11:32:07.081 INFO [stdout] test grammar::test_directive_expression ... ok
Dec 07 11:32:07.084 INFO [stdout] test grammar::test_helper_end ... ok
Dec 07 11:32:07.086 INFO [stdout] test grammar::test_helper_expression ... ok
Dec 07 11:32:07.086 INFO [stdout] test grammar::test_helper_start ... ok
Dec 07 11:32:07.087 INFO [stdout] test grammar::test_html_expression ... ok
Dec 07 11:32:07.089 INFO [stdout] test grammar::test_directive_block ... ok
Dec 07 11:32:07.089 INFO [stdout] test grammar::test_param ... ok
Dec 07 11:32:07.091 INFO [stdout] test directives::test::test_update_data_with_decorator ... ok
Dec 07 11:32:07.091 INFO [stdout] test grammar::test_name ... ok
Dec 07 11:32:07.092 INFO [stdout] test grammar::test_path ... ok
Dec 07 11:32:07.093 INFO [stdout] test grammar::test_raw_block ... ok
Dec 07 11:32:07.094 INFO [stdout] test grammar::test_partial_expression ... ok
Dec 07 11:32:07.094 INFO [stdout] test directives::test::test_register_decorator ... ok
Dec 07 11:32:07.095 INFO [stdout] test grammar::test_partial_block ... ok
Dec 07 11:32:07.095 INFO [stdout] test grammar::test_raw_text ... ok
Dec 07 11:32:07.096 INFO [stdout] test grammar::test_reference ... ok
Dec 07 11:32:07.096 INFO [stdout] test grammar::test_raw_block_text ... ok
Dec 07 11:32:07.096 INFO [stdout] test grammar::test_subexpression ... ok
Dec 07 11:32:07.097 INFO [stdout] test grammar::test_identifier_with_dash ... ok
Dec 07 11:32:07.097 INFO [stdout] test grammar::test_json_literal ... ok
Dec 07 11:32:07.104 INFO [stdout] test grammar::test_helper_block ... ok
Dec 07 11:32:07.104 INFO [stdout] test helpers::helper_boolean::test_conditions::foo ... ok
Dec 07 11:32:07.105 INFO [stdout] test helpers::helper_each::test::test_each_object_block_param ... ok
Dec 07 11:32:07.105 INFO [stdout] test helpers::helper_each::test::test_each_with_parent ... ok
Dec 07 11:32:07.112 INFO [stdout] test helpers::helper_each::test::test_empty_key ... ok
Dec 07 11:32:07.113 INFO [stdout] test helpers::helper_boolean::test_conditions::nested_conditions ... ok
Dec 07 11:32:07.113 INFO [stdout] test helpers::helper_each::test::test_key_iteration_with_unicode ... ok
Dec 07 11:32:07.120 INFO [stdout] test helpers::helper_each::test::test_nested_array ... ok
Dec 07 11:32:07.121 INFO [stdout] test helpers::helper_each::test::test_block_param ... ok
Dec 07 11:32:07.121 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_parent ... ok
Dec 07 11:32:07.122 INFO [stdout] test helpers::helper_each::test::test_each ... ok
Dec 07 11:32:07.122 INFO [stdout] test helpers::helper_each::test::test_each_else ... ok
Dec 07 11:32:07.122 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_path_up_this ... ok
Dec 07 11:32:07.131 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_path_ups ... ok
Dec 07 11:32:07.134 INFO [stdout] test helpers::helper_each::test::test_nested_each ... ok
Dec 07 11:32:07.145 INFO [stdout] test helpers::helper_with::test::test_path_up ... ok
Dec 07 11:32:07.156 INFO [stdout] test helpers::helper_lookup::test::test_lookup ... ok
Dec 07 11:32:07.168 INFO [stdout] test helpers::helper_if::test::test_if_include_zero ... ok
Dec 07 11:32:07.176 INFO [stdout] test helpers::helper_if::test::test_if_context ... ok
Dec 07 11:32:07.177 INFO [stdout] test helpers::helper_if::test::test_if ... ok
Dec 07 11:32:07.181 INFO [stdout] test helpers::helper_with::test::test_with_in_each ... ok
Dec 07 11:32:07.185 INFO [stdout] test partial::test::test_hash_context_outscope ... ok
Dec 07 11:32:07.187 INFO [stdout] test helpers::helper_with::test::test_with ... ok
Dec 07 11:32:07.188 INFO [stdout] test helpers::test::test_helper_for_subexpression ... ok
Dec 07 11:32:07.208 INFO [stdout] test helpers::helper_with::test::test_with_block_param ... ok
Dec 07 11:32:07.209 INFO [stdout] test partial::test::test ... ok
Dec 07 11:32:07.217 INFO [stdout] test partial::test::test_include_partial_block ... ok
Dec 07 11:32:07.217 INFO [stdout] test helpers::helper_raw::test::test_raw_helper ... ok
Dec 07 11:32:07.228 INFO [stdout] test registry::test::test_register_templates_directory ... ok
Dec 07 11:32:07.240 INFO [stdout] test partial::test::test_self_inclusion ... ok
Dec 07 11:32:07.241 INFO [stdout] test helpers::test::test_meta_helper ... ok
Dec 07 11:32:07.243 INFO [stdout] test partial::test::test_issue_143 ... ok
Dec 07 11:32:07.260 INFO [stdout] test registry::test::test_escape ... ok
Dec 07 11:32:07.260 INFO [stdout] test registry::test::test_registry_operations ... ok
Dec 07 11:32:07.264 INFO [stdout] test partial::test::test_nested_partial_scope ... ok
Dec 07 11:32:07.264 INFO [stdout] test registry::test::test_render_to_write ... ok
Dec 07 11:32:07.268 INFO [stdout] test render::test_html_expression ... ok
Dec 07 11:32:07.269 INFO [stdout] test registry::test::test_escape_fn ... ok
Dec 07 11:32:07.269 INFO [stdout] test render::test_comment ... ok
Dec 07 11:32:07.271 INFO [stdout] test registry::test::test_strict_mode ... ok
Dec 07 11:32:07.271 INFO [stdout] test render::test_expression ... ok
Dec 07 11:32:07.271 INFO [stdout] test render::test_raw_string ... ok
Dec 07 11:32:07.272 INFO [stdout] test render::test_render_context_promotion_and_demotion ... ok
Dec 07 11:32:07.273 INFO [stdout] test support::str::test::test_string_writer ... ok
Dec 07 11:32:07.277 INFO [stdout] test template::test_block_param ... ok
Dec 07 11:32:07.278 INFO [stdout] test render::test_render_error_line_no ... ok
Dec 07 11:32:07.278 INFO [stdout] test render::test_partial_failback_render ... ok
Dec 07 11:32:07.279 INFO [stdout] test template::test_directive ... ok
Dec 07 11:32:07.284 INFO [stdout] test render::test_key_with_slash ... ok
Dec 07 11:32:07.305 INFO [stdout] test template::test_parse_template ... ok
Dec 07 11:32:07.306 INFO [stdout] test template::test_literal_parameter_parser ... ok
Dec 07 11:32:07.306 INFO [stdout] test render::test_template ... ok
Dec 07 11:32:07.306 INFO [stdout] test template::test_parse_error ... ok
Dec 07 11:32:07.307 INFO [stdout] test render::test_render_subexpression_issue_115 ... ok
Dec 07 11:32:07.307 INFO [stdout] test template::test_parse_escaped_tag_raw_string ... ok
Dec 07 11:32:07.307 INFO [stdout] test template::test_raw_helper ... ok
Dec 07 11:32:07.308 INFO [stdout] test template::test_parse_escaped_block_raw_string ... ok
Dec 07 11:32:07.308 INFO [stdout] test render::test_render_subexpression ... ok
Dec 07 11:32:07.308 INFO [stdout] test template::test_pure_backslash_raw_string ... ok
Dec 07 11:32:07.309 INFO [stdout] test template::test_white_space_omitter ... ok
Dec 07 11:32:07.310 INFO [stdout] test template::test_subexpression ... ok
Dec 07 11:32:07.316 INFO [stdout] test template::test_template_mapping ... ok
Dec 07 11:32:07.316 INFO [stdout] test template::test_whitespace_elements ... ok
Dec 07 11:32:07.316 INFO [stdout] test value::test_json_number_truthy ... ok
Dec 07 11:32:07.320 INFO [stdout] test value::test_json_render ... ok
Dec 07 11:32:07.324 INFO [stdout] test template::test_unclosed_expression ... ok
Dec 07 11:32:07.324 INFO [stdout] 
Dec 07 11:32:07.324 INFO [stdout] test result: ok. 99 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:32:07.324 INFO [stdout] 
Dec 07 11:32:07.325 INFO [stderr]      Running /target/debug/deps/escape-2899fb3063d1d032
Dec 07 11:32:07.338 INFO [stdout] 
Dec 07 11:32:07.338 INFO [stdout] running 1 test
Dec 07 11:32:07.352 INFO [stdout] test test_escape_216 ... ok
Dec 07 11:32:07.352 INFO [stdout] 
Dec 07 11:32:07.352 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:32:07.352 INFO [stdout] 
Dec 07 11:32:07.356 INFO [stderr]      Running /target/debug/deps/helper_macro-7df712d2b45a2eb3
Dec 07 11:32:07.362 INFO [stdout] 
Dec 07 11:32:07.362 INFO [stdout] running 1 test
Dec 07 11:32:07.363 INFO [stdout] test test_macro_helper ... ok
Dec 07 11:32:07.363 INFO [stdout] 
Dec 07 11:32:07.363 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:32:07.363 INFO [stdout] 
Dec 07 11:32:07.368 INFO [stderr]      Running /target/debug/deps/root_var-c99a3ed1d18a9974
Dec 07 11:32:07.374 INFO [stdout] 
Dec 07 11:32:07.374 INFO [stdout] running 1 test
Dec 07 11:32:07.377 INFO [stdout] test test_root_var ... ok
Dec 07 11:32:07.377 INFO [stdout] 
Dec 07 11:32:07.377 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:32:07.377 INFO [stdout] 
Dec 07 11:32:07.377 INFO [stderr]      Running /target/debug/deps/subexpression-f62c2b36988f398b
Dec 07 11:32:07.380 INFO [stdout] 
Dec 07 11:32:07.380 INFO [stdout] running 1 test
Dec 07 11:32:07.385 INFO [stdout] test test_subexpression ... ok
Dec 07 11:32:07.385 INFO [stdout] 
Dec 07 11:32:07.385 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:32:07.385 INFO [stdout] 
Dec 07 11:32:07.388 INFO [stderr]    Doc-tests handlebars
Dec 07 11:32:10.372 INFO [stdout] 
Dec 07 11:32:10.372 INFO [stdout] running 15 tests
Dec 07 11:32:36.172 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 31) ... ok
Dec 07 11:32:36.608 INFO [stdout] test src/directives/mod.rs - directives::DirectiveDef (line 44) ... ok
Dec 07 11:32:37.776 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 47) ... ok
Dec 07 11:32:38.864 INFO [stdout] test src/directives/mod.rs - directives::DirectiveDef (line 21) ... ok
Dec 07 11:32:39.632 INFO [stdout] test src/lib.rs -  (line 144) ... ok
Dec 07 11:32:43.160 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 67) ... ok
Dec 07 11:32:44.480 INFO [stdout] test src/lib.rs -  (line 123) ... ok
Dec 07 11:32:47.900 INFO [stdout] test src/lib.rs -  (line 10) ... ok
Dec 07 11:33:00.872 INFO [stdout] test src/render.rs - render::Helper<'reg, 'rc>::param (line 372) ... ok
Dec 07 11:33:02.840 INFO [stdout] test src/lib.rs -  (line 202) ... ok
Dec 07 11:33:03.809 INFO [stdout] test src/lib.rs -  (line 85) ... ok
Dec 07 11:33:04.001 INFO [stdout] test src/lib.rs -  (line 237) ... ok
Dec 07 11:33:04.076 INFO [stdout] test src/render.rs - render::Helper<'reg, 'rc>::hash_get (line 399) ... ok
Dec 07 11:33:04.081 INFO [stdout] test src/macros.rs - handlebars_helper (line 6) ... ok
Dec 07 11:33:04.356 INFO [stdout] test src/lib.rs -  (line 168) ... ok
Dec 07 11:33:04.356 INFO [stdout] 
Dec 07 11:33:04.356 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 11:33:04.356 INFO [stdout] 
Dec 07 11:33:04.384 INFO [stderr] su: No module specific data is present
Dec 07 11:33:04.639 INFO running `"docker" "inspect" "5fe3ace5330607013b784261391e7b3229a1851b052384ece9d8ee1c168646e7"`
Dec 07 11:33:04.828 INFO running `"docker" "rm" "-f" "5fe3ace5330607013b784261391e7b3229a1851b052384ece9d8ee1c168646e7"`
Dec 07 11:33:05.020 INFO [stdout] 5fe3ace5330607013b784261391e7b3229a1851b052384ece9d8ee1c168646e7