[INFO] fetching crate pochoir-lang 0.12.2...
[INFO] testing pochoir-lang-0.12.2 against 1.90.0 for beta-1.91-3
[INFO] extracting crate pochoir-lang 0.12.2 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate pochoir-lang 0.12.2
[INFO] finished tweaking crates.io crate pochoir-lang 0.12.2
[INFO] tweaked toml for crates.io crate pochoir-lang 0.12.2 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate pochoir-lang 0.12.2 on toolchain 1.90.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate pochoir-lang 0.12.2 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" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded slug v0.1.6
[INFO] [stderr]   Downloaded thiserror v1.0.63
[INFO] [stderr]   Downloaded thiserror-impl v1.0.63
[INFO] [stderr]   Downloaded pochoir-common v0.12.2
[INFO] [stderr]   Downloaded pochoir-macros v0.12.2
[INFO] [stderr]   Downloaded serde_derive v1.0.209
[INFO] [stderr]   Downloaded serde v1.0.209
[INFO] [stderr]   Downloaded indexmap v2.5.0
[INFO] [stderr]   Downloaded deunicode v1.6.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d0999e88c4fce19cc3a5a1de52508ae1410a13f848d59adceecb720d953296da
[INFO] running `Command { std: "docker" "start" "-a" "d0999e88c4fce19cc3a5a1de52508ae1410a13f848d59adceecb720d953296da", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d0999e88c4fce19cc3a5a1de52508ae1410a13f848d59adceecb720d953296da", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d0999e88c4fce19cc3a5a1de52508ae1410a13f848d59adceecb720d953296da", kill_on_drop: false }`
[INFO] [stdout] d0999e88c4fce19cc3a5a1de52508ae1410a13f848d59adceecb720d953296da
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b365f7514aec7520c6486cc12c92f93a42b98fe3bfca4efeaee1f2f077bb3011
[INFO] running `Command { std: "docker" "start" "-a" "b365f7514aec7520c6486cc12c92f93a42b98fe3bfca4efeaee1f2f077bb3011", kill_on_drop: false }`
[INFO] [stderr]    Compiling thiserror v1.0.63
[INFO] [stderr]    Compiling serde v1.0.209
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling deunicode v1.6.0
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling syn v2.0.87
[INFO] [stderr]    Compiling slug v0.1.6
[INFO] [stderr]    Compiling thiserror-impl v1.0.63
[INFO] [stderr]    Compiling serde_derive v1.0.209
[INFO] [stderr]    Compiling pochoir-macros v0.12.2
[INFO] [stderr]    Compiling pochoir-common v0.12.2
[INFO] [stderr]    Compiling indexmap v2.5.0
[INFO] [stderr]    Compiling pochoir-lang v0.12.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.67s
[INFO] running `Command { std: "docker" "inspect" "b365f7514aec7520c6486cc12c92f93a42b98fe3bfca4efeaee1f2f077bb3011", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b365f7514aec7520c6486cc12c92f93a42b98fe3bfca4efeaee1f2f077bb3011", kill_on_drop: false }`
[INFO] [stdout] b365f7514aec7520c6486cc12c92f93a42b98fe3bfca4efeaee1f2f077bb3011
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 98925f6aefe73936228c6a15e2e70982895c41b19a5501654edac3dfe4e80bed
[INFO] running `Command { std: "docker" "start" "-a" "98925f6aefe73936228c6a15e2e70982895c41b19a5501654edac3dfe4e80bed", kill_on_drop: false }`
[INFO] [stderr]    Compiling yansi v0.5.1
[INFO] [stderr]    Compiling pretty_assertions v1.4.0
[INFO] [stderr]    Compiling pochoir-lang v0.12.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.88s
[INFO] running `Command { std: "docker" "inspect" "98925f6aefe73936228c6a15e2e70982895c41b19a5501654edac3dfe4e80bed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "98925f6aefe73936228c6a15e2e70982895c41b19a5501654edac3dfe4e80bed", kill_on_drop: false }`
[INFO] [stdout] 98925f6aefe73936228c6a15e2e70982895c41b19a5501654edac3dfe4e80bed
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.90.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0cbee93b61a12dfae28ee2b82148ae2132adde8fa8e2da88169663fa37cd757a
[INFO] running `Command { std: "docker" "start" "-a" "0cbee93b61a12dfae28ee2b82148ae2132adde8fa8e2da88169663fa37cd757a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pochoir_lang-02c6428d07f66422)
[INFO] [stdout] 
[INFO] [stdout] running 74 tests
[INFO] [stdout] test functions::ends_with::tests::ends_with_test ... ok
[INFO] [stdout] test functions::entries::tests::entries_test ... ok
[INFO] [stdout] test functions::last::tests::last_test ... ok
[INFO] [stdout] test functions::len::tests::len_test ... ok
[INFO] [stdout] test functions::first::tests::first_test ... ok
[INFO] [stdout] test functions::iter::tests::iter_test ... ok
[INFO] [stdout] test functions::capitalize::tests::capitalize_test ... ok
[INFO] [stdout] test functions::enumerate::tests::enumerate_test ... ok
[INFO] [stdout] test functions::reading_time::tests::reading_time_test ... ok
[INFO] [stdout] test functions::slugify::tests::slugify_test ... ok
[INFO] [stdout] test functions::sort::tests::sort_test ... ok
[INFO] [stdout] test functions::reverse::tests::reverse_test ... ok
[INFO] [stdout] test functions::round::tests::round_test ... ok
[INFO] [stdout] test functions::skip::tests::skip_test ... ok
[INFO] [stdout] test functions::starts_with::tests::starts_with_test ... ok
[INFO] [stdout] test functions::word_count::tests::word_count_test ... ok
[INFO] [stdout] test parser::tests::function_args_trailing_comma ... ok
[INFO] [stdout] test parser::tests::namespaced_function ... ok
[INFO] [stdout] test parser::tests::object_newline ... ok
[INFO] [stdout] test functions::to_string::tests::to_string_test ... ok
[INFO] [stdout] test parser::tests::parse_number ... ok
[INFO] [stdout] test parser::tests::array_indexing ... ok
[INFO] [stdout] test parser::tests::array_newline ... ok
[INFO] [stdout] test parser::tests::function_args_newline ... ok
[INFO] [stdout] test parser::tests::range ... ok
[INFO] [stdout] test tests::additions ... ok
[INFO] [stdout] test functions::take::tests::take_test ... ok
[INFO] [stdout] test parser::tests::mathematical_priority_test ... ok
[INFO] [stdout] test tests::array_indexing_not_integer ... ok
[INFO] [stdout] test tests::array_indexing ... ok
[INFO] [stdout] test tests::chained_conditional ... ok
[INFO] [stdout] test tests::array_indexing_out_of_bounds ... ok
[INFO] [stdout] test tests::boolean_not_operator ... ok
[INFO] [stdout] test tests::chain_functions_test ... ok
[INFO] [stdout] test functions::trim::tests::trim_test ... ok
[INFO] [stdout] test functions::replace::tests::replace_test ... ok
[INFO] [stdout] test tests::complex_function_call ... ok
[INFO] [stdout] test tests::comparison_operator_test ... ok
[INFO] [stdout] test tests::arithmetic_test ... ok
[INFO] [stdout] test tests::chain_operators_test ... ok
[INFO] [stdout] test tests::data ... ok
[INFO] [stdout] test tests::define_constant_update_context ... ok
[INFO] [stdout] test tests::complex_test ... ok
[INFO] [stdout] test tests::function_with_optional_parameter ... ok
[INFO] [stdout] test tests::define_and_use_constant ... ok
[INFO] [stdout] test tests::indexing_by_function_and_array ... ok
[INFO] [stdout] test tests::define_constant ... ok
[INFO] [stdout] test tests::doc_example ... ok
[INFO] [stdout] test tests::empty_array_indexing ... ok
[INFO] [stdout] test tests::define_constant_precedence ... ok
[INFO] [stdout] test tests::define_constant_with_expr_error ... ok
[INFO] [stdout] test tests::function_and_array_indexing ... ok
[INFO] [stdout] test tests::define_function_test ... ok
[INFO] [stdout] test tests::function_comparison ... ok
[INFO] [stdout] test tests::function_comparison_type_error ... ok
[INFO] [stdout] test tests::mathematical_priority_test ... ok
[INFO] [stdout] test tests::nested_functions ... ok
[INFO] [stdout] test tests::namespaced_function_field_access ... ok
[INFO] [stdout] test tests::utf8 ... ok
[INFO] [stdout] test tests::object_array_indexing ... ok
[INFO] [stdout] test tests::nested_array_indexing ... ok
[INFO] [stdout] test value::tests::custom_structure ... ok
[INFO] [stdout] test tests::objects_test ... ok
[INFO] [stdout] test tests::nested_fn_calls ... ok
[INFO] [stdout] test tests::object_indexing_with_array_syntax ... ok
[INFO] [stdout] test tests::single_char_string_index ... ok
[INFO] [stdout] test tests::unary_group ... ok
[INFO] [stdout] test value::object::tests::deserialize_object ... ok
[INFO] [stdout] test tests::use_function_argument_types_test ... ok
[INFO] [stdout] test tests::use_functions_test ... ok
[INFO] [stdout] test tests::use_ranges ... ok
[INFO] [stdout] test functions::split::tests::split_test ... ok
[INFO] [stderr]    Doc-tests pochoir_lang
[INFO] [stdout] test tests::use_variable_test ... ok
[INFO] [stdout] test tests::define_returns_value ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 74 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test src/context.rs - context::Context::from_serialize (line 68) ... ignored
[INFO] [stdout] test src/functions/mod.rs - functions (line 14) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_mut (line 171) ... ok
[INFO] [stdout] test src/lib.rs - (line 98) ... ok
[INFO] [stdout] test src/lib.rs - (line 83) ... ok
[INFO] [stdout] test src/context.rs - context::Context::from_object (line 109) ... ok
[INFO] [stdout] test src/value/mod.rs - value::deserialize_from_value (line 30) ... ok
[INFO] [stdout] test src/lib.rs - (line 122) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::index (line 205) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::type_name (line 499) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::index_mut (line 233) ... ok
[INFO] [stdout] test src/value/mod.rs - value::IntoValue (line 666) ... ok
[INFO] [stdout] test src/lib.rs - (line 138) ... ok
[INFO] [stdout] test src/value/mod.rs - value::FromValue (line 614) ... ok
[INFO] [stdout] test src/value/mod.rs - value::serialize_to_value (line 67) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get (line 139) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.80s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0cbee93b61a12dfae28ee2b82148ae2132adde8fa8e2da88169663fa37cd757a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0cbee93b61a12dfae28ee2b82148ae2132adde8fa8e2da88169663fa37cd757a", kill_on_drop: false }`
[INFO] [stdout] 0cbee93b61a12dfae28ee2b82148ae2132adde8fa8e2da88169663fa37cd757a