Dec 07 01:30:25.332 INFO testing rspec-1.0.0-beta.4 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 01:30:25.332 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 07 01:30:25.780 INFO [stdout] 920fd0a5fb550a04812edd934e558b1411514c0c9e2279361f3619cddefcf60c
Dec 07 01:30:25.782 INFO running `"docker" "start" "-a" "920fd0a5fb550a04812edd934e558b1411514c0c9e2279361f3619cddefcf60c"`
Dec 07 01:30:27.456 INFO [stderr] usermod: no changes
Dec 07 01:30:27.616 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 07 01:30:30.151 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.65s
Dec 07 01:30:30.151 INFO [stderr] su: No module specific data is present
Dec 07 01:30:30.854 INFO running `"docker" "inspect" "920fd0a5fb550a04812edd934e558b1411514c0c9e2279361f3619cddefcf60c"`
Dec 07 01:30:31.108 INFO running `"docker" "rm" "-f" "920fd0a5fb550a04812edd934e558b1411514c0c9e2279361f3619cddefcf60c"`
Dec 07 01:30:31.436 INFO [stdout] 920fd0a5fb550a04812edd934e558b1411514c0c9e2279361f3619cddefcf60c
Dec 07 01:30:31.444 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 07 01:30:31.894 INFO [stdout] 316023a92ca9c61e2674e11ec6876b1d7a3bfdc1f4519475d9a79d0bc90c5464
Dec 07 01:30:31.896 INFO running `"docker" "start" "-a" "316023a92ca9c61e2674e11ec6876b1d7a3bfdc1f4519475d9a79d0bc90c5464"`
Dec 07 01:30:33.927 INFO [stderr] usermod: no changes
Dec 07 01:30:34.152 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 07 01:30:47.796 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.84s
Dec 07 01:30:47.808 INFO [stderr] su: No module specific data is present
Dec 07 01:30:48.708 INFO running `"docker" "inspect" "316023a92ca9c61e2674e11ec6876b1d7a3bfdc1f4519475d9a79d0bc90c5464"`
Dec 07 01:30:49.082 INFO running `"docker" "rm" "-f" "316023a92ca9c61e2674e11ec6876b1d7a3bfdc1f4519475d9a79d0bc90c5464"`
Dec 07 01:30:49.456 INFO [stdout] 316023a92ca9c61e2674e11ec6876b1d7a3bfdc1f4519475d9a79d0bc90c5464
Dec 07 01:30:49.460 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 07 01:30:49.882 INFO [stdout] c23cf02695bcfdb88c4beed3cbde887790c16d3c11b4f816ae75044a3f85767e
Dec 07 01:30:49.884 INFO running `"docker" "start" "-a" "c23cf02695bcfdb88c4beed3cbde887790c16d3c11b4f816ae75044a3f85767e"`
Dec 07 01:30:51.603 INFO [stderr] usermod: no changes
Dec 07 01:30:51.720 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 07 01:30:51.724 INFO [stderr]      Running /target/debug/deps/rspec-def5cd48312bd005
Dec 07 01:30:51.731 INFO [stdout] 
Dec 07 01:30:51.731 INFO [stdout] running 48 tests
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_check_function_then ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_check_function_example ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_check_function_it ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_contexual_function_specify ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_contexual_function_when ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::context::tests::it_has_root_functions ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::suite::tests::empty_given ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::suite::tests::empty_suite ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::suite::tests::empty_describe ... ok
Dec 07 01:30:51.734 INFO [stdout] test block::suite::tests::non_empty_suite ... ok
Dec 07 01:30:51.734 INFO [stdout] test header::context::tests::header_fmt ... ok
Dec 07 01:30:51.734 INFO [stdout] test header::context::tests::label_fmt ... ok
Dec 07 01:30:51.734 INFO [stdout] test header::example::tests::header_fmt ... ok
Dec 07 01:30:51.734 INFO [stdout] test header::example::tests::label_fmt ... ok
Dec 07 01:30:51.734 INFO [stdout] test header::suite::tests::label_fmt ... ok
Dec 07 01:30:51.734 INFO [stdout] test report::example::tests::from_bool ... ok
Dec 07 01:30:51.734 INFO [stdout] test report::example::tests::from_result ... ok
Dec 07 01:30:51.734 INFO [stdout] test report::example::tests::from_void ... ok
Dec 07 01:30:51.735 INFO [stdout] test header::suite::tests::header_fmt ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::configuration::tests::builder ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::configuration::tests::default ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::configuration::tests::default_with_builder ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::impl_visitor_block_for_runner::it_can_be_called ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_calls_observer_hooks ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_gives_an_env_to_the_example ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::runner::broadcast::it_gives_the_observer_as_callback_argument ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::runner::broadcast::it_calls_it_once_per_observer ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_can_be_called ... ok
Dec 07 01:30:51.735 INFO [stdout] test block::context::tests::it_has_contextual_function_context ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::runner::broadcast::it_calls_the_closure ... ok
Dec 07 01:30:51.735 INFO [stdout] test runner::tests::runner::it_can_be_instanciated ... ok
Dec 07 01:30:51.740 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_before_all_callbacks ... ok
Dec 07 01:30:51.740 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_closure ... ok
Dec 07 01:30:51.740 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_before_all_hook_before_the_main_closure ... ok
Dec 07 01:30:51.740 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_after_all_callbacks ... ok
Dec 07 01:30:51.744 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_before_all_callbacks ... ok
Dec 07 01:30:51.744 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_after_all_callbacks ... ok
Dec 07 01:30:51.744 INFO [stdout] test runner::tests::runner::wrap_all::it_can_be_called ... ok
Dec 07 01:30:51.744 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_after_each_hook_after_the_main_closure ... ok
Dec 07 01:30:51.744 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_after_all_hook_after_the_main_closure ... ok
Dec 07 01:30:51.748 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_before_each_callbacks ... ok
Dec 07 01:30:51.748 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_after_each_callbacks ... ok
Dec 07 01:30:51.748 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_before_each_hook_before_the_main_closure ... ok
Dec 07 01:30:51.748 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_after_each_callbacks ... ok
Dec 07 01:30:51.749 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_before_each_callbacks ... ok
Dec 07 01:30:51.749 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_closure ... ok
Dec 07 01:30:51.749 INFO [stdout] test runner::tests::runner::wrap_each::it_can_be_called ... ok
Dec 07 01:30:51.852 INFO [stdout] test runner::tests::impl_drop_for_runner::it_should_abort ... ok
Dec 07 01:30:51.852 INFO [stdout] 
Dec 07 01:30:51.852 INFO [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 01:30:51.852 INFO [stdout] 
Dec 07 01:30:51.856 INFO [stderr]    Doc-tests rspec
Dec 07 01:30:54.152 INFO [stdout] 
Dec 07 01:30:54.152 INFO [stdout] running 10 tests
Dec 07 01:31:17.677 INFO [stdout] test src/block/context.rs - block::context::Context<T>::context (line 75) ... ok
Dec 07 01:31:20.580 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_all (line 436) ... ok
Dec 07 01:31:21.305 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_each (line 499) ... ok
Dec 07 01:31:21.305 INFO [stdout] test src/block/context.rs - block::context::Context<T>::scope (line 162) ... ok
Dec 07 01:31:21.412 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_all (line 322) ... ok
Dec 07 01:31:22.008 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_each (line 385) ... ok
Dec 07 01:31:22.274 INFO [stdout] test src/block/context.rs - block::context::Context<T>::example (line 220) ... ok
Dec 07 01:31:22.684 INFO [stdout] test src/block/suite.rs - block::suite::suite (line 41) ... ok
Dec 07 01:31:27.744 INFO [stdout] test src/lib.rs - run (line 39) ... ok
Dec 07 01:31:27.892 INFO [stdout] test src/logger/mod.rs - logger (line 5) ... ok
Dec 07 01:31:27.892 INFO [stdout] 
Dec 07 01:31:27.892 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 01:31:27.892 INFO [stdout] 
Dec 07 01:31:27.936 INFO [stderr] su: No module specific data is present
Dec 07 01:31:28.984 INFO running `"docker" "inspect" "c23cf02695bcfdb88c4beed3cbde887790c16d3c11b4f816ae75044a3f85767e"`
Dec 07 01:31:29.278 INFO running `"docker" "rm" "-f" "c23cf02695bcfdb88c4beed3cbde887790c16d3c11b4f816ae75044a3f85767e"`
Dec 07 01:31:29.489 INFO [stdout] c23cf02695bcfdb88c4beed3cbde887790c16d3c11b4f816ae75044a3f85767e