[INFO] fetching crate axominijinja 2.4.0...
[INFO] testing axominijinja-2.4.0 against master#9e136a30a965bf4e63f03095c57df7257bf96fd6 for pr-134530
[INFO] extracting crate axominijinja 2.4.0 into /workspace/builds/worker-2-tc1/source
[INFO] validating manifest of crates.io crate axominijinja 2.4.0 on toolchain 9e136a30a965bf4e63f03095c57df7257bf96fd6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate axominijinja 2.4.0
[INFO] finished tweaking crates.io crate axominijinja 2.4.0
[INFO] tweaked toml for crates.io crate axominijinja 2.4.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 52 packages to latest compatible versions
[INFO] [stderr]       Adding indexmap v1.9.3 (available: v2.7.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded psm v0.1.24
[INFO] [stderr]   Downloaded similar-asserts v1.6.0
[INFO] [stderr]   Downloaded self_cell v1.1.0
[INFO] [stderr]   Downloaded stacker v0.1.17
[INFO] [stderr]   Downloaded v_htmlescape v0.15.8
[INFO] [stderr]   Downloaded memo-map v0.3.3
[INFO] [stderr]   Downloaded insta v1.41.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b818f6e5744aebcd53d669b18e132f20dc7f895b4d5af1dbbe25d03fd6ed7afb
[INFO] running `Command { std: "docker" "start" "-a" "b818f6e5744aebcd53d669b18e132f20dc7f895b4d5af1dbbe25d03fd6ed7afb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b818f6e5744aebcd53d669b18e132f20dc7f895b4d5af1dbbe25d03fd6ed7afb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b818f6e5744aebcd53d669b18e132f20dc7f895b4d5af1dbbe25d03fd6ed7afb", kill_on_drop: false }`
[INFO] [stdout] b818f6e5744aebcd53d669b18e132f20dc7f895b4d5af1dbbe25d03fd6ed7afb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cb7b9f0eaa61262721f3264b16c95321802f5b7fb6d76087dc267b4b0041b9cd
[INFO] running `Command { std: "docker" "start" "-a" "cb7b9f0eaa61262721f3264b16c95321802f5b7fb6d76087dc267b4b0041b9cd", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.216
[INFO] [stderr]    Compiling axominijinja v2.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.07s
[INFO] running `Command { std: "docker" "inspect" "cb7b9f0eaa61262721f3264b16c95321802f5b7fb6d76087dc267b4b0041b9cd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cb7b9f0eaa61262721f3264b16c95321802f5b7fb6d76087dc267b4b0041b9cd", kill_on_drop: false }`
[INFO] [stdout] cb7b9f0eaa61262721f3264b16c95321802f5b7fb6d76087dc267b4b0041b9cd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b91e33b384a07a2499c5468cd94c1e17a409ced602c57a5597a74cdba19da5fa
[INFO] running `Command { std: "docker" "start" "-a" "b91e33b384a07a2499c5468cd94c1e17a409ced602c57a5597a74cdba19da5fa", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-segmentation v1.12.0
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling bstr v1.11.1
[INFO] [stderr]    Compiling console v0.15.10
[INFO] [stderr]    Compiling serde v1.0.216
[INFO] [stderr]    Compiling similar v2.6.0
[INFO] [stderr]    Compiling similar-asserts v1.6.0
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling serde_json v1.0.133
[INFO] [stderr]    Compiling axominijinja v2.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling globset v0.4.15
[INFO] [stderr]    Compiling insta v1.41.1
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 17.48s
[INFO] running `Command { std: "docker" "inspect" "b91e33b384a07a2499c5468cd94c1e17a409ced602c57a5597a74cdba19da5fa", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b91e33b384a07a2499c5468cd94c1e17a409ced602c57a5597a74cdba19da5fa", kill_on_drop: false }`
[INFO] [stdout] b91e33b384a07a2499c5468cd94c1e17a409ced602c57a5597a74cdba19da5fa
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] fc5ae678d05a6de1206a074a4154b6219e6a2fbeb10d9c15105c051280425436
[INFO] running `Command { std: "docker" "start" "-a" "fc5ae678d05a6de1206a074a4154b6219e6a2fbeb10d9c15105c051280425436", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/minijinja-a6c3d695ac48e1fe)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test compiler::instructions::test_sizes ... ok
[INFO] [stdout] test compiler::lexer::tests::test_basic_identifiers ... ok
[INFO] [stdout] test utils::tests::test_splitn_whitespace ... ok
[INFO] [stdout] test defaults::unit_tests::test_default_uto_escape ... ok
[INFO] [stdout] test utils::tests::test_unescape ... ok
[INFO] [stdout] test compiler::lexer::tests::test_is_basic_tag ... 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 utils::tests::test_html_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::ops::tests::test_dividing ... ok
[INFO] [stdout] test value::ops::tests::test_concat ... 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_dynamic_object_roundtrip ... ok
[INFO] [stdout] test value::tests::test_sizes ... ok
[INFO] [stdout] test value::tests::test_string_char ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests minijinja
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::add_template (line 136) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_path_join_callback (line 276) ... ok
[INFO] [stdout] test src/error.rs - error::Error (line 22) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 74) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_auto_escape_callback (line 470) ... 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 521) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 35) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 54) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::set_unknown_method_callback (line 305) ... ok
[INFO] [stdout] test src/expression.rs - expression::Expression (line 29) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::templates (line 344) ... ok
[INFO] [stdout] test src/filters.rs - filters (line 96) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::get_template (line 369) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::template_from_named_str (line 386) ... ok
[INFO] [stdout] test src/filters.rs - filters::Filter (line 169) ... ok
[INFO] [stdout] test src/macros.rs - macros::args (line 197) ... ok
[INFO] [stdout] test src/functions.rs - functions::Function (line 142) ... ok
[INFO] [stdout] test src/filters.rs - filters::Filter (line 151) ... ok
[INFO] [stdout] test src/filters.rs - filters::Filter (line 187) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 100) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 61) ... ok
[INFO] [stdout] test src/functions.rs - functions::Function (line 164) ... ok
[INFO] [stdout] test src/lib.rs - (line 67) ... ok
[INFO] [stdout] test src/environment.rs - environment::Environment<'source>::render_named_str (line 423) ... ok
[INFO] [stdout] test src/functions.rs - functions (line 27) ... ok
[INFO] [stdout] test src/lib.rs - (line 85) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::eval_to_state (line 192) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 83) ... ok
[INFO] [stdout] test src/lib.rs - (line 43) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::undeclared_variables (line 244) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 288) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 711) ... ok
[INFO] [stdout] test src/macros.rs - macros::context (line 72) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 296) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render_and_return_state (line 127) ... ok
[INFO] [stdout] test src/macros.rs - macros::render (line 281) ... ok
[INFO] [stdout] test src/functions.rs - functions (line 51) ... ok
[INFO] [stdout] test src/tests.rs - tests::Test (line 117) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 747) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs (line 734) ... ok
[INFO] [stdout] test src/tests.rs - tests::Test (line 135) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render_to_write (line 159) ... ok
[INFO] [stdout] test src/tests.rs - tests (line 36) ... ok
[INFO] [stdout] test src/template.rs - template::Template<'env,'source>::render (line 102) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Kwargs::get (line 864) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::from_args (line 81) ... ok
[INFO] [stdout] test src/value/deserialize.rs - value::deserialize::ViaDeserialize (line 97) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::Rest (line 648) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 159) ... ok
[INFO] [stdout] test src/value/argtypes.rs - value::argtypes::from_args (line 66) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 100) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 91) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 137) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 47) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::call (line 1494) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_function (line 993) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_dyn_object (line 817) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 33) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::downcast_object_ref (line 1378) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 75) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 16) ... ok
[INFO] [stdout] test src/value/mod.rs - value (line 55) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_attr (line 1179) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_safe_string (line 768) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::call (line 1472) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_serialize (line 723) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Enumerator::Iter (line 518) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::try_iter (line 1259) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_one_shot_iterator (line 973) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_object_iterable (line 889) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::ObjectExt::mapped_enumerator (line 336) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::from_object (line 794) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::ObjectExt::mapped_rev_enumerator (line 401) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_item_by_index (line 1216) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::len (line 1158) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_item (line 1233) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 58) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::apply_filter (line 237) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 132) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::render_block (line 175) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 94) ... ok
[INFO] [stdout] test src/value/object.rs - value::object::Object (line 24) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::format (line 273) ... ok
[INFO] [stdout] test src/vm/state.rs - vm::state::State<'template,'env>::perform_test (line 255) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::make_iterable (line 865) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.61s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "fc5ae678d05a6de1206a074a4154b6219e6a2fbeb10d9c15105c051280425436", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fc5ae678d05a6de1206a074a4154b6219e6a2fbeb10d9c15105c051280425436", kill_on_drop: false }`
[INFO] [stdout] fc5ae678d05a6de1206a074a4154b6219e6a2fbeb10d9c15105c051280425436