Nov 16 16:05:21.932 INFO testing razielgn/ostrov against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 16:05:21.932 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:22.181 INFO [stdout] 6f3da245e70cbf2e97455700c68ca599ade7ae00e22d598aa858201d7fce420b
Nov 16 16:05:22.186 INFO running `"docker" "start" "-a" "6f3da245e70cbf2e97455700c68ca599ade7ae00e22d598aa858201d7fce420b"`
Nov 16 16:05:22.639 INFO [stderr] usermod: no changes
Nov 16 16:05:22.685 INFO [stderr]    Compiling nom v4.0.0
Nov 16 16:05:25.244 INFO [stderr]    Compiling nom_locate v0.3.0
Nov 16 16:05:25.848 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Nov 16 16:05:30.315 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.64s
Nov 16 16:05:30.327 INFO [stderr] su: No module specific data is present
Nov 16 16:05:30.855 INFO running `"docker" "inspect" "6f3da245e70cbf2e97455700c68ca599ade7ae00e22d598aa858201d7fce420b"`
Nov 16 16:05:31.177 INFO running `"docker" "rm" "-f" "6f3da245e70cbf2e97455700c68ca599ade7ae00e22d598aa858201d7fce420b"`
Nov 16 16:05:31.471 INFO [stdout] 6f3da245e70cbf2e97455700c68ca599ade7ae00e22d598aa858201d7fce420b
Nov 16 16:05:31.476 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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.783 INFO [stdout] b5ff3e1d3f37df1d3879e1ac252494be9be60de0c0aabcb6a7b7e38d10ba1066
Nov 16 16:05:31.786 INFO running `"docker" "start" "-a" "b5ff3e1d3f37df1d3879e1ac252494be9be60de0c0aabcb6a7b7e38d10ba1066"`
Nov 16 16:05:32.911 INFO [stderr] usermod: no changes
Nov 16 16:05:32.983 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Nov 16 16:05:33.606 INFO [stderr] warning: function `length_BadArity` should have a snake case name such as `length_bad_arity`
Nov 16 16:05:33.606 INFO [stderr]   --> tests/eval_list_procedures.rs:19:1
Nov 16 16:05:33.606 INFO [stderr]    |
Nov 16 16:05:33.606 INFO [stderr] 19 | / fn length_BadArity() {
Nov 16 16:05:33.606 INFO [stderr] 20 | |     assert_eval_err("(length '() '())", BadArity(Some("length".into())));
Nov 16 16:05:33.606 INFO [stderr] 21 | | }
Nov 16 16:05:33.606 INFO [stderr]    | |_^
Nov 16 16:05:33.606 INFO [stderr]    |
Nov 16 16:05:33.607 INFO [stderr]    = note: #[warn(non_snake_case)] on by default
Nov 16 16:05:33.607 INFO [stderr] 
Nov 16 16:05:38.675 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.74s
Nov 16 16:05:38.678 INFO [stderr] su: No module specific data is present
Nov 16 16:05:39.284 INFO running `"docker" "inspect" "b5ff3e1d3f37df1d3879e1ac252494be9be60de0c0aabcb6a7b7e38d10ba1066"`
Nov 16 16:05:39.629 INFO running `"docker" "rm" "-f" "b5ff3e1d3f37df1d3879e1ac252494be9be60de0c0aabcb6a7b7e38d10ba1066"`
Nov 16 16:05:39.890 INFO [stdout] b5ff3e1d3f37df1d3879e1ac252494be9be60de0c0aabcb6a7b7e38d10ba1066
Nov 16 16:05:39.898 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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.573 INFO [stdout] 1ec72e45412115695aba073729ff4b5883fce9928bd0e4a0fe073c04b632eccc
Nov 16 16:05:40.577 INFO running `"docker" "start" "-a" "1ec72e45412115695aba073729ff4b5883fce9928bd0e4a0fe073c04b632eccc"`
Nov 16 16:05:41.287 INFO [stderr] usermod: no changes
Nov 16 16:05:41.344 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 16 16:05:41.355 INFO [stderr]      Running /target/debug/deps/ostrov-837790e910416cac
Nov 16 16:05:41.363 INFO [stdout] 
Nov 16 16:05:41.363 INFO [stdout] running 50 tests
Nov 16 16:05:41.363 INFO [stdout] test compiler::test::define_with_lambda_any ... ok
Nov 16 16:05:41.363 INFO [stdout] test compiler::test::define_with_lambda_fixed ... ok
Nov 16 16:05:41.363 INFO [stdout] test compiler::test::define_with_constant ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::define_with_nothing ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::define_with_lambda_variable ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::constants_values_two_integers ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::constants_values_one_integer ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::if_two_args ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::constants_values_boolean ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::lambda_any ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::function_application ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::and ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::let_ ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::if_one_arg ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::lambda_fixed ... ok
Nov 16 16:05:41.374 INFO [stdout] test env::test::set_and_get_none ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::lambda_variable ... ok
Nov 16 16:05:41.374 INFO [stdout] test env::test::wraps ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::or ... ok
Nov 16 16:05:41.374 INFO [stdout] test parser::test::booleans ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::quote ... ok
Nov 16 16:05:41.374 INFO [stdout] test compiler::test::set_bang ... ok
Nov 16 16:05:41.374 INFO [stdout] test parser::test::atoms ... ok
Nov 16 16:05:41.375 INFO [stdout] test compiler::test::variable_referencing ... ok
Nov 16 16:05:41.375 INFO [stdout] test env::test::set_and_get_ok ... ok
Nov 16 16:05:41.376 INFO [stdout] test parser::test::dotted_list ... ok
Nov 16 16:05:41.377 INFO [stdout] test parser::test::integers ... ok
Nov 16 16:05:41.377 INFO [stdout] test parser::test::lists ... ok
Nov 16 16:05:41.377 INFO [stdout] test parser::test::multiple_expressions ... ok
Nov 16 16:05:41.377 INFO [stdout] test parser::test::no_expressions ... ok
Nov 16 16:05:41.377 INFO [stdout] test parser::test::quoted_literals ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::atoms ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::booleans ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::nil ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::pairs ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::primitive_fns ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::closures ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::integers ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_jump_on_false ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_argument ... ok
Nov 16 16:05:41.377 INFO [stdout] test values::test::unspecified ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_assignment ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_apply ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_jump ... ok
Nov 16 16:05:41.377 INFO [stdout] test vm::test::execute_nested_arguments ... ok
Nov 16 16:05:41.378 INFO [stdout] test vm::test::execute_load_constant ... ok
Nov 16 16:05:41.378 INFO [stdout] test vm::test::illegal_frame_apply ... ok
Nov 16 16:05:41.379 INFO [stdout] test vm::test::execute_jump_on_true ... ok
Nov 16 16:05:41.379 INFO [stdout] test vm::test::execute_load_unspecified ... ok
Nov 16 16:05:41.379 INFO [stdout] test vm::test::execute_load_reference ... ok
Nov 16 16:05:41.379 INFO [stdout] 
Nov 16 16:05:41.379 INFO [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:05:41.379 INFO [stdout] 
Nov 16 16:05:41.387 INFO [stdout] 
Nov 16 16:05:41.387 INFO [stderr]      Running /target/debug/deps/tests-74e91f6f1948f9ed
Nov 16 16:05:41.387 INFO [stdout] running 74 tests
Nov 16 16:05:41.387 INFO [stdout] test eval_application::integers ... ok
Nov 16 16:05:41.387 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number ... ok
Nov 16 16:05:41.387 INFO [stdout] test eval_application::lambda_with_any_arguments_number ... ok
Nov 16 16:05:41.387 INFO [stdout] test eval_application::lambda_multiple_expressions_are_evaluated_sequentially ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::expressions_in_first_position ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::dotted_lists ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::procedure_with_mismatched_arity ... ignored
Nov 16 16:05:41.395 INFO [stdout] test eval_application::booleans ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::atoms ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number_bad_arity ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::procedure_with_no_args ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::procedure_with_one_arg ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::lambda_with_variable_arguments_number ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::procedure_with_two_args ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::procedure_with_any_args ... ok
Nov 16 16:05:41.395 INFO [stdout] test eval_application::lambda_with_variable_arguments_number_bad_arity ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::lists ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::procedure_with_two_args_scoping ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::procedure_with_two_args_previous_scoping_is_kept ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_application::procedure_with_var_args ... ok
Nov 16 16:05:41.396 INFO [stdout] test eval_assignment::wrong_arguments_number ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_assignment::unknown_variable ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_assignment::malformed_variable_name ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_assignment::overwrites_variables ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_assignment::overwrites_variables_on_upper_scopes ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_boolean_procedures::not_bad_arity ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_assignment::returns_expression ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_boolean_procedures::or ... ok
Nov 16 16:05:41.403 INFO [stdout] test eval_boolean_procedures::not ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_conditionals::if_with_one_arg ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_procedure_with_fixed_args ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_bad_arity ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_procedure_with_any ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_conditionals::if_bad_arity ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_boolean_procedures::and ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_assignment::overwrites_variables_in_captured_scopes ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_with_one_arg ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_procedure_with_varargs ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_with_one_arg_lambda ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_conditionals::if_with_two_args ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_definitions::define_with_two_args ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_integer_procedures::equal_sign ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_integer_procedures::minus ... ok
Nov 16 16:05:41.427 INFO [stdout] test eval_integer_procedures::division ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::division_bad_arity ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::greater_than_sign ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::plus ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_lets::bad_arity_ ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::greater_than_or_equal_sign ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::less_than_or_equal_sign ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::minus_bad_arity ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_lets::malformed ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::plus_bad_arity ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_integer_procedures::product ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_lets::one_definition ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_list_procedures::cdr ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_list_procedures::cdr_bad_arity ... ok
Nov 16 16:05:41.428 INFO [stdout] test eval_list_procedures::cons ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::car_bad_arity ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::car_wrong_argument_type ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::cdr_wrong_argument_type ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::cons_bad_arity ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::length ... ok
Nov 16 16:05:41.433 INFO [stdout] test eval_list_procedures::length_BadArity ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_list_procedures::list_ ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_list_procedures::length_bad_arguments ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_list_procedures::list_question_mark_bad_arity ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_integer_procedures::less_than_sign ... ok
Nov 16 16:05:41.439 INFO [stdout] test eval_list_procedures::pair_bad_arity ... ok
Nov 16 16:05:41.446 INFO [stdout] test eval_list_procedures::null_bad_arity ... ok
Nov 16 16:05:41.446 INFO [stdout] test eval_list_procedures::car ... ok
Nov 16 16:05:41.446 INFO [stdout] test eval_list_procedures::list_question_mark ... ok
Nov 16 16:05:41.455 INFO [stdout] test eval_list_procedures::pair_ ... ok
Nov 16 16:05:41.455 INFO [stdout] test eval_list_procedures::null ... ok
Nov 16 16:05:41.455 INFO [stdout] 
Nov 16 16:05:41.455 INFO [stdout] test result: ok. 73 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Nov 16 16:05:41.455 INFO [stdout] 
Nov 16 16:05:41.463 INFO [stderr] su: No module specific data is present
Nov 16 16:05:42.185 INFO running `"docker" "inspect" "1ec72e45412115695aba073729ff4b5883fce9928bd0e4a0fe073c04b632eccc"`
Nov 16 16:05:42.449 INFO running `"docker" "rm" "-f" "1ec72e45412115695aba073729ff4b5883fce9928bd0e4a0fe073c04b632eccc"`
Nov 16 16:05:42.739 INFO [stdout] 1ec72e45412115695aba073729ff4b5883fce9928bd0e4a0fe073c04b632eccc