[INFO] fetching crate minijinja 2.12.0...
[INFO] testing minijinja-2.12.0 against try#b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b for pr-145330-1
[INFO] extracting crate minijinja 2.12.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate minijinja 2.12.0
[INFO] finished tweaking crates.io crate minijinja 2.12.0
[INFO] tweaked toml for crates.io crate minijinja 2.12.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate minijinja 2.12.0 on toolchain b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate minijinja 2.12.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] aa96d88a297819061eae769343dc7ab4b3f96a5ead869c5bf3df26849b5aed9a
[INFO] running `Command { std: "docker" "start" "-a" "aa96d88a297819061eae769343dc7ab4b3f96a5ead869c5bf3df26849b5aed9a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aa96d88a297819061eae769343dc7ab4b3f96a5ead869c5bf3df26849b5aed9a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aa96d88a297819061eae769343dc7ab4b3f96a5ead869c5bf3df26849b5aed9a", kill_on_drop: false }`
[INFO] [stdout] aa96d88a297819061eae769343dc7ab4b3f96a5ead869c5bf3df26849b5aed9a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a48819c9f57ca17401feb089e046889c949a7ba2ca572a3adee119a1e14cc3ff
[INFO] running `Command { std: "docker" "start" "-a" "a48819c9f57ca17401feb089e046889c949a7ba2ca572a3adee119a1e14cc3ff", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling minijinja v2.12.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.57s
[INFO] running `Command { std: "docker" "inspect" "a48819c9f57ca17401feb089e046889c949a7ba2ca572a3adee119a1e14cc3ff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a48819c9f57ca17401feb089e046889c949a7ba2ca572a3adee119a1e14cc3ff", kill_on_drop: false }`
[INFO] [stdout] a48819c9f57ca17401feb089e046889c949a7ba2ca572a3adee119a1e14cc3ff
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a2cfe8ac8b65590c13587e29b434f9a164d65a073271b882ffcf5ed15a28819c
[INFO] running `Command { std: "docker" "start" "-a" "a2cfe8ac8b65590c13587e29b434f9a164d65a073271b882ffcf5ed15a28819c", kill_on_drop: false }`
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling bstr v1.12.0
[INFO] [stderr]    Compiling syn v2.0.101
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling similar-asserts v1.7.0
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling globset v0.4.16
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling insta v1.43.1
[INFO] [stderr]    Compiling minijinja v2.12.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 19.95s
[INFO] running `Command { std: "docker" "inspect" "a2cfe8ac8b65590c13587e29b434f9a164d65a073271b882ffcf5ed15a28819c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a2cfe8ac8b65590c13587e29b434f9a164d65a073271b882ffcf5ed15a28819c", kill_on_drop: false }`
[INFO] [stdout] a2cfe8ac8b65590c13587e29b434f9a164d65a073271b882ffcf5ed15a28819c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a5da5bb30d0c5cdb286a4b5fd01da579c1a24f2adc2e3179e0c338ed4bd9f8d8
[INFO] running `Command { std: "docker" "start" "-a" "a5da5bb30d0c5cdb286a4b5fd01da579c1a24f2adc2e3179e0c338ed4bd9f8d8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/minijinja-23a8ede3e3bc1b7c)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test utils::tests::test_html_escape ... ok
[INFO] [stdout] test utils::tests::test_splitn_whitespace ... ok
[INFO] [stdout] test value::argtypes::tests::test_as_f64 ... ok
[INFO] [stdout] test value::argtypes::tests::test_kwargs_fails_string_conversion ... ok
[INFO] [stdout] test compiler::instructions::test_sizes ... ok
[INFO] [stdout] test utils::tests::test_unescape ... ok
[INFO] [stdout] test compiler::lexer::tests::test_is_basic_tag ... ok
[INFO] [stdout] test compiler::lexer::tests::test_basic_identifiers ... ok
[INFO] [stdout] test defaults::unit_tests::test_default_uto_escape ... ok
[INFO] [stdout] test value::argtypes::tests::test_optional_none ... ok
[INFO] [stdout] test value::argtypes::tests::test_split_kwargs ... ok
[INFO] [stdout] test value::ops::tests::test_adding ... ok
[INFO] [stdout] test value::merge_object::test_merge_object ... ok
[INFO] [stdout] test value::ops::tests::test_concat ... ok
[INFO] [stdout] test value::ops::tests::test_bytes_slicing ... ok
[INFO] [stdout] test value::ops::tests::test_dividing ... ok
[INFO] [stdout] test value::ops::tests::test_neg ... ok
[INFO] [stdout] test value::ops::tests::test_subtracting ... ok
[INFO] [stdout] test value::tests::test_string_char ... ok
[INFO] [stdout] test value::tests::test_sizes ... ok
[INFO] [stdout] test value::ops::tests::test_slicing ... ok
[INFO] [stderr]    Doc-tests minijinja
[INFO] [stdout] test value::ops::tests::test_string_slicing ... ok
[INFO] [stdout] test value::tests::test_dynamic_object_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 87 tests
[INFO] [stdout] test src/filters.rs - filters (line 75) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 55) ... ok
[INFO] [stdout] test src/error.rs - error::Error (line 22) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::get_template (line 377) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::add_template (line 145) ... ok
[INFO] [stdout] test src/error.rs - error::Error::range (line 293) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_formatter (line 529) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_unknown_method_callback (line 313) ... ok
[INFO] [stdout] test src/expression.rs - expression::Expression (line 29) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 35) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_path_join_callback (line 284) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::template_from_named_str (line 394) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_auto_escape_callback (line 478) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::render_named_str (line 431) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 97) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::templates (line 352) ... ok
[INFO] [stdout] test src/macros.rs - macros::args (line 191) ... ok
[INFO] [stdout] test src/functions.rs - functions::Function (line 167) ... ok
[INFO] [stdout] test src/functions.rs - functions::Function (line 185) ... ok
[INFO] [stdout] test src/functions.rs - functions (line 27) ... ok
[INFO] [stdout] test src/functions.rs - functions::Function (line 145) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 282) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 94) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 275) ... ok
[INFO] [stdout] test src/lib.rs - (line 85) ... ok
[INFO] [stdout] test src/functions.rs - functions (line 51) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 55) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::eval_to_state (line 189) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::undeclared_variables (line 241) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 703) ... ok
[INFO] [stdout] test src/tests.rs - tests (line 36) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 66) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 739) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render_to_write (line 159) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render (line 102) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 290) ... ok
[INFO] [stdout] test src/lib.rs - (line 67) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 77) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render_and_return_state (line 127) ... ok
[INFO] [stdout] test src/lib.rs - (line 43) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 137) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs::get (line 853) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::from_args (line 79) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Rest (line 640) ... ok
[INFO] [stdout] test src/value/merge_object.rs - value::merge_object::merge_maps (line 110) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 159) ... ok
[INFO] [stdout] test src/value/deserialize.rs - value::deserialize::ViaDeserialize (line 97) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 55) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 726) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::from_args (line 64) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 33) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 100) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 16) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 47) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_dyn_object (line 816) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 75) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::call (line 1537) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::downcast_object_ref (line 1443) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_function (line 1053) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_object (line 793) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::call (line 1559) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 91) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_attr (line 1244) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_object_map (line 943) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Enumerator::Iter (line 532) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::len (line 1223) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::try_iter (line 1324) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_serialize (line 723) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_iterable (line 864) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_safe_string (line 767) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_item (line 1298) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_item_by_index (line 1281) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::ObjectExt::mapped_enumerator (line 373) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_one_shot_iterator (line 1033) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::ObjectExt::mapped_rev_enumerator (line 413) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 26) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 96) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_object_iterable (line 888) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 60) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::apply_filter (line 250) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object::custom_cmp (line 265) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::get_or_set_temp_object (line 362) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::render_block (line 176) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::perform_test (line 268) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 134) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::get_temp (line 329) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::format (line 286) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 87 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.46s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a5da5bb30d0c5cdb286a4b5fd01da579c1a24f2adc2e3179e0c338ed4bd9f8d8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a5da5bb30d0c5cdb286a4b5fd01da579c1a24f2adc2e3179e0c338ed4bd9f8d8", kill_on_drop: false }`
[INFO] [stdout] a5da5bb30d0c5cdb286a4b5fd01da579c1a24f2adc2e3179e0c338ed4bd9f8d8