Nov 16 16:05:21.567 INFO testing razielgn/ostrov against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 16:05:21.567 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:05:21.777 INFO [stdout] 790b97020b14d5905e3793e6773cd237733bf42452ffc5d23a9d8498758bda1e
Nov 16 16:05:21.777 INFO running `"docker" "start" "-a" "790b97020b14d5905e3793e6773cd237733bf42452ffc5d23a9d8498758bda1e"`
Nov 16 16:05:22.218 INFO [stderr] usermod: no changes
Nov 16 16:05:22.253 INFO [stderr]    Compiling nom v4.0.0
Nov 16 16:05:24.763 INFO [stderr]    Compiling nom_locate v0.3.0
Nov 16 16:05:25.407 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Nov 16 16:05:29.872 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.63s
Nov 16 16:05:29.879 INFO [stderr] su: No module specific data is present
Nov 16 16:05:30.581 INFO running `"docker" "inspect" "790b97020b14d5905e3793e6773cd237733bf42452ffc5d23a9d8498758bda1e"`
Nov 16 16:05:30.745 INFO running `"docker" "rm" "-f" "790b97020b14d5905e3793e6773cd237733bf42452ffc5d23a9d8498758bda1e"`
Nov 16 16:05:30.929 INFO [stdout] 790b97020b14d5905e3793e6773cd237733bf42452ffc5d23a9d8498758bda1e
Nov 16 16:05:30.938 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:05:31.471 INFO [stdout] e0bfc9990072cadf2374aca1c12cf14ec6172f34a1165d5f47caafb0587a8a82
Nov 16 16:05:31.475 INFO running `"docker" "start" "-a" "e0bfc9990072cadf2374aca1c12cf14ec6172f34a1165d5f47caafb0587a8a82"`
Nov 16 16:05:32.434 INFO [stderr] usermod: no changes
Nov 16 16:05:32.509 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Nov 16 16:05:33.079 INFO [stderr] warning: function `length_BadArity` should have a snake case name such as `length_bad_arity`
Nov 16 16:05:33.079 INFO [stderr]   --> tests/eval_list_procedures.rs:19:1
Nov 16 16:05:33.079 INFO [stderr]    |
Nov 16 16:05:33.079 INFO [stderr] 19 | / fn length_BadArity() {
Nov 16 16:05:33.079 INFO [stderr] 20 | |     assert_eval_err("(length '() '())", BadArity(Some("length".into())));
Nov 16 16:05:33.079 INFO [stderr] 21 | | }
Nov 16 16:05:33.079 INFO [stderr]    | |_^
Nov 16 16:05:33.079 INFO [stderr]    |
Nov 16 16:05:33.079 INFO [stderr]    = note: #[warn(non_snake_case)] on by default
Nov 16 16:05:33.079 INFO [stderr] 
Nov 16 16:05:38.588 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.11s
Nov 16 16:05:38.591 INFO [stderr] su: No module specific data is present
Nov 16 16:05:39.271 INFO running `"docker" "inspect" "e0bfc9990072cadf2374aca1c12cf14ec6172f34a1165d5f47caafb0587a8a82"`
Nov 16 16:05:39.579 INFO running `"docker" "rm" "-f" "e0bfc9990072cadf2374aca1c12cf14ec6172f34a1165d5f47caafb0587a8a82"`
Nov 16 16:05:39.915 INFO [stdout] e0bfc9990072cadf2374aca1c12cf14ec6172f34a1165d5f47caafb0587a8a82
Nov 16 16:05:39.927 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:05:40.431 INFO [stdout] b2347e96cea79cb9e1ee927059d15f7c9d4b86406a092a23d16c77046d82bfcd
Nov 16 16:05:40.439 INFO running `"docker" "start" "-a" "b2347e96cea79cb9e1ee927059d15f7c9d4b86406a092a23d16c77046d82bfcd"`
Nov 16 16:05:41.258 INFO [stderr] usermod: no changes
Nov 16 16:05:41.319 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 16 16:05:41.319 INFO [stderr]      Running /target/debug/deps/ostrov-582dd3ab1a3c38af
Nov 16 16:05:41.323 INFO [stdout] 
Nov 16 16:05:41.323 INFO [stdout] running 50 tests
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::and ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::constants_values_boolean ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::constants_values_one_integer ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::constants_values_two_integers ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::define_with_nothing ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::define_with_constant ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::define_with_lambda_fixed ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::define_with_lambda_any ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::if_two_args ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::function_application ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::let_ ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::if_one_arg ... ok
Nov 16 16:05:41.327 INFO [stdout] test compiler::test::set_bang ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::define_with_lambda_variable ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::lambda_variable ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::lambda_fixed ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::lambda_any ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::quote ... ok
Nov 16 16:05:41.331 INFO [stdout] test env::test::set_and_get_none ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::or ... ok
Nov 16 16:05:41.331 INFO [stdout] test compiler::test::variable_referencing ... ok
Nov 16 16:05:41.331 INFO [stdout] test parser::test::atoms ... ok
Nov 16 16:05:41.331 INFO [stdout] test env::test::wraps ... ok
Nov 16 16:05:41.331 INFO [stdout] test parser::test::booleans ... ok
Nov 16 16:05:41.331 INFO [stdout] test env::test::set_and_get_ok ... ok
Nov 16 16:05:41.331 INFO [stdout] test parser::test::integers ... ok
Nov 16 16:05:41.331 INFO [stdout] test parser::test::dotted_list ... ok
Nov 16 16:05:41.331 INFO [stdout] test parser::test::lists ... ok
Nov 16 16:05:41.331 INFO [stdout] test values::test::atoms ... ok
Nov 16 16:05:41.331 INFO [stdout] test values::test::booleans ... ok
Nov 16 16:05:41.335 INFO [stdout] test values::test::closures ... ok
Nov 16 16:05:41.335 INFO [stderr]      Running /target/debug/deps/tests-6c0d7633aadc875a
Nov 16 16:05:41.335 INFO [stdout] test values::test::integers ... ok
Nov 16 16:05:41.335 INFO [stdout] test values::test::nil ... ok
Nov 16 16:05:41.335 INFO [stdout] test parser::test::multiple_expressions ... ok
Nov 16 16:05:41.335 INFO [stdout] test values::test::pairs ... ok
Nov 16 16:05:41.335 INFO [stdout] test parser::test::no_expressions ... ok
Nov 16 16:05:41.335 INFO [stdout] test values::test::primitive_fns ... ok
Nov 16 16:05:41.335 INFO [stdout] test parser::test::quoted_literals ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_apply ... ok
Nov 16 16:05:41.335 INFO [stdout] test values::test::unspecified ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_argument ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_jump ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_assignment ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_jump_on_false ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_jump_on_true ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_load_constant ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_load_unspecified ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_load_reference ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::illegal_frame_apply ... ok
Nov 16 16:05:41.335 INFO [stdout] test vm::test::execute_nested_arguments ... ok
Nov 16 16:05:41.335 INFO [stdout] 
Nov 16 16:05:41.335 INFO [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:05:41.335 INFO [stdout] 
Nov 16 16:05:41.343 INFO [stdout] 
Nov 16 16:05:41.347 INFO [stdout] running 74 tests
Nov 16 16:05:41.350 INFO [stdout] test eval_application::expressions_in_first_position ... ok
Nov 16 16:05:41.359 INFO [stdout] test eval_application::dotted_lists ... ok
Nov 16 16:05:41.375 INFO [stdout] test eval_application::booleans ... ok
Nov 16 16:05:41.375 INFO [stdout] test eval_application::atoms ... ok
Nov 16 16:05:41.375 INFO [stdout] test eval_application::lambda_multiple_expressions_are_evaluated_sequentially ... ok
Nov 16 16:05:41.375 INFO [stdout] test eval_application::integers ... ok
Nov 16 16:05:41.375 INFO [stdout] test eval_application::procedure_with_mismatched_arity ... ignored
Nov 16 16:05:41.375 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number ... ok
Nov 16 16:05:41.378 INFO [stdout] test eval_application::lists ... ok
Nov 16 16:05:41.379 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number_bad_arity ... ok
Nov 16 16:05:41.387 INFO [stdout] test eval_application::procedure_with_two_args ... ok
Nov 16 16:05:41.387 INFO [stdout] test eval_application::procedure_with_two_args_scoping ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::lambda_with_variable_arguments_number_bad_arity ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_assignment::malformed_variable_name ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::procedure_with_one_arg ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_application::lambda_with_any_arguments_number ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_application::procedure_with_no_args ... ok
Nov 16 16:05:41.404 INFO [stdout] test eval_application::lambda_with_variable_arguments_number ... ok
Nov 16 16:05:41.404 INFO [stdout] test eval_application::procedure_with_any_args ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_assignment::returns_expression ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_assignment::wrong_arguments_number ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_assignment::unknown_variable ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_application::procedure_with_var_args ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_assignment::overwrites_variables ... ok
Nov 16 16:05:41.407 INFO [stdout] test eval_assignment::overwrites_variables_in_captured_scopes ... ok
Nov 16 16:05:41.411 INFO [stdout] test eval_application::procedure_with_two_args_previous_scoping_is_kept ... ok
Nov 16 16:05:41.411 INFO [stdout] test eval_assignment::overwrites_variables_on_upper_scopes ... ok
Nov 16 16:05:41.411 INFO [stdout] test eval_boolean_procedures::not_bad_arity ... ok
Nov 16 16:05:41.413 INFO [stdout] test eval_boolean_procedures::and ... ok
Nov 16 16:05:41.413 INFO [stdout] test eval_boolean_procedures::or ... ok
Nov 16 16:05:41.413 INFO [stdout] test eval_conditionals::if_bad_arity ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_conditionals::if_with_one_arg ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_boolean_procedures::not ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_bad_arity ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_procedure_with_any ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_procedure_with_fixed_args ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_with_one_arg ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_procedure_with_varargs ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_definitions::define_with_one_arg_lambda ... ok
Nov 16 16:05:41.423 INFO [stdout] test eval_conditionals::if_with_two_args ... ok
Nov 16 16:05:41.424 INFO [stdout] test eval_definitions::define_with_two_args ... ok
Nov 16 16:05:41.424 INFO [stdout] test eval_integer_procedures::division ... ok
Nov 16 16:05:41.424 INFO [stdout] test eval_integer_procedures::division_bad_arity ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_integer_procedures::greater_than_or_equal_sign ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_integer_procedures::equal_sign ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_integer_procedures::greater_than_sign ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_integer_procedures::less_than_or_equal_sign ... ok
Nov 16 16:05:41.444 INFO [stdout] test eval_integer_procedures::plus_bad_arity ... ok
Nov 16 16:05:41.444 INFO [stdout] test eval_integer_procedures::minus_bad_arity ... ok
Nov 16 16:05:41.444 INFO [stdout] test eval_integer_procedures::product ... ok
Nov 16 16:05:41.445 INFO [stdout] test eval_integer_procedures::minus ... ok
Nov 16 16:05:41.451 INFO [stdout] test eval_integer_procedures::less_than_sign ... ok
Nov 16 16:05:41.471 INFO [stdout] test eval_list_procedures::car_wrong_argument_type ... ok
Nov 16 16:05:41.471 INFO [stdout] test eval_integer_procedures::plus ... ok
Nov 16 16:05:41.471 INFO [stdout] test eval_lets::bad_arity_ ... ok
Nov 16 16:05:41.471 INFO [stdout] test eval_list_procedures::car ... ok
Nov 16 16:05:41.478 INFO [stdout] test eval_list_procedures::cons ... ok
Nov 16 16:05:41.479 INFO [stdout] test eval_lets::malformed ... ok
Nov 16 16:05:41.480 INFO [stdout] test eval_list_procedures::length ... ok
Nov 16 16:05:41.480 INFO [stdout] test eval_list_procedures::car_bad_arity ... ok
Nov 16 16:05:41.480 INFO [stdout] test eval_list_procedures::cdr ... ok
Nov 16 16:05:41.480 INFO [stdout] test eval_list_procedures::cons_bad_arity ... ok
Nov 16 16:05:41.480 INFO [stdout] test eval_lets::one_definition ... ok
Nov 16 16:05:41.483 INFO [stdout] test eval_list_procedures::null ... ok
Nov 16 16:05:41.491 INFO [stdout] test eval_list_procedures::cdr_wrong_argument_type ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::cdr_bad_arity ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::length_BadArity ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::null_bad_arity ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::list_ ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::length_bad_arguments ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::list_question_mark ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::pair_bad_arity ... ok
Nov 16 16:05:41.495 INFO [stdout] test eval_list_procedures::list_question_mark_bad_arity ... ok
Nov 16 16:05:41.507 INFO [stdout] test eval_list_procedures::pair_ ... ok
Nov 16 16:05:41.507 INFO [stderr] su: No module specific data is present
Nov 16 16:05:41.507 INFO [stdout] 
Nov 16 16:05:41.507 INFO [stdout] test result: ok. 73 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Nov 16 16:05:41.507 INFO [stdout] 
Nov 16 16:05:42.172 INFO running `"docker" "inspect" "b2347e96cea79cb9e1ee927059d15f7c9d4b86406a092a23d16c77046d82bfcd"`
Nov 16 16:05:42.414 INFO running `"docker" "rm" "-f" "b2347e96cea79cb9e1ee927059d15f7c9d4b86406a092a23d16c77046d82bfcd"`
Nov 16 16:05:42.675 INFO [stdout] b2347e96cea79cb9e1ee927059d15f7c9d4b86406a092a23d16c77046d82bfcd