Dec 13 06:25:36.246 INFO testing razielgn/ostrov against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 06:25:36.246 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 06:25:36.740 INFO [stdout] eca86dee82420d1bbc0c7d481de9b931cfa568f4c13ad5a27ddd2b61b9733930
Dec 13 06:25:36.748 INFO running `"docker" "start" "-a" "eca86dee82420d1bbc0c7d481de9b931cfa568f4c13ad5a27ddd2b61b9733930"`
Dec 13 06:25:38.063 INFO [stderr] usermod: no changes
Dec 13 06:25:38.191 INFO [stderr]    Compiling libc v0.2.42
Dec 13 06:25:39.803 INFO [stderr]    Compiling memchr v2.0.1
Dec 13 06:25:40.411 INFO [stderr]    Compiling nom v4.0.0
Dec 13 06:25:43.773 INFO [stderr]    Compiling nom_locate v0.3.0
Dec 13 06:25:44.408 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Dec 13 06:25:48.446 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.34s
Dec 13 06:25:48.452 INFO [stderr] su: No module specific data is present
Dec 13 06:25:49.003 INFO running `"docker" "inspect" "eca86dee82420d1bbc0c7d481de9b931cfa568f4c13ad5a27ddd2b61b9733930"`
Dec 13 06:25:49.255 INFO running `"docker" "rm" "-f" "eca86dee82420d1bbc0c7d481de9b931cfa568f4c13ad5a27ddd2b61b9733930"`
Dec 13 06:25:49.459 INFO [stdout] eca86dee82420d1bbc0c7d481de9b931cfa568f4c13ad5a27ddd2b61b9733930
Dec 13 06:25:49.459 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 06:25:49.999 INFO [stdout] 6a925d2f13d565bf22965ac189ae41ee624333cabc218043870c053d21046e5d
Dec 13 06:25:50.007 INFO running `"docker" "start" "-a" "6a925d2f13d565bf22965ac189ae41ee624333cabc218043870c053d21046e5d"`
Dec 13 06:25:51.339 INFO [stderr] usermod: no changes
Dec 13 06:25:51.412 INFO [stderr]    Compiling ostrov v0.0.1 (/source)
Dec 13 06:25:52.011 INFO [stderr] warning: function `length_BadArity` should have a snake case name such as `length_bad_arity`
Dec 13 06:25:52.011 INFO [stderr]   --> tests/eval_list_procedures.rs:19:1
Dec 13 06:25:52.011 INFO [stderr]    |
Dec 13 06:25:52.011 INFO [stderr] 19 | / fn length_BadArity() {
Dec 13 06:25:52.011 INFO [stderr] 20 | |     assert_eval_err("(length '() '())", BadArity(Some("length".into())));
Dec 13 06:25:52.011 INFO [stderr] 21 | | }
Dec 13 06:25:52.011 INFO [stderr]    | |_^
Dec 13 06:25:52.011 INFO [stderr]    |
Dec 13 06:25:52.011 INFO [stderr]    = note: #[warn(non_snake_case)] on by default
Dec 13 06:25:52.011 INFO [stderr] 
Dec 13 06:25:57.457 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.08s
Dec 13 06:25:57.457 INFO [stderr] su: No module specific data is present
Dec 13 06:25:57.961 INFO running `"docker" "inspect" "6a925d2f13d565bf22965ac189ae41ee624333cabc218043870c053d21046e5d"`
Dec 13 06:25:58.385 INFO running `"docker" "rm" "-f" "6a925d2f13d565bf22965ac189ae41ee624333cabc218043870c053d21046e5d"`
Dec 13 06:25:58.563 INFO [stdout] 6a925d2f13d565bf22965ac189ae41ee624333cabc218043870c053d21046e5d
Dec 13 06:25:58.569 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 06:25:59.009 INFO [stdout] 132de8630ec3718ec587d2b32f6a7fa4f959b1fe5ac3315b48eb25fad27ae31f
Dec 13 06:25:59.009 INFO running `"docker" "start" "-a" "132de8630ec3718ec587d2b32f6a7fa4f959b1fe5ac3315b48eb25fad27ae31f"`
Dec 13 06:26:00.648 INFO [stderr] usermod: no changes
Dec 13 06:26:00.722 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Dec 13 06:26:00.733 INFO [stderr]      Running /target/debug/deps/ostrov-0001affa0fc8158c
Dec 13 06:26:00.740 INFO [stdout] 
Dec 13 06:26:00.740 INFO [stdout] running 50 tests
Dec 13 06:26:00.744 INFO [stdout] test compiler::test::constants_values_one_integer ... ok
Dec 13 06:26:00.744 INFO [stdout] test compiler::test::and ... ok
Dec 13 06:26:00.744 INFO [stdout] test compiler::test::constants_values_boolean ... ok
Dec 13 06:26:00.744 INFO [stdout] test compiler::test::define_with_nothing ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::define_with_lambda_variable ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::if_one_arg ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::function_application ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::define_with_lambda_fixed ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::lambda_fixed ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::define_with_lambda_any ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::define_with_constant ... ok
Dec 13 06:26:00.751 INFO [stdout] test compiler::test::lambda_any ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::lambda_variable ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::if_two_args ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::variable_referencing ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::let_ ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::or ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::quote ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::set_bang ... ok
Dec 13 06:26:00.769 INFO [stdout] test compiler::test::constants_values_two_integers ... ok
Dec 13 06:26:00.769 INFO [stdout] test env::test::set_and_get_none ... ok
Dec 13 06:26:00.769 INFO [stdout] test env::test::set_and_get_ok ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::booleans ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::multiple_expressions ... ok
Dec 13 06:26:00.769 INFO [stdout] test env::test::wraps ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::integers ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::dotted_list ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::lists ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::atoms ... ok
Dec 13 06:26:00.769 INFO [stdout] test parser::test::no_expressions ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::nil ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::atoms ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::integers ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::closures ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::pairs ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::primitive_fns ... ok
Dec 13 06:26:00.769 INFO [stdout] test values::test::unspecified ... ok
Dec 13 06:26:00.769 INFO [stdout] test vm::test::execute_apply ... ok
Dec 13 06:26:00.769 INFO [stdout] test vm::test::execute_argument ... ok
Dec 13 06:26:00.769 INFO [stdout] test vm::test::execute_assignment ... ok
Dec 13 06:26:00.775 INFO [stdout] test values::test::booleans ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_jump ... ok
Dec 13 06:26:00.776 INFO [stdout] test parser::test::quoted_literals ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_jump_on_false ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_nested_arguments ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_jump_on_true ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_load_constant ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_load_reference ... ok
Dec 13 06:26:00.776 INFO [stdout] test vm::test::execute_load_unspecified ... ok
Dec 13 06:26:00.783 INFO [stdout] test vm::test::illegal_frame_apply ... ok
Dec 13 06:26:00.783 INFO [stdout] 
Dec 13 06:26:00.783 INFO [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 06:26:00.783 INFO [stdout] 
Dec 13 06:26:00.788 INFO [stderr]      Running /target/debug/deps/tests-e71c10cb2d6c7368
Dec 13 06:26:00.791 INFO [stdout] 
Dec 13 06:26:00.791 INFO [stdout] running 74 tests
Dec 13 06:26:00.795 INFO [stdout] test eval_application::atoms ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::dotted_lists ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::booleans ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::lambda_multiple_expressions_are_evaluated_sequentially ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::lambda_with_variable_arguments_number_bad_arity ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::lists ... ok
Dec 13 06:26:00.795 INFO [stdout] test eval_application::procedure_with_mismatched_arity ... ignored
Dec 13 06:26:00.795 INFO [stdout] test eval_application::lambda_with_any_arguments_number ... ok
Dec 13 06:26:00.796 INFO [stdout] test eval_application::procedure_with_any_args ... ok
Dec 13 06:26:00.796 INFO [stdout] test eval_application::integers ... ok
Dec 13 06:26:00.796 INFO [stdout] test eval_application::procedure_with_no_args ... ok
Dec 13 06:26:00.804 INFO [stdout] test eval_application::lambda_with_variable_arguments_number ... ok
Dec 13 06:26:00.819 INFO [stdout] test eval_application::procedure_with_two_args_previous_scoping_is_kept ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number_bad_arity ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::lambda_with_fixed_arguments_number ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::expressions_in_first_position ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::procedure_with_one_arg ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::returns_expression ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::procedure_with_var_args ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::procedure_with_two_args_scoping ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_application::procedure_with_two_args ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::overwrites_variables_in_captured_scopes ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::overwrites_variables ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::overwrites_variables_on_upper_scopes ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::unknown_variable ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_assignment::malformed_variable_name ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_conditionals::if_bad_arity ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_conditionals::if_with_one_arg ... ok
Dec 13 06:26:00.820 INFO [stdout] test eval_conditionals::if_with_two_args ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_boolean_procedures::or ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_boolean_procedures::not_bad_arity ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_boolean_procedures::not ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_assignment::wrong_arguments_number ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_boolean_procedures::and ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_procedure_with_fixed_args ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_bad_arity ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_procedure_with_any ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::division ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::division_bad_arity ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_with_two_args ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_procedure_with_varargs ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_with_one_arg_lambda ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::equal_sign ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_definitions::define_with_one_arg ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::plus_bad_arity ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::plus ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::greater_than_or_equal_sign ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::minus_bad_arity ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_integer_procedures::minus ... ok
Dec 13 06:26:00.839 INFO [stdout] test eval_lets::bad_arity_ ... ok
Dec 13 06:26:00.841 INFO [stdout] test eval_integer_procedures::greater_than_sign ... ok
Dec 13 06:26:00.841 INFO [stdout] test eval_integer_procedures::product ... ok
Dec 13 06:26:00.841 INFO [stdout] test eval_list_procedures::car_bad_arity ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_list_procedures::car_wrong_argument_type ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_list_procedures::cdr_wrong_argument_type ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_lets::malformed ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_lets::one_definition ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_list_procedures::cons_bad_arity ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_list_procedures::length ... ok
Dec 13 06:26:00.847 INFO [stdout] test eval_list_procedures::cons ... ok
Dec 13 06:26:00.849 INFO [stdout] test eval_list_procedures::length_bad_arguments ... ok
Dec 13 06:26:00.849 INFO [stdout] test eval_list_procedures::length_BadArity ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_list_procedures::list_ ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_list_procedures::list_question_mark ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_integer_procedures::less_than_sign ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_integer_procedures::less_than_or_equal_sign ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_list_procedures::car ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_list_procedures::cdr ... ok
Dec 13 06:26:00.855 INFO [stdout] test eval_list_procedures::pair_ ... ok
Dec 13 06:26:00.859 INFO [stdout] test eval_list_procedures::cdr_bad_arity ... ok
Dec 13 06:26:00.859 INFO [stdout] test eval_list_procedures::null_bad_arity ... ok
Dec 13 06:26:00.859 INFO [stdout] test eval_list_procedures::null ... ok
Dec 13 06:26:00.859 INFO [stdout] test eval_list_procedures::list_question_mark_bad_arity ... ok
Dec 13 06:26:00.859 INFO [stdout] test eval_list_procedures::pair_bad_arity ... ok
Dec 13 06:26:00.859 INFO [stdout] 
Dec 13 06:26:00.859 INFO [stdout] test result: ok. 73 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 13 06:26:00.859 INFO [stdout] 
Dec 13 06:26:00.873 INFO [stderr] su: No module specific data is present
Dec 13 06:26:01.468 INFO running `"docker" "inspect" "132de8630ec3718ec587d2b32f6a7fa4f959b1fe5ac3315b48eb25fad27ae31f"`
Dec 13 06:26:01.799 INFO running `"docker" "rm" "-f" "132de8630ec3718ec587d2b32f6a7fa4f959b1fe5ac3315b48eb25fad27ae31f"`
Dec 13 06:26:02.202 INFO [stdout] 132de8630ec3718ec587d2b32f6a7fa4f959b1fe5ac3315b48eb25fad27ae31f