Dec 05 10:27:40.681 INFO testing razielgn/ostrov against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 10:27:40.682 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 05 10:27:41.288 INFO [stdout] 504383d4a0cb3dfb30f8b2cd7833258b5710e5f88f2ac0e8e702e334e70dad49
Dec 05 10:27:41.296 INFO running `"docker" "start" "-a" "504383d4a0cb3dfb30f8b2cd7833258b5710e5f88f2ac0e8e702e334e70dad49"`
Dec 05 10:27:42.958 INFO [stderr] usermod: no changes
Dec 05 10:27:43.012 INFO [stderr]    Compiling nom v4.0.0
Dec 05 10:27:45.933 INFO [stderr]    Compiling nom_locate v0.3.0
Dec 05 10:27:46.597 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Dec 05 10:27:50.088 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.10s
Dec 05 10:27:50.088 INFO [stderr] su: No module specific data is present
Dec 05 10:27:50.672 INFO running `"docker" "inspect" "504383d4a0cb3dfb30f8b2cd7833258b5710e5f88f2ac0e8e702e334e70dad49"`
Dec 05 10:27:50.899 INFO running `"docker" "rm" "-f" "504383d4a0cb3dfb30f8b2cd7833258b5710e5f88f2ac0e8e702e334e70dad49"`
Dec 05 10:27:51.092 INFO [stdout] 504383d4a0cb3dfb30f8b2cd7833258b5710e5f88f2ac0e8e702e334e70dad49
Dec 05 10:27:51.094 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 05 10:27:51.466 INFO [stdout] 28905d99015441efade6bf598e48f0ff1e70cbe3ee0c16f3c1cf2bcbbb9ee30d
Dec 05 10:27:51.470 INFO running `"docker" "start" "-a" "28905d99015441efade6bf598e48f0ff1e70cbe3ee0c16f3c1cf2bcbbb9ee30d"`
Dec 05 10:27:52.455 INFO [stderr] usermod: no changes
Dec 05 10:27:52.532 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Dec 05 10:27:53.348 INFO [stderr] warning: function `length_BadArity` should have a snake case name such as `length_bad_arity`
Dec 05 10:27:53.348 INFO [stderr]   --> tests/eval_list_procedures.rs:19:1
Dec 05 10:27:53.348 INFO [stderr]    |
Dec 05 10:27:53.348 INFO [stderr] 19 | / fn length_BadArity() {
Dec 05 10:27:53.348 INFO [stderr] 20 | |     assert_eval_err("(length '() '())", BadArity(Some("length".into())));
Dec 05 10:27:53.348 INFO [stderr] 21 | | }
Dec 05 10:27:53.348 INFO [stderr]    | |_^
Dec 05 10:27:53.348 INFO [stderr]    |
Dec 05 10:27:53.348 INFO [stderr]    = note: #[warn(non_snake_case)] on by default
Dec 05 10:27:53.348 INFO [stderr] 
Dec 05 10:27:57.759 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.28s
Dec 05 10:27:57.760 INFO [stderr] su: No module specific data is present
Dec 05 10:27:58.213 INFO running `"docker" "inspect" "28905d99015441efade6bf598e48f0ff1e70cbe3ee0c16f3c1cf2bcbbb9ee30d"`
Dec 05 10:27:58.350 INFO running `"docker" "rm" "-f" "28905d99015441efade6bf598e48f0ff1e70cbe3ee0c16f3c1cf2bcbbb9ee30d"`
Dec 05 10:27:58.588 INFO [stdout] 28905d99015441efade6bf598e48f0ff1e70cbe3ee0c16f3c1cf2bcbbb9ee30d
Dec 05 10:27:58.594 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 05 10:27:59.032 INFO [stdout] 821e84eb1f5447405d809f6bfeaa21252a4a9ea9d587c0be8cbf07d5b49e018d
Dec 05 10:27:59.036 INFO running `"docker" "start" "-a" "821e84eb1f5447405d809f6bfeaa21252a4a9ea9d587c0be8cbf07d5b49e018d"`
Dec 05 10:28:00.105 INFO [stderr] usermod: no changes
Dec 05 10:28:00.185 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 05 10:28:00.200 INFO [stderr]      Running /target/debug/deps/ostrov-ad2463288e9c4a6d
Dec 05 10:28:00.204 INFO [stdout] 
Dec 05 10:28:00.204 INFO [stdout] running 50 tests
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::constants_values_one_integer ... ok
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::define_with_constant ... ok
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::constants_values_two_integers ... ok
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::constants_values_boolean ... ok
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::define_with_lambda_any ... ok
Dec 05 10:28:00.236 INFO [stdout] test compiler::test::and ... ok
Dec 05 10:28:00.260 INFO [stdout] test compiler::test::define_with_lambda_variable ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::define_with_nothing ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::lambda_fixed ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::if_one_arg ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::lambda_any ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::define_with_lambda_fixed ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::if_two_args ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::function_application ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::set_bang ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::or ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::lambda_variable ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::let_ ... ok
Dec 05 10:28:00.262 INFO [stdout] test compiler::test::quote ... ok
Dec 05 10:28:00.262 INFO [stdout] test parser::test::integers ... ok
Dec 05 10:28:00.262 INFO [stdout] test parser::test::booleans ... ok
Dec 05 10:28:00.262 INFO [stdout] test parser::test::atoms ... ok
Dec 05 10:28:00.264 INFO [stdout] test env::test::set_and_get_ok ... ok
Dec 05 10:28:00.264 INFO [stdout] test env::test::set_and_get_none ... ok
Dec 05 10:28:00.264 INFO [stdout] test compiler::test::variable_referencing ... ok
Dec 05 10:28:00.264 INFO [stdout] test parser::test::multiple_expressions ... ok
Dec 05 10:28:00.264 INFO [stdout] test parser::test::no_expressions ... ok
Dec 05 10:28:00.264 INFO [stdout] test env::test::wraps ... ok
Dec 05 10:28:00.264 INFO [stdout] test parser::test::dotted_list ... ok
Dec 05 10:28:00.272 INFO [stdout] test values::test::closures ... ok
Dec 05 10:28:00.272 INFO [stdout] test values::test::booleans ... ok
Dec 05 10:28:00.272 INFO [stdout] test values::test::atoms ... ok
Dec 05 10:28:00.288 INFO [stdout] test values::test::nil ... ok
Dec 05 10:28:00.288 INFO [stdout] test values::test::pairs ... ok
Dec 05 10:28:00.288 INFO [stdout] test values::test::integers ... ok
Dec 05 10:28:00.288 INFO [stdout] test values::test::unspecified ... ok
Dec 05 10:28:00.288 INFO [stdout] test values::test::primitive_fns ... ok
Dec 05 10:28:00.288 INFO [stdout] test parser::test::lists ... ok
Dec 05 10:28:00.288 INFO [stdout] test parser::test::quoted_literals ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::execute_apply ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::execute_argument ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::execute_jump ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::execute_jump_on_false ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::execute_load_constant ... ok
Dec 05 10:28:00.288 INFO [stdout] test vm::test::illegal_frame_apply ... ok
Dec 05 10:28:00.296 INFO [stdout] test vm::test::execute_nested_arguments ... ok
Dec 05 10:28:00.296 INFO [stderr]      Running /target/debug/deps/tests-f9f636a199f53647
Dec 05 10:28:00.296 INFO [stdout] test vm::test::execute_load_unspecified ... ok
Dec 05 10:28:00.296 INFO [stdout] test vm::test::execute_load_reference ... ok
Dec 05 10:28:00.296 INFO [stdout] test vm::test::execute_assignment ... ok
Dec 05 10:28:00.296 INFO [stdout] test vm::test::execute_jump_on_true ... ok
Dec 05 10:28:00.296 INFO [stdout] 
Dec 05 10:28:00.296 INFO [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 10:28:00.296 INFO [stdout] 
Dec 05 10:28:00.304 INFO [stdout] 
Dec 05 10:28:00.304 INFO [stdout] running 74 tests
Dec 05 10:28:00.312 INFO [stdout] test eval_application::atoms ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::lambda_multiple_expressions_are_evaluated_sequentially ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::booleans ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::lists ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::procedure_with_any_args ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::procedure_with_mismatched_arity ... ignored
Dec 05 10:28:00.312 INFO [stdout] test eval_application::procedure_with_no_args ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::lambda_with_variable_arguments_number ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::procedure_with_one_arg ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::dotted_lists ... ok
Dec 05 10:28:00.312 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number_bad_arity ... ok
Dec 05 10:28:00.320 INFO [stdout] test eval_application::procedure_with_two_args ... ok
Dec 05 10:28:00.329 INFO [stdout] test eval_assignment::malformed_variable_name ... ok
Dec 05 10:28:00.329 INFO [stdout] test eval_application::procedure_with_two_args_previous_scoping_is_kept ... ok
Dec 05 10:28:00.330 INFO [stdout] test eval_application::lambda_with_variable_arguments_number_bad_arity ... ok
Dec 05 10:28:00.331 INFO [stdout] test eval_application::expressions_in_first_position ... ok
Dec 05 10:28:00.331 INFO [stdout] test eval_application::procedure_with_two_args_scoping ... ok
Dec 05 10:28:00.331 INFO [stdout] test eval_application::procedure_with_var_args ... ok
Dec 05 10:28:00.331 INFO [stdout] test eval_application::lambda_with_any_arguments_number ... ok
Dec 05 10:28:00.332 INFO [stdout] test eval_application::integers ... ok
Dec 05 10:28:00.340 INFO [stdout] test eval_assignment::unknown_variable ... ok
Dec 05 10:28:00.352 INFO [stdout] test eval_assignment::overwrites_variables_on_upper_scopes ... ok
Dec 05 10:28:00.352 INFO [stdout] test eval_assignment::overwrites_variables_in_captured_scopes ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_assignment::overwrites_variables ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_assignment::returns_expression ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_boolean_procedures::and ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_boolean_procedures::not ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_assignment::wrong_arguments_number ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_boolean_procedures::not_bad_arity ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_conditionals::if_with_two_args ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_conditionals::if_with_one_arg ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_definitions::define_procedure_with_fixed_args ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_conditionals::if_bad_arity ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_definitions::define_bad_arity ... ok
Dec 05 10:28:00.360 INFO [stdout] test eval_definitions::define_procedure_with_any ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_definitions::define_procedure_with_varargs ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_boolean_procedures::or ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_integer_procedures::division_bad_arity ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_integer_procedures::greater_than_or_equal_sign ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_definitions::define_with_two_args ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_integer_procedures::division ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_definitions::define_with_one_arg_lambda ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_definitions::define_with_one_arg ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_integer_procedures::equal_sign ... ok
Dec 05 10:28:00.364 INFO [stdout] test eval_integer_procedures::less_than_sign ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_integer_procedures::minus ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_integer_procedures::minus_bad_arity ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_integer_procedures::greater_than_sign ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_integer_procedures::plus ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_integer_procedures::less_than_or_equal_sign ... ok
Dec 05 10:28:00.366 INFO [stdout] test eval_lets::malformed ... ok
Dec 05 10:28:00.367 INFO [stdout] test eval_integer_procedures::plus_bad_arity ... ok
Dec 05 10:28:00.372 INFO [stdout] test eval_integer_procedures::product ... ok
Dec 05 10:28:00.376 INFO [stdout] test eval_lets::one_definition ... ok
Dec 05 10:28:00.376 INFO [stdout] test eval_list_procedures::car_bad_arity ... ok
Dec 05 10:28:00.376 INFO [stdout] test eval_list_procedures::car ... ok
Dec 05 10:28:00.376 INFO [stdout] test eval_lets::bad_arity_ ... ok
Dec 05 10:28:00.381 INFO [stdout] test eval_list_procedures::cons_bad_arity ... ok
Dec 05 10:28:00.382 INFO [stdout] test eval_list_procedures::cdr ... ok
Dec 05 10:28:00.382 INFO [stdout] test eval_list_procedures::cdr_bad_arity ... ok
Dec 05 10:28:00.383 INFO [stdout] test eval_list_procedures::car_wrong_argument_type ... ok
Dec 05 10:28:00.392 INFO [stdout] test eval_list_procedures::length_bad_arguments ... ok
Dec 05 10:28:00.396 INFO [stdout] test eval_list_procedures::cons ... ok
Dec 05 10:28:00.396 INFO [stdout] test eval_list_procedures::cdr_wrong_argument_type ... ok
Dec 05 10:28:00.396 INFO [stdout] test eval_list_procedures::length_BadArity ... ok
Dec 05 10:28:00.396 INFO [stdout] test eval_list_procedures::length ... ok
Dec 05 10:28:00.396 INFO [stdout] test eval_list_procedures::list_question_mark_bad_arity ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::pair_bad_arity ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::list_ ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::list_question_mark ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::null_bad_arity ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::pair_ ... ok
Dec 05 10:28:00.404 INFO [stdout] test eval_list_procedures::null ... ok
Dec 05 10:28:00.404 INFO [stdout] 
Dec 05 10:28:00.404 INFO [stdout] test result: ok. 73 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 05 10:28:00.404 INFO [stdout] 
Dec 05 10:28:00.416 INFO [stderr] su: No module specific data is present
Dec 05 10:28:01.202 INFO running `"docker" "inspect" "821e84eb1f5447405d809f6bfeaa21252a4a9ea9d587c0be8cbf07d5b49e018d"`
Dec 05 10:28:01.456 INFO running `"docker" "rm" "-f" "821e84eb1f5447405d809f6bfeaa21252a4a9ea9d587c0be8cbf07d5b49e018d"`
Dec 05 10:28:01.663 INFO [stdout] 821e84eb1f5447405d809f6bfeaa21252a4a9ea9d587c0be8cbf07d5b49e018d