Nov 18 10:00:56.135 INFO testing handlebars-1.0.5 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 10:00:56.135 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 10:00:56.615 INFO [stdout] 8d511ddaa9d92d59e0b58871a2997dbe67520bc3d7b8caddf2ad27cd39904a3b
Nov 18 10:00:56.619 INFO running `"docker" "start" "-a" "8d511ddaa9d92d59e0b58871a2997dbe67520bc3d7b8caddf2ad27cd39904a3b"`
Nov 18 10:00:57.743 INFO [stderr] usermod: no changes
Nov 18 10:00:57.911 INFO [stderr]    Compiling handlebars v1.0.5 (/source)
Nov 18 10:01:06.748 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.00s
Nov 18 10:01:06.775 INFO [stderr] su: No module specific data is present
Nov 18 10:01:07.740 INFO running `"docker" "inspect" "8d511ddaa9d92d59e0b58871a2997dbe67520bc3d7b8caddf2ad27cd39904a3b"`
Nov 18 10:01:07.947 INFO running `"docker" "rm" "-f" "8d511ddaa9d92d59e0b58871a2997dbe67520bc3d7b8caddf2ad27cd39904a3b"`
Nov 18 10:01:08.106 INFO [stdout] 8d511ddaa9d92d59e0b58871a2997dbe67520bc3d7b8caddf2ad27cd39904a3b
Nov 18 10:01:08.109 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 10:01:08.318 INFO [stdout] b30aa14c8f07998c3f45951a8e094c9d66ce9aa259301b8f453f5aba7103b968
Nov 18 10:01:08.320 INFO running `"docker" "start" "-a" "b30aa14c8f07998c3f45951a8e094c9d66ce9aa259301b8f453f5aba7103b968"`
Nov 18 10:01:09.071 INFO [stderr] usermod: no changes
Nov 18 10:01:09.284 INFO [stderr]    Compiling handlebars v1.0.5 (/source)
Nov 18 10:01:50.990 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 41.88s
Nov 18 10:01:51.014 INFO [stderr] su: No module specific data is present
Nov 18 10:01:51.608 INFO running `"docker" "inspect" "b30aa14c8f07998c3f45951a8e094c9d66ce9aa259301b8f453f5aba7103b968"`
Nov 18 10:01:51.932 INFO running `"docker" "rm" "-f" "b30aa14c8f07998c3f45951a8e094c9d66ce9aa259301b8f453f5aba7103b968"`
Nov 18 10:01:52.375 INFO [stdout] b30aa14c8f07998c3f45951a8e094c9d66ce9aa259301b8f453f5aba7103b968
Nov 18 10:01:52.384 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 10:01:52.819 INFO [stdout] 0175d7e922cdd8a24b7916a30a04f324531c79bb5931448259ad4d19efc1233b
Nov 18 10:01:52.825 INFO running `"docker" "start" "-a" "0175d7e922cdd8a24b7916a30a04f324531c79bb5931448259ad4d19efc1233b"`
Nov 18 10:01:53.748 INFO [stderr] usermod: no changes
Nov 18 10:01:53.897 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Nov 18 10:01:53.919 INFO [stderr]      Running /target/debug/deps/handlebars-c300e48f2d3731ee
Nov 18 10:01:53.919 INFO [stdout] 
Nov 18 10:01:53.920 INFO [stdout] running 97 tests
Nov 18 10:01:53.939 INFO [stdout] test context::test::test_render ... ok
Nov 18 10:01:53.939 INFO [stdout] test context::test::test_navigation ... ok
Nov 18 10:01:53.940 INFO [stdout] test context::test::test_key_name_with_this ... ok
Nov 18 10:01:53.940 INFO [stdout] test context::test::test_root ... ok
Nov 18 10:01:53.940 INFO [stdout] test context::test::test_merge_json ... ok
Nov 18 10:01:53.941 INFO [stdout] test grammar::test_block_param ... ok
Nov 18 10:01:53.941 INFO [stdout] test directives::test::test_register_decorator ... ok
Nov 18 10:01:53.947 INFO [stdout] test context::test::test_serialize_error ... ok
Nov 18 10:01:53.947 INFO [stdout] test grammar::test_comment ... ok
Nov 18 10:01:53.947 INFO [stdout] test directives::test::test_update_data_with_decorator ... ok
Nov 18 10:01:53.948 INFO [stdout] test context::test::test_this ... ok
Nov 18 10:01:53.948 INFO [stdout] test grammar::test_helper_end ... ok
Nov 18 10:01:53.949 INFO [stdout] test grammar::test_expression ... ok
Nov 18 10:01:53.949 INFO [stdout] test directives::test::test_local_helper_with_decorator ... ok
Nov 18 10:01:53.949 INFO [stdout] test grammar::test_directive_block ... ok
Nov 18 10:01:53.950 INFO [stdout] test directives::inline::test::test_inline ... ok
Nov 18 10:01:53.955 INFO [stdout] test grammar::test_helper_block ... ok
Nov 18 10:01:53.956 INFO [stdout] test grammar::test_html_expression ... ok
Nov 18 10:01:53.956 INFO [stdout] test grammar::test_identifier_with_dash ... ok
Nov 18 10:01:53.956 INFO [stdout] test grammar::test_directive_expression ... ok
Nov 18 10:01:53.956 INFO [stdout] test grammar::test_hash ... ok
Nov 18 10:01:53.957 INFO [stdout] test grammar::test_json_literal ... ok
Nov 18 10:01:53.957 INFO [stdout] test grammar::test_helper_expression ... ok
Nov 18 10:01:53.957 INFO [stdout] test grammar::test_raw_block ... ok
Nov 18 10:01:53.958 INFO [stdout] test grammar::test_helper_start ... ok
Nov 18 10:01:53.963 INFO [stdout] test grammar::test_raw_block_text ... ok
Nov 18 10:01:53.963 INFO [stdout] test grammar::test_raw_text ... ok
Nov 18 10:01:53.964 INFO [stdout] test grammar::test_path ... ok
Nov 18 10:01:53.964 INFO [stdout] test grammar::test_partial_expression ... ok
Nov 18 10:01:53.964 INFO [stdout] test grammar::test_name ... ok
Nov 18 10:01:53.965 INFO [stdout] test grammar::test_partial_block ... ok
Nov 18 10:01:53.965 INFO [stdout] test grammar::test_param ... ok
Nov 18 10:01:53.965 INFO [stdout] test grammar::test_reference ... ok
Nov 18 10:01:53.965 INFO [stdout] test grammar::test_subexpression ... ok
Nov 18 10:01:53.966 INFO [stdout] test helpers::helper_each::test::test_block_param ... ok
Nov 18 10:01:53.966 INFO [stdout] test helpers::helper_each::test::test_empty_key ... ok
Nov 18 10:01:53.967 INFO [stdout] test helpers::helper_each::test::test_key_iteration_with_unicode ... ok
Nov 18 10:01:53.967 INFO [stdout] test helpers::helper_each::test::test_each_else ... ok
Nov 18 10:01:53.967 INFO [stdout] test helpers::helper_boolean::test_conditions::foo ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_each::test::test_each_object_block_param ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_each::test::test_each_with_parent ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_each::test::test_nested_array ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_path_ups ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_each::test::test_nested_each ... ok
Nov 18 10:01:53.977 INFO [stdout] test helpers::helper_boolean::test_conditions::nested_conditions ... ok
Nov 18 10:01:53.990 INFO [stdout] test helpers::helper_each::test::test_each ... ok
Nov 18 10:01:53.991 INFO [stdout] test helpers::helper_if::test::test_if_context ... ok
Nov 18 10:01:53.991 INFO [stdout] test helpers::helper_if::test::test_if ... ok
Nov 18 10:01:53.991 INFO [stdout] test helpers::helper_raw::test::test_raw_helper ... ok
Nov 18 10:01:53.991 INFO [stdout] test helpers::helper_lookup::test::test_lookup ... ok
Nov 18 10:01:53.998 INFO [stdout] test helpers::helper_with::test::test_path_up ... ok
Nov 18 10:01:53.999 INFO [stdout] test helpers::helper_with::test::test_with ... ok
Nov 18 10:01:53.999 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_parent ... ok
Nov 18 10:01:53.999 INFO [stdout] test helpers::helper_each::test::test_nested_each_with_path_up_this ... ok
Nov 18 10:01:53.999 INFO [stdout] test helpers::helper_with::test::test_with_in_each ... ok
Nov 18 10:01:53.999 INFO [stdout] test helpers::test::test_meta_helper ... ok
Nov 18 10:01:53.999 INFO [stdout] test partial::test::test_issue_143 ... ok
Nov 18 10:01:53.999 INFO [stdout] test partial::test::test_include_partial_block ... ok
Nov 18 10:01:53.999 INFO [stdout] test partial::test::test_nested_partial_scope ... ok
Nov 18 10:01:53.999 INFO [stdout] test partial::test::test_self_inclusion ... ok
Nov 18 10:01:53.999 INFO [stdout] test partial::test::test_hash_context_outscope ... ok
Nov 18 10:01:54.006 INFO [stdout] test registry::test::test_render_to_write ... ok
Nov 18 10:01:54.007 INFO [stdout] test helpers::test::test_helper_for_subexpression ... ok
Nov 18 10:01:54.007 INFO [stdout] test registry::test::test_registry_operations ... ok
Nov 18 10:01:54.007 INFO [stdout] test helpers::helper_with::test::test_with_block_param ... ok
Nov 18 10:01:54.015 INFO [stdout] test partial::test::test ... ok
Nov 18 10:01:54.027 INFO [stdout] test registry::test::test_escape ... ok
Nov 18 10:01:54.027 INFO [stdout] test registry::test::test_register_templates_directory ... ok
Nov 18 10:01:54.035 INFO [stdout] test render::test_html_expression ... ok
Nov 18 10:01:54.035 INFO [stdout] test render::test_raw_string ... ok
Nov 18 10:01:54.035 INFO [stdout] test registry::test::test_escape_fn ... ok
Nov 18 10:01:54.039 INFO [stdout] test render::test_render_context_promotion_and_demotion ... ok
Nov 18 10:01:54.059 INFO [stdout] test render::test_render_subexpression_issue_115 ... ok
Nov 18 10:01:54.059 INFO [stdout] test render::test_render_subexpression ... ok
Nov 18 10:01:54.059 INFO [stdout] test render::test_expression ... ok
Nov 18 10:01:54.059 INFO [stdout] test registry::test::test_strict_mode ... ok
Nov 18 10:01:54.059 INFO [stdout] test render::test_partial_failback_render ... ok
Nov 18 10:01:54.063 INFO [stdout] test support::str::test::test_string_writer ... ok
Nov 18 10:01:54.071 INFO [stdout] test render::test_comment ... ok
Nov 18 10:01:54.071 INFO [stdout] test render::test_render_error_line_no ... ok
Nov 18 10:01:54.071 INFO [stdout] test render::test_template ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_literal_parameter_parser ... ok
Nov 18 10:01:54.071 INFO [stdout] test render::test_key_with_slash ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_directive ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_block_param ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_parse_error ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_parse_escaped_block_raw_string ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_raw_helper ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_parse_escaped_tag_raw_string ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_pure_backslash_raw_string ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_subexpression ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_unclosed_expression ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_parse_template ... ok
Nov 18 10:01:54.071 INFO [stdout] test template::test_template_mapping ... ok
Nov 18 10:01:54.071 INFO [stdout] test value::test_json_render ... ok
Nov 18 10:01:54.076 INFO [stdout] test template::test_white_space_omitter ... ok
Nov 18 10:01:54.076 INFO [stdout] test template::test_whitespace_elements ... ok
Nov 18 10:01:54.076 INFO [stdout] 
Nov 18 10:01:54.076 INFO [stdout] test result: ok. 97 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:01:54.077 INFO [stdout] 
Nov 18 10:01:54.099 INFO [stderr]      Running /target/debug/deps/escape-2f184e00b101f328
Nov 18 10:01:54.103 INFO [stdout] 
Nov 18 10:01:54.103 INFO [stdout] running 1 test
Nov 18 10:01:54.115 INFO [stdout] test test_escape_216 ... ok
Nov 18 10:01:54.115 INFO [stderr]      Running /target/debug/deps/helper_macro-377c23f6847633ec
Nov 18 10:01:54.116 INFO [stdout] 
Nov 18 10:01:54.116 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:01:54.116 INFO [stdout] 
Nov 18 10:01:54.123 INFO [stdout] 
Nov 18 10:01:54.123 INFO [stdout] running 1 test
Nov 18 10:01:54.135 INFO [stdout] test test_macro_helper ... ok
Nov 18 10:01:54.135 INFO [stderr]      Running /target/debug/deps/root_var-596471b0ea952d76
Nov 18 10:01:54.135 INFO [stdout] 
Nov 18 10:01:54.136 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:01:54.136 INFO [stdout] 
Nov 18 10:01:54.136 INFO [stdout] 
Nov 18 10:01:54.137 INFO [stdout] running 1 test
Nov 18 10:01:54.145 INFO [stdout] test test_root_var ... ok
Nov 18 10:01:54.146 INFO [stderr]      Running /target/debug/deps/subexpression-7d46db1f14954d5b
Nov 18 10:01:54.146 INFO [stdout] 
Nov 18 10:01:54.146 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:01:54.147 INFO [stdout] 
Nov 18 10:01:54.147 INFO [stdout] 
Nov 18 10:01:54.147 INFO [stdout] running 1 test
Nov 18 10:01:54.167 INFO [stdout] test test_subexpression ... ok
Nov 18 10:01:54.167 INFO [stderr]    Doc-tests handlebars
Nov 18 10:01:54.167 INFO [stdout] 
Nov 18 10:01:54.167 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:01:54.167 INFO [stdout] 
Nov 18 10:01:56.380 INFO [stdout] 
Nov 18 10:01:56.380 INFO [stdout] running 15 tests
Nov 18 10:02:10.788 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 47) ... ok
Nov 18 10:02:12.359 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 31) ... ok
Nov 18 10:02:16.344 INFO [stdout] test src/directives/mod.rs - directives::DirectiveDef (line 44) ... ok
Nov 18 10:02:17.835 INFO [stdout] test src/directives/mod.rs - directives::DirectiveDef (line 21) ... ok
Nov 18 10:02:21.667 INFO [stdout] test src/lib.rs -  (line 144) ... ok
Nov 18 10:02:21.987 INFO [stdout] test src/lib.rs -  (line 123) ... ok
Nov 18 10:02:25.515 INFO [stdout] test src/lib.rs -  (line 10) ... ok
Nov 18 10:02:27.232 INFO [stdout] test src/helpers/mod.rs - helpers::HelperDef (line 67) ... ok
Nov 18 10:02:39.863 INFO [stdout] test src/render.rs - render::Helper<'reg, 'rc>::hash_get (line 393) ... ok
Nov 18 10:02:41.523 INFO [stdout] test src/lib.rs -  (line 168) ... ok
Nov 18 10:02:41.763 INFO [stdout] test src/lib.rs -  (line 202) ... ok
Nov 18 10:02:42.419 INFO [stdout] test src/macros.rs - handlebars_helper (line 6) ... ok
Nov 18 10:02:42.681 INFO [stdout] test src/render.rs - render::Helper<'reg, 'rc>::param (line 366) ... ok
Nov 18 10:02:42.691 INFO [stdout] test src/lib.rs -  (line 237) ... ok
Nov 18 10:02:42.931 INFO [stdout] test src/lib.rs -  (line 85) ... ok
Nov 18 10:02:42.931 INFO [stdout] 
Nov 18 10:02:42.931 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 10:02:42.931 INFO [stdout] 
Nov 18 10:02:42.964 INFO [stderr] su: No module specific data is present
Nov 18 10:02:43.347 INFO running `"docker" "inspect" "0175d7e922cdd8a24b7916a30a04f324531c79bb5931448259ad4d19efc1233b"`
Nov 18 10:02:43.519 INFO running `"docker" "rm" "-f" "0175d7e922cdd8a24b7916a30a04f324531c79bb5931448259ad4d19efc1233b"`
Nov 18 10:02:43.686 INFO [stdout] 0175d7e922cdd8a24b7916a30a04f324531c79bb5931448259ad4d19efc1233b