Dec 14 22:28:35.095 INFO testing rspec-1.0.0-beta.4 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 22:28:35.095 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14 22:28:35.563 INFO [stdout] 65e4be3929f2f6f3138cdec2ff968d8fa06cc77cab7b099a8ee643a79a295926
Dec 14 22:28:35.565 INFO running `"docker" "start" "-a" "65e4be3929f2f6f3138cdec2ff968d8fa06cc77cab7b099a8ee643a79a295926"`
Dec 14 22:28:37.983 INFO [stderr] usermod: no changes
Dec 14 22:28:38.151 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 14 22:28:40.623 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.62s
Dec 14 22:28:40.639 INFO [stderr] su: No module specific data is present
Dec 14 22:28:41.628 INFO running `"docker" "inspect" "65e4be3929f2f6f3138cdec2ff968d8fa06cc77cab7b099a8ee643a79a295926"`
Dec 14 22:28:41.913 INFO running `"docker" "rm" "-f" "65e4be3929f2f6f3138cdec2ff968d8fa06cc77cab7b099a8ee643a79a295926"`
Dec 14 22:28:42.238 INFO [stdout] 65e4be3929f2f6f3138cdec2ff968d8fa06cc77cab7b099a8ee643a79a295926
Dec 14 22:28:42.245 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14 22:28:42.688 INFO [stdout] e1a6725383b78662dc10a0bc1f5aa119623966083907c7f06ba723ed5707c6ef
Dec 14 22:28:42.690 INFO running `"docker" "start" "-a" "e1a6725383b78662dc10a0bc1f5aa119623966083907c7f06ba723ed5707c6ef"`
Dec 14 22:28:44.614 INFO [stderr] usermod: no changes
Dec 14 22:28:44.775 INFO [stderr]    Compiling rspec v1.0.0-beta.4 (/source)
Dec 14 22:28:59.971 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.33s
Dec 14 22:28:59.984 INFO [stderr] su: No module specific data is present
Dec 14 22:29:01.103 INFO running `"docker" "inspect" "e1a6725383b78662dc10a0bc1f5aa119623966083907c7f06ba723ed5707c6ef"`
Dec 14 22:29:01.543 INFO running `"docker" "rm" "-f" "e1a6725383b78662dc10a0bc1f5aa119623966083907c7f06ba723ed5707c6ef"`
Dec 14 22:29:01.839 INFO [stdout] e1a6725383b78662dc10a0bc1f5aa119623966083907c7f06ba723ed5707c6ef
Dec 14 22:29:01.841 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14 22:29:02.295 INFO [stdout] b582c73101e91fd70ef7dc83ef5270818992ecc05ee467d32aed3fea2a89326d
Dec 14 22:29:02.303 INFO running `"docker" "start" "-a" "b582c73101e91fd70ef7dc83ef5270818992ecc05ee467d32aed3fea2a89326d"`
Dec 14 22:29:03.874 INFO [stderr] usermod: no changes
Dec 14 22:29:03.992 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 14 22:29:03.996 INFO [stderr]      Running /target/debug/deps/rspec-02ee657d33cef857
Dec 14 22:29:04.003 INFO [stdout] 
Dec 14 22:29:04.003 INFO [stdout] running 48 tests
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_check_function_example ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_check_function_then ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_check_function_it ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::suite::tests::empty_given ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::suite::tests::non_empty_suite ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::suite::tests::empty_suite ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::context::tests::header_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::context::tests::label_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::example::tests::header_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::example::tests::label_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::suite::tests::header_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test header::suite::tests::label_fmt ... ok
Dec 14 22:29:04.003 INFO [stdout] test report::example::tests::from_void ... ok
Dec 14 22:29:04.003 INFO [stdout] test report::example::tests::from_bool ... ok
Dec 14 22:29:04.003 INFO [stdout] test report::example::tests::from_result ... ok
Dec 14 22:29:04.003 INFO [stdout] test runner::configuration::tests::default ... ok
Dec 14 22:29:04.003 INFO [stdout] test runner::configuration::tests::builder ... ok
Dec 14 22:29:04.003 INFO [stdout] test runner::configuration::tests::default_with_builder ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_contexual_function_specify ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_contexual_function_when ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::context::tests::it_has_root_functions ... ok
Dec 14 22:29:04.003 INFO [stdout] test block::suite::tests::empty_describe ... ok
Dec 14 22:29:04.007 INFO [stdout] test block::context::tests::it_has_contextual_function_context ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::broadcast::it_gives_the_observer_as_callback_argument ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::it_can_be_instanciated ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_after_all_hook_after_the_main_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_after_all_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_all_before_all_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_before_all_hook_before_the_main_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_after_all_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_before_all_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_calls_the_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_all::it_can_be_called ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_after_each_hook_after_the_main_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_after_each_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_all_before_each_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_before_each_hook_before_the_main_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_after_each_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_before_each_callbacks ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_calls_the_closure ... ok
Dec 14 22:29:04.007 INFO [stdout] test runner::tests::runner::wrap_each::it_can_be_called ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::impl_visitor_block_for_runner::it_can_be_called ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_calls_observer_hooks ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::runner::broadcast::it_calls_the_closure ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::runner::broadcast::it_calls_it_once_per_observer ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_gives_an_env_to_the_example ... ok
Dec 14 22:29:04.011 INFO [stdout] test runner::tests::impl_visitor_example_for_runner::it_can_be_called ... ok
Dec 14 22:29:04.155 INFO [stdout] test runner::tests::impl_drop_for_runner::it_should_abort ... ok
Dec 14 22:29:04.155 INFO [stdout] 
Dec 14 22:29:04.155 INFO [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 22:29:04.155 INFO [stdout] 
Dec 14 22:29:04.165 INFO [stderr]    Doc-tests rspec
Dec 14 22:29:06.647 INFO [stdout] 
Dec 14 22:29:06.647 INFO [stdout] running 10 tests
Dec 14 22:29:29.115 INFO [stdout] test src/block/context.rs - block::context::Context<T>::example (line 220) ... ok
Dec 14 22:29:29.996 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_all (line 322) ... ok
Dec 14 22:29:30.627 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_all (line 436) ... ok
Dec 14 22:29:31.490 INFO [stdout] test src/block/context.rs - block::context::Context<T>::context (line 75) ... ok
Dec 14 22:29:32.501 INFO [stdout] test src/block/context.rs - block::context::Context<T>::after_each (line 499) ... ok
Dec 14 22:29:32.775 INFO [stdout] test src/block/suite.rs - block::suite::suite (line 41) ... ok
Dec 14 22:29:33.470 INFO [stdout] test src/block/context.rs - block::context::Context<T>::scope (line 162) ... ok
Dec 14 22:29:33.637 INFO [stdout] test src/block/context.rs - block::context::Context<T>::before_each (line 385) ... ok
Dec 14 22:29:38.959 INFO [stdout] test src/logger/mod.rs - logger (line 5) ... ok
Dec 14 22:29:39.810 INFO [stdout] test src/lib.rs - run (line 39) ... ok
Dec 14 22:29:39.811 INFO [stdout] 
Dec 14 22:29:39.811 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 22:29:39.811 INFO [stdout] 
Dec 14 22:29:39.902 INFO [stderr] su: No module specific data is present
Dec 14 22:29:40.811 INFO running `"docker" "inspect" "b582c73101e91fd70ef7dc83ef5270818992ecc05ee467d32aed3fea2a89326d"`
Dec 14 22:29:41.005 INFO running `"docker" "rm" "-f" "b582c73101e91fd70ef7dc83ef5270818992ecc05ee467d32aed3fea2a89326d"`
Dec 14 22:29:41.252 INFO [stdout] b582c73101e91fd70ef7dc83ef5270818992ecc05ee467d32aed3fea2a89326d