Nov 18 01:10:38.295 INFO testing rspec-1.0.0-beta.4 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 01:10:38.297 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 01:10:38.877 INFO [stdout] e189aeaeb59a48b42eaad1569e4c23fbb255eea1be6f7ab74c76829d0190fa71
Nov 18 01:10:38.879 INFO running `"docker" "start" "-a" "e189aeaeb59a48b42eaad1569e4c23fbb255eea1be6f7ab74c76829d0190fa71"`
Nov 18 01:10:40.735 INFO [stderr] usermod: no changes
Nov 18 01:10:40.922 INFO [stderr]    Compiling derive-new v0.5.6
Nov 18 01:10:46.887 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Nov 18 01:10:49.304 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.54s
Nov 18 01:10:49.327 INFO [stderr] su: No module specific data is present
Nov 18 01:10:50.273 INFO running `"docker" "inspect" "e189aeaeb59a48b42eaad1569e4c23fbb255eea1be6f7ab74c76829d0190fa71"`
Nov 18 01:10:50.582 INFO running `"docker" "rm" "-f" "e189aeaeb59a48b42eaad1569e4c23fbb255eea1be6f7ab74c76829d0190fa71"`
Nov 18 01:10:50.859 INFO [stdout] e189aeaeb59a48b42eaad1569e4c23fbb255eea1be6f7ab74c76829d0190fa71
Nov 18 01:10:50.861 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 01:10:51.508 INFO [stdout] 960ef6ffcdc87cdccaa6b167f35661c09641c73b240d6d35d167eeeffa889090
Nov 18 01:10:51.511 INFO running `"docker" "start" "-a" "960ef6ffcdc87cdccaa6b167f35661c09641c73b240d6d35d167eeeffa889090"`
Nov 18 01:10:52.871 INFO [stderr] usermod: no changes
Nov 18 01:10:53.054 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Nov 18 01:11:05.207 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.30s
Nov 18 01:11:05.208 INFO [stderr] su: No module specific data is present
Nov 18 01:11:05.915 INFO running `"docker" "inspect" "960ef6ffcdc87cdccaa6b167f35661c09641c73b240d6d35d167eeeffa889090"`
Nov 18 01:11:06.177 INFO running `"docker" "rm" "-f" "960ef6ffcdc87cdccaa6b167f35661c09641c73b240d6d35d167eeeffa889090"`
Nov 18 01:11:06.471 INFO [stdout] 960ef6ffcdc87cdccaa6b167f35661c09641c73b240d6d35d167eeeffa889090
Nov 18 01:11:06.473 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 01:11:06.825 INFO [stdout] c570b2662a6e2c8fc4be1c11c4f42a93593f2d9326d5b0c3e644627fdb4f1391
Nov 18 01:11:06.827 INFO running `"docker" "start" "-a" "c570b2662a6e2c8fc4be1c11c4f42a93593f2d9326d5b0c3e644627fdb4f1391"`
Nov 18 01:11:07.987 INFO [stderr] usermod: no changes
Nov 18 01:11:08.197 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Nov 18 01:11:08.209 INFO [stderr]      Running /target/debug/deps/rspec-e20f3f6e442ff65a
Nov 18 01:11:08.212 INFO [stdout] 
Nov 18 01:11:08.212 INFO [stdout] running 48 tests
Nov 18 01:11:08.212 INFO [stdout] test block::context::tests::it_has_check_function_example ... ok
Nov 18 01:11:08.212 INFO [stdout] test block::context::tests::it_has_check_function_it ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::context::tests::it_has_contexual_function_specify ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::context::tests::it_has_root_functions ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::suite::tests::empty_given ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::context::tests::it_has_contextual_function_context ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::suite::tests::empty_describe ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::context::tests::it_has_contexual_function_when ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::example::tests::header_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::example::tests::label_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::context::tests::header_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::context::tests::label_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::suite::tests::header_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::suite::tests::non_empty_suite ... ok
Nov 18 01:11:08.219 INFO [stdout] test block::suite::tests::empty_suite ... ok
Nov 18 01:11:08.219 INFO [stdout] test header::suite::tests::label_fmt ... ok
Nov 18 01:11:08.219 INFO [stdout] test report::example::tests::from_bool ... ok
Nov 18 01:11:08.219 INFO [stdout] test report::example::tests::from_result ... ok
Nov 18 01:11:08.219 INFO [stdout] test report::example::tests::from_void ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::configuration::tests::builder ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::configuration::tests::default ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::configuration::tests::default_with_builder ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::impl_visitor_block_for_runner::it_can_be_called ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_calls_observer_hooks ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_can_be_called ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_gives_an_env_to_the_example ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::runner::broadcast::it_calls_it_once_per_observer ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::runner::broadcast::it_calls_the_closure ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_after_all_hook_after_the_main_closure ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_after_all_callbacks ... ok
Nov 18 01:11:08.219 INFO [stdout] test runner::tests::runner::broadcast::it_gives_the_observer_as_callback_argument ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_before_all_callbacks ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_before_all_hook_before_the_main_closure ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_after_all_callbacks ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_before_all_callbacks ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_closure ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_all::it_can_be_called ... ok
Nov 18 01:11:08.220 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_after_each_hook_after_the_main_closure ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::it_can_be_instanciated ... ok
Nov 18 01:11:08.231 INFO [stdout] test block::context::tests::it_has_check_function_then ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_after_each_callbacks ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_before_each_callbacks ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_before_each_hook_before_the_main_closure ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_after_each_callbacks ... ok
Nov 18 01:11:08.231 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_before_each_callbacks ... ok
Nov 18 01:11:08.267 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_closure ... ok
Nov 18 01:11:08.267 INFO [stdout] test runner::tests::runner::wrap_each::it_can_be_called ... ok
Nov 18 01:11:08.303 INFO [stdout] test runner::tests::impl_drop_for_runner::it_should_abort ... ok
Nov 18 01:11:08.303 INFO [stderr]    Doc-tests rspec
Nov 18 01:11:08.303 INFO [stdout] 
Nov 18 01:11:08.303 INFO [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 01:11:08.303 INFO [stdout] 
Nov 18 01:11:10.237 INFO [stdout] 
Nov 18 01:11:10.237 INFO [stdout] running 10 tests
Nov 18 01:11:29.907 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_all (line 322) ... ok
Nov 18 01:11:33.919 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_each (line 499) ... ok
Nov 18 01:11:35.011 INFO [stdout] test src/block/suite.rs - block::suite::suite (line 41) ... ok
Nov 18 01:11:35.375 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_each (line 385) ... ok
Nov 18 01:11:37.881 INFO [stdout] test src/block/context.rs - block::context::Context<T>::context (line 75) ... ok
Nov 18 01:11:37.891 INFO [stdout] test src/block/context.rs - block::context::Context<T>::example (line 220) ... ok
Nov 18 01:11:38.683 INFO [stdout] test src/block/context.rs - block::context::Context<T>::scope (line 162) ... ok
Nov 18 01:11:39.231 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_all (line 436) ... ok
Nov 18 01:11:42.787 INFO [stdout] test src/lib.rs - run (line 39) ... ok
Nov 18 01:11:42.867 INFO [stdout] test src/logger/mod.rs - logger (line 5) ... ok
Nov 18 01:11:42.867 INFO [stdout] 
Nov 18 01:11:42.867 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 01:11:42.867 INFO [stdout] 
Nov 18 01:11:42.915 INFO [stderr] su: No module specific data is present
Nov 18 01:11:43.567 INFO running `"docker" "inspect" "c570b2662a6e2c8fc4be1c11c4f42a93593f2d9326d5b0c3e644627fdb4f1391"`
Nov 18 01:11:43.801 INFO running `"docker" "rm" "-f" "c570b2662a6e2c8fc4be1c11c4f42a93593f2d9326d5b0c3e644627fdb4f1391"`
Nov 18 01:11:44.215 INFO [stdout] c570b2662a6e2c8fc4be1c11c4f42a93593f2d9326d5b0c3e644627fdb4f1391