[INFO] fetching crate css_math 1.0.0...
[INFO] testing css_math-1.0.0 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1
[INFO] extracting crate css_math 1.0.0 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate css_math 1.0.0
[INFO] finished tweaking crates.io crate css_math 1.0.0
[INFO] tweaked toml for crates.io crate css_math 1.0.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate css_math 1.0.0 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 9 packages to latest compatible versions
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c01e28195d3147bbfeb1dd025fc91c3d30d92cf4645128b5071ff4933c5e30ed
[INFO] running `Command { std: "docker" "start" "-a" "c01e28195d3147bbfeb1dd025fc91c3d30d92cf4645128b5071ff4933c5e30ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c01e28195d3147bbfeb1dd025fc91c3d30d92cf4645128b5071ff4933c5e30ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c01e28195d3147bbfeb1dd025fc91c3d30d92cf4645128b5071ff4933c5e30ed", kill_on_drop: false }`
[INFO] [stdout] c01e28195d3147bbfeb1dd025fc91c3d30d92cf4645128b5071ff4933c5e30ed
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f3d74e69023ceb39f9d491c72e29072a9a03aff9f73157620baa5caee88f34ca
[INFO] running `Command { std: "docker" "start" "-a" "f3d74e69023ceb39f9d491c72e29072a9a03aff9f73157620baa5caee88f34ca", kill_on_drop: false }`
[INFO] [stderr]    Compiling css_math v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.25s
[INFO] running `Command { std: "docker" "inspect" "f3d74e69023ceb39f9d491c72e29072a9a03aff9f73157620baa5caee88f34ca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f3d74e69023ceb39f9d491c72e29072a9a03aff9f73157620baa5caee88f34ca", kill_on_drop: false }`
[INFO] [stdout] f3d74e69023ceb39f9d491c72e29072a9a03aff9f73157620baa5caee88f34ca
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3d3a8ee8ecfcdb615e21242e2551382442cf3dfc1c1a065c318d677874764304
[INFO] running `Command { std: "docker" "start" "-a" "3d3a8ee8ecfcdb615e21242e2551382442cf3dfc1c1a065c318d677874764304", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling cfg-if v1.0.3
[INFO] [stderr]    Compiling matches v0.1.10
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling test-case-core v3.3.1
[INFO] [stderr]    Compiling test-case-macros v3.3.1
[INFO] [stderr]    Compiling test-case v3.3.1
[INFO] [stderr]    Compiling css_math v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.08s
[INFO] running `Command { std: "docker" "inspect" "3d3a8ee8ecfcdb615e21242e2551382442cf3dfc1c1a065c318d677874764304", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3d3a8ee8ecfcdb615e21242e2551382442cf3dfc1c1a065c318d677874764304", kill_on_drop: false }`
[INFO] [stdout] 3d3a8ee8ecfcdb615e21242e2551382442cf3dfc1c1a065c318d677874764304
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] de955e707cb47bb71348677bf1b334cc2734327ec972005332a3c0b0432cad90
[INFO] running `Command { std: "docker" "start" "-a" "de955e707cb47bb71348677bf1b334cc2734327ec972005332a3c0b0432cad90", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stdout] 
[INFO] [stdout] running 72 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/css_math-435b8e19f321caea)
[INFO] [stdout] test tests::invalid::consecutive_units_without_a_number_2 ... ok
[INFO] [stdout] test tests::invalid::consecutive_units_without_a_number ... ok
[INFO] [stdout] test tests::invalid::consecutive_units_without_a_number_3 ... ok
[INFO] [stdout] test tests::invalid::invalid_number ... ok
[INFO] [stdout] test tests::valid::_var_typescale_3_1_5_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_invalid_variable::missing_closing_paren ... ok
[INFO] [stdout] test tokenize::tests::test_output::_10_1 ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_invalid_variable::missing_hyphens_in_variable_name ... ok
[INFO] [stdout] test tokenize::tests::test_output::_2_10ch_4px ... ok
[INFO] [stdout] test tokenize::tests::test_output::_400_23_1 ... ok
[INFO] [stdout] test tokenize::tests::test_output::_5_10px ... ok
[INFO] [stdout] test tokenize::tests::test_output::_5vh_10px_100_ ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_invalid_variable::incorrect_hyphenation ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_left_paren ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_100_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_10_5_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_10_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_1_12_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_1_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_operator::plus ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_ch_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_px_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_rem_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unrecognized_character::ampersand ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unrecognized_character::at ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unrecognized_character::bracket_right ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_variable::_var_color_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_5_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_variable::_var_gutter_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_60_0001_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_number::_6_2_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_operator::divide ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_operator::multiply ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_operator::subtract ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_right_paren ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_cw_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_em_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_vh_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unit::_vw_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unrecognized_character::bracket_left ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_unrecognized_character::chevron ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_variable::_var_ref_purple_100_expects ... ok
[INFO] [stdout] test tokenize::tokenizer::tests::handles_variable::_var_typescale_1_expects ... ok
[INFO] [stdout] test validate::validator::test::invalid::unit_before_number ... ok
[INFO] [stdout] test validate::validator::test::invalid::division_by_zero ... ok
[INFO] [stdout] test validate::validator::test::invalid::division_by_zero_in_nested_expression ... ok
[INFO] [stdout] test validate::validator::test::invalid::division_of_variable_by_unit ... ok
[INFO] [stdout] test validate::validator::test::invalid::incomplete_expression ... ok
[INFO] [stdout] test validate::validator::test::invalid::invalid_operator_at_the_start_of_nested_expression ... ok
[INFO] [stdout] test validate::validator::test::invalid::divide_px_by_px ... ok
[INFO] [stdout] test validate::validator::test::invalid::missing_closing_parenthesis ... ok
[INFO] [stdout] test validate::validator::test::invalid::missing_operator_before_a_number ... ok
[INFO] [stdout] test validate::validator::test::invalid::multiplication_with_two_units ... ok
[INFO] [stdout] test validate::validator::test::invalid::multiply_percent_by_percent ... ok
[INFO] [stdout] test validate::validator::test::invalid::nested_multiplication_without_unit_in_inner_expression ... ok
[INFO] [stdout] test validate::validator::test::invalid::operator_at_the_start ... ok
[INFO] [stdout] test validate::validator::test::invalid::operator_at_the_start_of_nested_expression ... ok
[INFO] [stdout] test validate::validator::test::invalid::unit_mismatch_in_division_after_nested_expression ... ok
[INFO] [stdout] test validate::validator::test::valid::add_px_to_em ... ok
[INFO] [stdout] test validate::validator::test::valid::complex_nested_operation_with_variable ... ok
[INFO] [stdout] test validate::validator::test::valid::division_and_subtraction_with_no_parentheses ... ok
[INFO] [stdout] test validate::validator::test::valid::divide_vh_by_number ... ok
[INFO] [stdout] test validate::validator::test::valid::multiply_percent_by_number ... ok
[INFO] [stdout] test validate::validator::test::valid::double_nested_add_and_subtract_then_multiply ... ok
[INFO] [stdout] test validate::validator::test::valid::subtract_px_from_percent ... ok
[INFO] [stdout] test validate::validator::test::valid::nested_division_and_addition ... ok
[INFO] [stdout] test validate::validator::test::valid::multiply_variable_by_number ... ok
[INFO] [stdout] test validate::validator::test::valid::nested_operation_with_subtraction ... ok
[INFO] [stdout] test validate::validator::test::valid::nested_operation_with_subtraction_and_multiplication ... ok
[INFO] [stdout] test validate::validator::test::valid::subtract_with_nested_add ... ok
[INFO] [stdout] test validate::validator::test::valid::subtract_variable_from_percentage ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 72 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests css_math
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "de955e707cb47bb71348677bf1b334cc2734327ec972005332a3c0b0432cad90", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "de955e707cb47bb71348677bf1b334cc2734327ec972005332a3c0b0432cad90", kill_on_drop: false }`
[INFO] [stdout] de955e707cb47bb71348677bf1b334cc2734327ec972005332a3c0b0432cad90