[INFO] cloning repository https://github.com/wolfgang/tbac
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/wolfgang/tbac" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwolfgang%2Ftbac", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwolfgang%2Ftbac'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d5766fe26d8064c8f4b048471c68fda6d916574f
[INFO] testing wolfgang/tbac against master#432e145bd5a974c5b6f4dd9b352891bd7502b69d for pr-87041
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwolfgang%2Ftbac" "/workspace/builds/worker-11/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-11/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/wolfgang/tbac on toolchain 432e145bd5a974c5b6f4dd9b352891bd7502b69d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/wolfgang/tbac
[INFO] finished tweaking git repo https://github.com/wolfgang/tbac
[INFO] tweaked toml for git repo https://github.com/wolfgang/tbac written to /workspace/builds/worker-11/source/Cargo.toml
[INFO] crate git repo https://github.com/wolfgang/tbac already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8ba3c6ba0537202f0661e306301941c815bd70e6b0fb6a677dcb4d722224b172
[INFO] running `Command { std: "docker" "start" "-a" "8ba3c6ba0537202f0661e306301941c815bd70e6b0fb6a677dcb4d722224b172", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8ba3c6ba0537202f0661e306301941c815bd70e6b0fb6a677dcb4d722224b172", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ba3c6ba0537202f0661e306301941c815bd70e6b0fb6a677dcb4d722224b172", kill_on_drop: false }`
[INFO] [stdout] 8ba3c6ba0537202f0661e306301941c815bd70e6b0fb6a677dcb4d722224b172
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 105c87a775a2cc5caf5e7a93718324ad52b921897f9f1dfffb4655deb9570e88
[INFO] running `Command { std: "docker" "start" "-a" "105c87a775a2cc5caf5e7a93718324ad52b921897f9f1dfffb4655deb9570e88", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling memchr v2.3.0
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling regex-syntax v0.6.13
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling aho-corasick v0.7.6
[INFO] [stderr]    Compiling regex v1.3.3
[INFO] [stderr]    Compiling tbac v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.38s
[INFO] running `Command { std: "docker" "inspect" "105c87a775a2cc5caf5e7a93718324ad52b921897f9f1dfffb4655deb9570e88", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "105c87a775a2cc5caf5e7a93718324ad52b921897f9f1dfffb4655deb9570e88", kill_on_drop: false }`
[INFO] [stdout] 105c87a775a2cc5caf5e7a93718324ad52b921897f9f1dfffb4655deb9570e88
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0323f92dc22985323e8d051a605ed6515b098db15a01892b7ea3e6b2b7679f3a
[INFO] running `Command { std: "docker" "start" "-a" "0323f92dc22985323e8d051a605ed6515b098db15a01892b7ea3e6b2b7679f3a", kill_on_drop: false }`
[INFO] [stderr]    Compiling tbac v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.42s
[INFO] running `Command { std: "docker" "inspect" "0323f92dc22985323e8d051a605ed6515b098db15a01892b7ea3e6b2b7679f3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0323f92dc22985323e8d051a605ed6515b098db15a01892b7ea3e6b2b7679f3a", kill_on_drop: false }`
[INFO] [stdout] 0323f92dc22985323e8d051a605ed6515b098db15a01892b7ea3e6b2b7679f3a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] b5ea2e75ea821ba1d8340eb6bd0178217572d884140b12cad84981228dd80785
[INFO] running `Command { std: "docker" "start" "-a" "b5ea2e75ea821ba1d8340eb6bd0178217572d884140b12cad84981228dd80785", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/tbac-52103c5b3d33c1c3)
[INFO] [stdout] 
[INFO] [stdout] running 64 tests
[INFO] [stdout] test _tests::parser::nodes::expression_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::returns_error_if_parsing_goes_wrong ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_if_statement_with_expressions ... ok
[INFO] [stdout] test _tests::parser::nodes::goto_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::parser::nodes::ifnode_test::eval ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::returns_error_if_tokenizing_goes_wrong ... ok
[INFO] [stdout] test _tests::parser::nodes::let_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::parser::nodes::number_node_test::eval ... ok
[INFO] [stdout] test _tests::parser::nodes::print_node_test::construct_and_eval_with_param_nodes ... ok
[INFO] [stdout] test _tests::parser::nodes::sequence_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::parser::nodes::print_node_test::eval ... ok
[INFO] [stdout] test _tests::parser::nodes::string_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::parser::nodes::var_node_test::construct_and_eval ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::parses_print_with_vars ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_binary_expressions_with_left_var_in_let ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_expression_with_factor ... ok
[INFO] [stdout] test _tests::parser::parse_goto_test::parses_goto_statement ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::return_error_if_print_has_no_argument ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::return_error_if_print_has_non_string_argument ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::parses_with_single_string_parameter ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::parses_with_multiple_params ... ok
[INFO] [stdout] test _tests::parser::parse_print_test::parses_with_single_number_expression ... ok
[INFO] [stdout] test _tests::testing_test::nothing ... ok
[INFO] [stdout] test _tests::tokenizer::token_test::can_construct_tokens ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_brackets ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_statements_with_line_numbers ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_print_statement_with_expressions ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_let_with_complex_expressions ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_goto ... ok
[INFO] [stdout] test _tests::parser::parser_test::parse_two_statements ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_expression_with_brackets ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_binary_expressions_with_right_var_in_let ... ok
[INFO] [stdout] test _tests::code_generator::code_generator_test::compiles_print_statement_with_multiple_args ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_commas ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_binary_expressions_with_numbers_in_let ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_relops ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_gt ... ok
[INFO] [stdout] test _tests::parser::parser_test::parse_if_statement_with_vars ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenizes_string_literals ... ok
[INFO] [stdout] test _tests::parser::parser_test::parsing_statements_with_line_numbers_produces_nodes_with_line_numbers ... ok
[INFO] [stdout] test _tests::parser::parse_let_test::parse_let_with_var_on_the_right ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenizes_numbers ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_vars ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::handles_blanks_inside_string_literals ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_statement_is_invalid ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::returns_error_if_string_is_unterminated ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::parses_expression_with_multiple_factors ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::returns_error_if_encountering_unknown_token ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_termops ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::handles_some_special_chars_inside_strings ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_first_token_is_not_command ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenizes_all_keywords ... ok
[INFO] [stdout] test _tests::parser::parser_test::parse_if_statement_with_if_statement_in_then ... ok
[INFO] [stdout] test _tests::parser::parse_expressions_test::return_error_if_expression_is_incomplete ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenizes_print_statement_without_parameters ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_if_token_not_followed_by_expression ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::handles_surrounding_whitespace ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_if_has_not_enough_parts ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_factops ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_then_branch_is_not_a_command ... ok
[INFO] [stdout] test _tests::parser::parse_let_test::parse_let_statement ... ok
[INFO] [stdout] test _tests::parser::parser_test::return_error_if_let_not_followed_by_equal_sign ... ok
[INFO] [stdout] test _tests::tokenizer::tokenizer_test::tokenize_brackets ... ok
[INFO] [stdout] test _tests::parser::parser_test::parse_if_statement ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 64 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/tbac-ae2522794f09d7bd)
[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] [stderr]    Doc-tests tbac
[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" "b5ea2e75ea821ba1d8340eb6bd0178217572d884140b12cad84981228dd80785", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b5ea2e75ea821ba1d8340eb6bd0178217572d884140b12cad84981228dd80785", kill_on_drop: false }`
[INFO] [stdout] b5ea2e75ea821ba1d8340eb6bd0178217572d884140b12cad84981228dd80785