Dec 14 17:15:57.641 INFO testing test-case-derive-0.2.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 17:15:57.641 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 17:15:57.991 INFO [stdout] 208037d87db1bc939c6441a77e27379aa61d10d6296bbf1d1d71e70d1fff64b6
Dec 14 17:15:57.996 INFO running `"docker" "start" "-a" "208037d87db1bc939c6441a77e27379aa61d10d6296bbf1d1d71e70d1fff64b6"`
Dec 14 17:15:59.215 INFO [stderr] usermod: no changes
Dec 14 17:15:59.295 INFO [stderr]    Compiling test-case-derive v0.2.1 (/source)
Dec 14 17:16:00.050 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Dec 14 17:16:00.050 INFO [stderr]  --> src/prelude.rs:3:9
Dec 14 17:16:00.050 INFO [stderr]   |
Dec 14 17:16:00.050 INFO [stderr] 3 | pub use std::ascii::AsciiExt;
Dec 14 17:16:00.050 INFO [stderr]   |         ^^^^^^^^^^^^^^^^^^^^
Dec 14 17:16:00.050 INFO [stderr]   |
Dec 14 17:16:00.050 INFO [stderr]   = note: #[warn(deprecated)] on by default
Dec 14 17:16:00.050 INFO [stderr] 
Dec 14 17:16:00.207 INFO [stderr] warning: the feature `proc_macro` has been stable since 1.29.0 and no longer requires an attribute to enable
Dec 14 17:16:00.207 INFO [stderr]    --> src/lib.rs:214:12
Dec 14 17:16:00.207 INFO [stderr]     |
Dec 14 17:16:00.207 INFO [stderr] 214 | #![feature(proc_macro)]
Dec 14 17:16:00.207 INFO [stderr]     |            ^^^^^^^^^^
Dec 14 17:16:00.207 INFO [stderr]     |
Dec 14 17:16:00.207 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 14 17:16:00.207 INFO [stderr] 
Dec 14 17:16:01.682 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.43s
Dec 14 17:16:01.693 INFO [stderr] su: No module specific data is present
Dec 14 17:16:02.421 INFO running `"docker" "inspect" "208037d87db1bc939c6441a77e27379aa61d10d6296bbf1d1d71e70d1fff64b6"`
Dec 14 17:16:02.755 INFO running `"docker" "rm" "-f" "208037d87db1bc939c6441a77e27379aa61d10d6296bbf1d1d71e70d1fff64b6"`
Dec 14 17:16:03.019 INFO [stdout] 208037d87db1bc939c6441a77e27379aa61d10d6296bbf1d1d71e70d1fff64b6
Dec 14 17:16:03.025 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 17:16:03.489 INFO [stdout] 48769d8e96ff8e62c55e9d5ef32638b5779f307c5bbc855b85ab092d71c7cf77
Dec 14 17:16:03.489 INFO running `"docker" "start" "-a" "48769d8e96ff8e62c55e9d5ef32638b5779f307c5bbc855b85ab092d71c7cf77"`
Dec 14 17:16:04.576 INFO [stderr] usermod: no changes
Dec 14 17:16:04.691 INFO [stderr]    Compiling test-case-derive v0.2.1 (/source)
Dec 14 17:16:05.847 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Dec 14 17:16:05.847 INFO [stderr]  --> src/prelude.rs:3:9
Dec 14 17:16:05.847 INFO [stderr]   |
Dec 14 17:16:05.847 INFO [stderr] 3 | pub use std::ascii::AsciiExt;
Dec 14 17:16:05.847 INFO [stderr]   |         ^^^^^^^^^^^^^^^^^^^^
Dec 14 17:16:05.847 INFO [stderr]   |
Dec 14 17:16:05.847 INFO [stderr]   = note: #[warn(deprecated)] on by default
Dec 14 17:16:05.847 INFO [stderr] 
Dec 14 17:16:05.915 INFO [stderr] warning: the feature `proc_macro` has been stable since 1.29.0 and no longer requires an attribute to enable
Dec 14 17:16:05.915 INFO [stderr]  --> tests/test_cases.rs:2:12
Dec 14 17:16:05.915 INFO [stderr]   |
Dec 14 17:16:05.915 INFO [stderr] 2 | #![feature(proc_macro)]
Dec 14 17:16:05.915 INFO [stderr]   |            ^^^^^^^^^^
Dec 14 17:16:05.915 INFO [stderr]   |
Dec 14 17:16:05.915 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 14 17:16:05.915 INFO [stderr] 
Dec 14 17:16:06.039 INFO [stderr] warning: the feature `proc_macro` has been stable since 1.29.0 and no longer requires an attribute to enable
Dec 14 17:16:06.039 INFO [stderr]    --> src/lib.rs:214:12
Dec 14 17:16:06.039 INFO [stderr]     |
Dec 14 17:16:06.039 INFO [stderr] 214 | #![feature(proc_macro)]
Dec 14 17:16:06.039 INFO [stderr]     |            ^^^^^^^^^^
Dec 14 17:16:06.039 INFO [stderr]     |
Dec 14 17:16:06.039 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 14 17:16:06.039 INFO [stderr] 
Dec 14 17:16:07.199 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.61s
Dec 14 17:16:07.206 INFO [stderr] su: No module specific data is present
Dec 14 17:16:08.071 INFO running `"docker" "inspect" "48769d8e96ff8e62c55e9d5ef32638b5779f307c5bbc855b85ab092d71c7cf77"`
Dec 14 17:16:08.357 INFO running `"docker" "rm" "-f" "48769d8e96ff8e62c55e9d5ef32638b5779f307c5bbc855b85ab092d71c7cf77"`
Dec 14 17:16:08.739 INFO [stdout] 48769d8e96ff8e62c55e9d5ef32638b5779f307c5bbc855b85ab092d71c7cf77
Dec 14 17:16:08.745 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 17:16:09.116 INFO [stdout] 9b8f15cda0148af32f98165dbfb25104f784ab255106241a62d012423f2a2dcb
Dec 14 17:16:09.117 INFO running `"docker" "start" "-a" "9b8f15cda0148af32f98165dbfb25104f784ab255106241a62d012423f2a2dcb"`
Dec 14 17:16:10.489 INFO [stderr] usermod: no changes
Dec 14 17:16:10.547 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 14 17:16:10.547 INFO [stderr]      Running /target/debug/deps/test_case_derive-2b12ef3cb0c9cb2f
Dec 14 17:16:10.549 INFO [stdout] 
Dec 14 17:16:10.549 INFO [stdout] running 0 tests
Dec 14 17:16:10.549 INFO [stdout] 
Dec 14 17:16:10.549 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:16:10.549 INFO [stdout] 
Dec 14 17:16:10.549 INFO [stderr]      Running /target/debug/deps/acceptance_tests-d62166f465af7a6a
Dec 14 17:16:10.551 INFO [stdout] 
Dec 14 17:16:10.551 INFO [stdout] running 7 tests
Dec 14 17:16:10.647 INFO [stdout] test acceptance::runs_all_tests ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::removes_repeated_underscores ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::marks_inconclusive_tests_as_ignored ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::lowers_test_case_name ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::escapes_rust_keywords ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::escapes_names_starting_with_digit ... ok
Dec 14 17:16:10.647 INFO [stdout] test acceptance::escapes_unnecessary_leading_underscore ... ok
Dec 14 17:16:10.647 INFO [stdout] 
Dec 14 17:16:10.647 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:16:10.647 INFO [stdout] 
Dec 14 17:16:10.651 INFO [stderr]      Running /target/debug/deps/test_cases-9f7903603565a390
Dec 14 17:16:10.651 INFO [stdout] 
Dec 14 17:16:10.651 INFO [stdout] running 32 tests
Dec 14 17:16:10.651 INFO [stdout] test test_cases::abs_tests::returns_0_for_0 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::arg_expressions::_2_4_6_to_string ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::basic_test::_1 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::fancy_addition::some_2_some_3 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::inconclusive_tests::this_test_is_inconclusive_and_will_always_be ... ignored
Dec 14 17:16:10.651 INFO [stdout] test test_cases::fancy_addition::treats_none_as_0 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::lowercase_test_name::dummy_code ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::multiple_test_cases::_4 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::multiplication_tests::when_both_operands_are_negative ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::multiplication_tests::when_both_operands_are_possitive ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::multiplication_tests::when_operands_are_swapped ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::name::_2nd_test ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::name::_4_5 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::name::test_1_8_9 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::name::test_3_6_9 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::nested::nested_test_case::_1_1 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::nested::using_fn_from_super::_20_22 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::nested::using_fn_from_super::_42 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result::_2 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result::_4 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result_and_name::_4_5 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result_and_name::test_no_1 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result_expresion_with_name::test_result_expression ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result_expression::_2_2 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::result_with_mod_sep::_42 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::abs_tests::returns_given_number_for_positive_input ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::abs_tests::returns_opposite_number_for_non_positive_input ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::fancy_addition::some_2_3_some_4 ... ok
Dec 14 17:16:10.651 INFO [stdout] test test_cases::multiple_test_cases::_2 ... ok
Dec 14 17:16:10.667 INFO [stdout] test test_cases::inconclusive_tests::should_not_take_into_account_keyword_on_argument_position ... ok
Dec 14 17:16:10.667 INFO [stdout] test test_cases::leading_underscore_in_test_name::dummy ... ok
Dec 14 17:16:10.667 INFO [stdout] test test_cases::keyword_test::_true ... ok
Dec 14 17:16:10.667 INFO [stdout] 
Dec 14 17:16:10.667 INFO [stdout] test result: ok. 31 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 14 17:16:10.667 INFO [stdout] 
Dec 14 17:16:10.675 INFO [stderr] su: No module specific data is present
Dec 14 17:16:11.625 INFO running `"docker" "inspect" "9b8f15cda0148af32f98165dbfb25104f784ab255106241a62d012423f2a2dcb"`
Dec 14 17:16:11.891 INFO running `"docker" "rm" "-f" "9b8f15cda0148af32f98165dbfb25104f784ab255106241a62d012423f2a2dcb"`
Dec 14 17:16:12.247 INFO [stdout] 9b8f15cda0148af32f98165dbfb25104f784ab255106241a62d012423f2a2dcb