Dec 14 22:28:14.613 INFO testing rspec-1.0.0-beta.4 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 22:28:14.613 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 22:28:15.096 INFO [stdout] 2700c71042f2e18b24e14e09bf4b37153f16e743dc10ee2a7e62d979fad8545d
Dec 14 22:28:15.099 INFO running `"docker" "start" "-a" "2700c71042f2e18b24e14e09bf4b37153f16e743dc10ee2a7e62d979fad8545d"`
Dec 14 22:28:16.642 INFO [stderr] usermod: no changes
Dec 14 22:28:16.859 INFO [stderr]    Compiling derive-new v0.5.6
Dec 14 22:28:20.252 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 14 22:28:22.510 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.84s
Dec 14 22:28:22.517 INFO [stderr] su: No module specific data is present
Dec 14 22:28:23.517 INFO running `"docker" "inspect" "2700c71042f2e18b24e14e09bf4b37153f16e743dc10ee2a7e62d979fad8545d"`
Dec 14 22:28:23.853 INFO running `"docker" "rm" "-f" "2700c71042f2e18b24e14e09bf4b37153f16e743dc10ee2a7e62d979fad8545d"`
Dec 14 22:28:24.203 INFO [stdout] 2700c71042f2e18b24e14e09bf4b37153f16e743dc10ee2a7e62d979fad8545d
Dec 14 22:28:24.205 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 22:28:24.775 INFO [stdout] db27f9ec6a373c73d8e8e22a8d94ad621ad08b2e9593c83c21e0fd416e61d65e
Dec 14 22:28:24.781 INFO running `"docker" "start" "-a" "db27f9ec6a373c73d8e8e22a8d94ad621ad08b2e9593c83c21e0fd416e61d65e"`
Dec 14 22:28:26.294 INFO [stderr] usermod: no changes
Dec 14 22:28:26.491 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 14 22:28:40.546 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.22s
Dec 14 22:28:40.554 INFO [stderr] su: No module specific data is present
Dec 14 22:28:41.204 INFO running `"docker" "inspect" "db27f9ec6a373c73d8e8e22a8d94ad621ad08b2e9593c83c21e0fd416e61d65e"`
Dec 14 22:28:41.457 INFO running `"docker" "rm" "-f" "db27f9ec6a373c73d8e8e22a8d94ad621ad08b2e9593c83c21e0fd416e61d65e"`
Dec 14 22:28:41.722 INFO [stdout] db27f9ec6a373c73d8e8e22a8d94ad621ad08b2e9593c83c21e0fd416e61d65e
Dec 14 22:28:41.725 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 22:28:42.125 INFO [stdout] d7ef496d328fbb4e5c835d62f46c75a5c222c4e6a80047e7fda3d341416e511e
Dec 14 22:28:42.130 INFO running `"docker" "start" "-a" "d7ef496d328fbb4e5c835d62f46c75a5c222c4e6a80047e7fda3d341416e511e"`
Dec 14 22:28:43.354 INFO [stderr] usermod: no changes
Dec 14 22:28:43.492 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 14 22:28:43.499 INFO [stderr]      Running /target/debug/deps/rspec-b5c4077656da7eef
Dec 14 22:28:43.499 INFO [stdout] 
Dec 14 22:28:43.499 INFO [stdout] running 48 tests
Dec 14 22:28:43.506 INFO [stdout] test block::context::tests::it_has_contexual_function_specify ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::suite::tests::empty_describe ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_root_functions ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_contextual_function_context ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_check_function_then ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_check_function_it ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_contexual_function_when ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::context::tests::it_has_check_function_example ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::suite::tests::empty_suite ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::suite::tests::non_empty_suite ... ok
Dec 14 22:28:43.511 INFO [stdout] test header::context::tests::label_fmt ... ok
Dec 14 22:28:43.511 INFO [stdout] test header::context::tests::header_fmt ... ok
Dec 14 22:28:43.511 INFO [stdout] test report::example::tests::from_void ... ok
Dec 14 22:28:43.511 INFO [stdout] test report::example::tests::from_result ... ok
Dec 14 22:28:43.511 INFO [stdout] test block::suite::tests::empty_given ... ok
Dec 14 22:28:43.511 INFO [stdout] test runner::configuration::tests::builder ... ok
Dec 14 22:28:43.511 INFO [stdout] test runner::configuration::tests::default ... ok
Dec 14 22:28:43.511 INFO [stdout] test runner::configuration::tests::default_with_builder ... ok
Dec 14 22:28:43.511 INFO [stdout] test header::example::tests::label_fmt ... ok
Dec 14 22:28:43.511 INFO [stdout] test header::suite::tests::header_fmt ... ok
Dec 14 22:28:43.511 INFO [stdout] test header::suite::tests::label_fmt ... ok
Dec 14 22:28:43.511 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_calls_observer_hooks ... ok
Dec 14 22:28:43.511 INFO [stdout] test report::example::tests::from_bool ... ok
Dec 14 22:28:43.511 INFO [stdout] test runner::tests::impl_visitor_block_for_runner::it_can_be_called ... ok
Dec 14 22:28:43.515 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_gives_an_env_to_the_example ... ok
Dec 14 22:28:43.515 INFO [stdout] test runner::tests::runner::broadcast::it_calls_the_closure ... ok
Dec 14 22:28:43.515 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_can_be_called ... ok
Dec 14 22:28:43.515 INFO [stdout] test runner::tests::runner::broadcast::it_calls_it_once_per_observer ... ok
Dec 14 22:28:43.515 INFO [stdout] test runner::tests::runner::it_can_be_instanciated ... ok
Dec 14 22:28:43.523 INFO [stdout] test runner::tests::runner::broadcast::it_gives_the_observer_as_callback_argument ... ok
Dec 14 22:28:43.523 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_before_all_hook_before_the_main_closure ... ok
Dec 14 22:28:43.523 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_before_all_callbacks ... ok
Dec 14 22:28:43.523 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_after_all_callbacks ... ok
Dec 14 22:28:43.523 INFO [stdout] test header::example::tests::header_fmt ... ok
Dec 14 22:28:43.523 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_after_all_hook_after_the_main_closure ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_before_all_callbacks ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_closure ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_all::it_can_be_called ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_after_each_hook_after_the_main_closure ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_after_all_callbacks ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_after_each_callbacks ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_before_each_callbacks ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_before_each_callbacks ... ok
Dec 14 22:28:43.539 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_closure ... ok
Dec 14 22:28:43.543 INFO [stdout] test runner::tests::runner::wrap_each::it_can_be_called ... ok
Dec 14 22:28:43.543 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_after_each_callbacks ... ok
Dec 14 22:28:43.543 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_before_each_hook_before_the_main_closure ... ok
Dec 14 22:28:43.589 INFO [stdout] test runner::tests::impl_drop_for_runner::it_should_abort ... ok
Dec 14 22:28:43.589 INFO [stdout] 
Dec 14 22:28:43.589 INFO [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 22:28:43.589 INFO [stdout] 
Dec 14 22:28:43.602 INFO [stderr]    Doc-tests rspec
Dec 14 22:28:46.002 INFO [stdout] 
Dec 14 22:28:46.002 INFO [stdout] running 10 tests
Dec 14 22:29:09.693 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_each (line 499) ... ok
Dec 14 22:29:11.464 INFO [stdout] test src/block/context.rs - block::context::Context<T>::context (line 75) ... ok
Dec 14 22:29:12.679 INFO [stdout] test src/block/context.rs - block::context::Context<T>::scope (line 162) ... ok
Dec 14 22:29:13.187 INFO [stdout] test src/block/suite.rs - block::suite::suite (line 41) ... ok
Dec 14 22:29:13.554 INFO [stdout] test src/block/context.rs - block::context::Context<T>::example (line 220) ... ok
Dec 14 22:29:14.363 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_all (line 436) ... ok
Dec 14 22:29:14.677 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_all (line 322) ... ok
Dec 14 22:29:15.203 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_each (line 385) ... ok
Dec 14 22:29:21.146 INFO [stdout] test src/logger/mod.rs - logger (line 5) ... ok
Dec 14 22:29:21.147 INFO [stdout] test src/lib.rs - run (line 39) ... ok
Dec 14 22:29:21.147 INFO [stdout] 
Dec 14 22:29:21.147 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 22:29:21.147 INFO [stdout] 
Dec 14 22:29:21.228 INFO [stderr] su: No module specific data is present
Dec 14 22:29:22.395 INFO running `"docker" "inspect" "d7ef496d328fbb4e5c835d62f46c75a5c222c4e6a80047e7fda3d341416e511e"`
Dec 14 22:29:22.685 INFO running `"docker" "rm" "-f" "d7ef496d328fbb4e5c835d62f46c75a5c222c4e6a80047e7fda3d341416e511e"`
Dec 14 22:29:22.982 INFO [stdout] d7ef496d328fbb4e5c835d62f46c75a5c222c4e6a80047e7fda3d341416e511e