[INFO] fetching crate pochoir-lang 0.12.2...
[INFO] testing pochoir-lang-0.12.2 against try#2440211fe03bc45c89b6dc1a3df18382ce91e32b for pr-146098-1
[INFO] extracting crate pochoir-lang 0.12.2 into /workspace/builds/worker-2-tc2/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-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate pochoir-lang 0.12.2 on toolchain 2440211fe03bc45c89b6dc1a3df18382ce91e32b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2ec2e724bd5d662cc5334e076e75f99f77f1a951c525829efe71355b8431d991
[INFO] running `Command { std: "docker" "start" "-a" "2ec2e724bd5d662cc5334e076e75f99f77f1a951c525829efe71355b8431d991", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2ec2e724bd5d662cc5334e076e75f99f77f1a951c525829efe71355b8431d991", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2ec2e724bd5d662cc5334e076e75f99f77f1a951c525829efe71355b8431d991", kill_on_drop: false }`
[INFO] [stdout] 2ec2e724bd5d662cc5334e076e75f99f77f1a951c525829efe71355b8431d991
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5970bf711de1688c894fb1012418231d826610c2ff23f1f885531bad764b2955
[INFO] running `Command { std: "docker" "start" "-a" "5970bf711de1688c894fb1012418231d826610c2ff23f1f885531bad764b2955", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.209
[INFO] [stderr]    Compiling deunicode v1.6.0
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling thiserror v1.0.63
[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 12.43s
[INFO] running `Command { std: "docker" "inspect" "5970bf711de1688c894fb1012418231d826610c2ff23f1f885531bad764b2955", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5970bf711de1688c894fb1012418231d826610c2ff23f1f885531bad764b2955", kill_on_drop: false }`
[INFO] [stdout] 5970bf711de1688c894fb1012418231d826610c2ff23f1f885531bad764b2955
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5074af6e08e6c1c275222607cec75504a4a264fb3e144d6d96cf0a90cd34236c
[INFO] running `Command { std: "docker" "start" "-a" "5074af6e08e6c1c275222607cec75504a4a264fb3e144d6d96cf0a90cd34236c", 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.42s
[INFO] running `Command { std: "docker" "inspect" "5074af6e08e6c1c275222607cec75504a4a264fb3e144d6d96cf0a90cd34236c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5074af6e08e6c1c275222607cec75504a4a264fb3e144d6d96cf0a90cd34236c", kill_on_drop: false }`
[INFO] [stdout] 5074af6e08e6c1c275222607cec75504a4a264fb3e144d6d96cf0a90cd34236c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9dc04745acd76bdd80777a9ef530f608521575e9691317d4f0de665759e41dd9
[INFO] running `Command { std: "docker" "start" "-a" "9dc04745acd76bdd80777a9ef530f608521575e9691317d4f0de665759e41dd9", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pochoir_lang-a524969fddbb317d)
[INFO] [stdout] 
[INFO] [stdout] running 74 tests
[INFO] [stdout] test functions::ends_with::tests::ends_with_test ... ok
[INFO] [stdout] test functions::capitalize::tests::capitalize_test ... ok
[INFO] [stdout] test functions::entries::tests::entries_test ... ok
[INFO] [stdout] test functions::first::tests::first_test ... ok
[INFO] [stdout] test functions::enumerate::tests::enumerate_test ... ok
[INFO] [stdout] test functions::last::tests::last_test ... ok
[INFO] [stdout] test functions::iter::tests::iter_test ... ok
[INFO] [stdout] test functions::len::tests::len_test ... ok
[INFO] [stdout] test functions::replace::tests::replace_test ... ok
[INFO] [stdout] test functions::reverse::tests::reverse_test ... ok
[INFO] [stdout] test functions::slugify::tests::slugify_test ... ok
[INFO] [stdout] test functions::round::tests::round_test ... ok
[INFO] [stdout] test functions::sort::tests::sort_test ... ok
[INFO] [stdout] test functions::starts_with::tests::starts_with_test ... ok
[INFO] [stdout] test functions::split::tests::split_test ... ok
[INFO] [stdout] test functions::reading_time::tests::reading_time_test ... ok
[INFO] [stdout] test functions::word_count::tests::word_count_test ... 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::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::take::tests::take_test ... ok
[INFO] [stdout] test functions::to_string::tests::to_string_test ... ok
[INFO] [stdout] test functions::trim::tests::trim_test ... ok
[INFO] [stdout] test tests::array_indexing ... ok
[INFO] [stdout] test tests::array_indexing_not_integer ... ok
[INFO] [stdout] test parser::tests::range ... ok
[INFO] [stdout] test parser::tests::mathematical_priority_test ... ok
[INFO] [stdout] test tests::boolean_not_operator ... ok
[INFO] [stdout] test tests::additions ... ok
[INFO] [stdout] test parser::tests::parse_number ... ok
[INFO] [stdout] test tests::chained_conditional ... ok
[INFO] [stdout] test tests::arithmetic_test ... ok
[INFO] [stdout] test tests::complex_function_call ... ok
[INFO] [stdout] test functions::skip::tests::skip_test ... ok
[INFO] [stdout] test tests::data ... ok
[INFO] [stdout] test tests::complex_test ... ok
[INFO] [stdout] test tests::chain_operators_test ... ok
[INFO] [stdout] test tests::comparison_operator_test ... ok
[INFO] [stdout] test tests::chain_functions_test ... ok
[INFO] [stdout] test tests::array_indexing_out_of_bounds ... ok
[INFO] [stdout] test tests::define_and_use_constant ... ok
[INFO] [stdout] test tests::define_constant_precedence ... ok
[INFO] [stdout] test tests::empty_array_indexing ... ok
[INFO] [stdout] test tests::doc_example ... ok
[INFO] [stdout] test tests::define_constant_with_expr_error ... ok
[INFO] [stdout] test tests::define_function_test ... ok
[INFO] [stdout] test tests::define_constant ... ok
[INFO] [stdout] test tests::define_returns_value ... ok
[INFO] [stdout] test tests::function_comparison ... ok
[INFO] [stdout] test tests::function_and_array_indexing ... ok
[INFO] [stdout] test tests::define_constant_update_context ... ok
[INFO] [stdout] test tests::function_comparison_type_error ... ok
[INFO] [stdout] test tests::function_with_optional_parameter ... ok
[INFO] [stdout] test tests::mathematical_priority_test ... ok
[INFO] [stdout] test tests::single_char_string_index ... ok
[INFO] [stdout] test tests::use_functions_test ... ok
[INFO] [stdout] test tests::use_function_argument_types_test ... ok
[INFO] [stdout] test tests::nested_array_indexing ... ok
[INFO] [stdout] test tests::indexing_by_function_and_array ... ok
[INFO] [stdout] test tests::object_array_indexing ... ok
[INFO] [stdout] test tests::objects_test ... ok
[INFO] [stdout] test tests::nested_fn_calls ... ok
[INFO] [stdout] test tests::nested_functions ... ok
[INFO] [stderr]    Doc-tests pochoir_lang
[INFO] [stdout] test tests::object_indexing_with_array_syntax ... ok
[INFO] [stdout] test tests::namespaced_function_field_access ... ok
[INFO] [stdout] test tests::use_variable_test ... ok
[INFO] [stdout] test tests::utf8 ... ok
[INFO] [stdout] test value::object::tests::deserialize_object ... ok
[INFO] [stdout] test tests::use_ranges ... ok
[INFO] [stdout] test value::tests::custom_structure ... ok
[INFO] [stdout] test tests::unary_group ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 74 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[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/lib.rs - (line 83) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::type_name (line 499) ... ok
[INFO] [stdout] test src/lib.rs - (line 122) ... ok
[INFO] [stdout] test src/value/mod.rs - value::serialize_to_value (line 67) ... ok
[INFO] [stdout] test src/lib.rs - (line 138) ... ok
[INFO] [stdout] test src/lib.rs - (line 98) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::index (line 205) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get_mut (line 171) ... ok
[INFO] [stdout] test src/value/mod.rs - value::Value::get (line 139) ... ok
[INFO] [stdout] test src/value/mod.rs - value::deserialize_from_value (line 30) ... ok
[INFO] [stdout] test src/context.rs - context::Context::from_object (line 109) ... ok
[INFO] [stdout] test src/value/mod.rs - value::FromValue (line 614) ... 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] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 1.77s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "9dc04745acd76bdd80777a9ef530f608521575e9691317d4f0de665759e41dd9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9dc04745acd76bdd80777a9ef530f608521575e9691317d4f0de665759e41dd9", kill_on_drop: false }`
[INFO] [stdout] 9dc04745acd76bdd80777a9ef530f608521575e9691317d4f0de665759e41dd9