Dec 13 05:05:02.727 INFO testing rust-lang-nursery/mdBook against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 05:05:02.727 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 13 05:05:03.228 INFO [stdout] 949ad43c6aa98a0cbcf416b1354614ec3b2e46e6c70831b9158c2af4857a2c65
Dec 13 05:05:03.230 INFO running `"docker" "start" "-a" "949ad43c6aa98a0cbcf416b1354614ec3b2e46e6c70831b9158c2af4857a2c65"`
Dec 13 05:05:05.971 INFO [stderr] usermod: no changes
Dec 13 05:05:06.409 INFO [stderr]    Compiling utf-8 v0.7.4
Dec 13 05:05:06.423 INFO [stderr]    Compiling pulldown-cmark v0.1.2
Dec 13 05:05:06.423 INFO [stderr]    Compiling same-file v1.0.3
Dec 13 05:05:06.423 INFO [stderr]    Compiling sequence_trie v0.3.5
Dec 13 05:05:06.431 INFO [stderr]    Compiling is-match v0.1.0
Dec 13 05:05:06.439 INFO [stderr]    Compiling strum v0.9.0
Dec 13 05:05:06.463 INFO [stderr]    Compiling open v1.2.2
Dec 13 05:05:06.519 INFO [stderr]    Compiling inotify-sys v0.1.3
Dec 13 05:05:07.904 INFO [stderr]    Compiling filetime v0.2.1
Dec 13 05:05:08.099 INFO [stderr]    Compiling log v0.3.9
Dec 13 05:05:08.456 INFO [stderr]    Compiling mio v0.6.16
Dec 13 05:05:08.929 INFO [stderr]    Compiling new_debug_unreachable v1.0.1
Dec 13 05:05:09.856 INFO [stderr]    Compiling tokio-io v0.1.9
Dec 13 05:05:09.887 INFO [stderr]    Compiling pest_derive v1.0.8
Dec 13 05:05:09.907 INFO [stderr]    Compiling syn v0.15.12
Dec 13 05:05:10.059 INFO [stderr]    Compiling syn v0.14.9
Dec 13 05:05:13.177 INFO [stderr]    Compiling walkdir v2.2.5
Dec 13 05:05:13.581 INFO [stderr]    Compiling futf v0.1.4
Dec 13 05:05:21.612 INFO [stderr]    Compiling error-chain v0.12.0
Dec 13 05:05:21.640 INFO [stderr]    Compiling string_cache_codegen v0.4.1
Dec 13 05:05:35.591 INFO [stderr]    Compiling env_logger v0.5.13
Dec 13 05:05:36.455 INFO [stderr]    Compiling mime v0.2.6
Dec 13 05:05:36.592 INFO [stderr]    Compiling tendril v0.4.0
Dec 13 05:05:52.664 INFO [stderr]    Compiling mime_guess v1.8.6
Dec 13 05:05:52.932 INFO [stderr]    Compiling toml-query v0.7.0
Dec 13 05:05:53.045 INFO [stderr]    Compiling mio-extras v2.0.5
Dec 13 05:05:53.181 INFO [stderr]    Compiling string_cache v0.7.3
Dec 13 05:05:53.847 INFO [stderr]    Compiling hyper v0.10.13
Dec 13 05:05:54.164 INFO [stderr]    Compiling handlebars v1.0.5
Dec 13 05:05:54.166 INFO [stderr]    Compiling strum_macros v0.9.1
Dec 13 05:05:56.624 INFO [stderr]    Compiling tokio-reactor v0.1.6
Dec 13 05:06:01.539 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 13 05:06:01.617 INFO [stderr]    Compiling ws v0.7.9
Dec 13 05:06:09.375 INFO [stderr]    Compiling inotify v0.6.1
Dec 13 05:06:16.671 INFO [stderr]    Compiling notify v4.0.6
Dec 13 05:06:37.422 INFO [stderr]    Compiling iron v0.6.0
Dec 13 05:06:41.820 INFO [stderr]    Compiling mount v0.4.0
Dec 13 05:06:48.023 INFO [stderr]    Compiling staticfile v0.5.0
Dec 13 05:06:51.250 INFO [stderr]    Compiling markup5ever v0.7.3
Dec 13 05:06:51.250 INFO [stderr]    Compiling elasticlunr-rs v2.3.3
Dec 13 05:07:04.171 INFO [stderr]    Compiling html5ever v0.22.3
Dec 13 05:07:07.671 INFO [stderr]    Compiling ammonia v1.2.0
Dec 13 05:07:13.258 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 13 05:07:42.520 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 36s
Dec 13 05:07:42.550 INFO [stderr] su: No module specific data is present
Dec 13 05:07:44.368 INFO running `"docker" "inspect" "949ad43c6aa98a0cbcf416b1354614ec3b2e46e6c70831b9158c2af4857a2c65"`
Dec 13 05:07:44.753 INFO running `"docker" "rm" "-f" "949ad43c6aa98a0cbcf416b1354614ec3b2e46e6c70831b9158c2af4857a2c65"`
Dec 13 05:07:45.051 INFO [stdout] 949ad43c6aa98a0cbcf416b1354614ec3b2e46e6c70831b9158c2af4857a2c65
Dec 13 05:07:45.059 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 13 05:07:45.687 INFO [stdout] a0d20ae94f6ecb6979176376c1de2eede256a10897b6304534c00ca3c1caf9ac
Dec 13 05:07:45.689 INFO running `"docker" "start" "-a" "a0d20ae94f6ecb6979176376c1de2eede256a10897b6304534c00ca3c1caf9ac"`
Dec 13 05:07:48.095 INFO [stderr] usermod: no changes
Dec 13 05:07:48.611 INFO [stderr]    Compiling bit-set v0.4.0
Dec 13 05:07:48.611 INFO [stderr]    Compiling pretty_assertions v0.5.1
Dec 13 05:07:48.623 INFO [stderr]    Compiling html5ever v0.18.0
Dec 13 05:07:48.624 INFO [stderr]    Compiling pulldown-cmark-to-cmark v1.1.0
Dec 13 05:07:48.635 INFO [stderr]    Compiling tendril v0.3.1
Dec 13 05:07:48.638 INFO [stderr]    Compiling string_cache v0.6.2
Dec 13 05:07:48.644 INFO [stderr]    Compiling markup5ever v0.3.2
Dec 13 05:08:09.144 INFO [stderr]    Compiling select v0.4.2
Dec 13 05:08:14.479 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 13 05:09:21.719 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 33s
Dec 13 05:09:21.763 INFO [stderr] su: No module specific data is present
Dec 13 05:09:23.356 INFO running `"docker" "inspect" "a0d20ae94f6ecb6979176376c1de2eede256a10897b6304534c00ca3c1caf9ac"`
Dec 13 05:09:23.659 INFO running `"docker" "rm" "-f" "a0d20ae94f6ecb6979176376c1de2eede256a10897b6304534c00ca3c1caf9ac"`
Dec 13 05:09:24.144 INFO [stdout] a0d20ae94f6ecb6979176376c1de2eede256a10897b6304534c00ca3c1caf9ac
Dec 13 05:09:24.148 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 13 05:09:24.783 INFO [stdout] 57054db3218b5e510987af7a2ae5c2b417624d38d1ac936ae53395a63dfd3133
Dec 13 05:09:24.787 INFO running `"docker" "start" "-a" "57054db3218b5e510987af7a2ae5c2b417624d38d1ac936ae53395a63dfd3133"`
Dec 13 05:09:25.924 INFO [stderr] usermod: no changes
Dec 13 05:09:26.383 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.43s
Dec 13 05:09:26.402 INFO [stderr]      Running /target/debug/deps/mdbook-88c8358390e7e82a
Dec 13 05:09:26.407 INFO [stdout] 
Dec 13 05:09:26.407 INFO [stdout] running 79 tests
Dec 13 05:09:26.407 INFO [stdout] test book::book::tests::cant_load_chapters_with_an_empty_path ... ok
Dec 13 05:09:26.413 INFO [stdout] test book::book::tests::cant_load_a_nonexistent_chapter ... ok
Dec 13 05:09:26.413 INFO [stdout] test book::book::tests::for_each_mut_visits_all_items ... ok
Dec 13 05:09:26.413 INFO [stdout] test book::book::tests::load_a_single_chapter_from_disk ... ok
Dec 13 05:09:26.413 INFO [stdout] test book::book::tests::iterate_over_nested_book_items ... ok
Dec 13 05:09:26.413 INFO [stdout] test book::book::tests::book_iter_iterates_over_sequential_items ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::book::tests::load_a_book_with_a_single_chapter ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::book::tests::cant_load_chapters_when_the_link_is_a_directory ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::summary::tests::an_empty_link_location_is_an_error ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::summary::tests::can_have_a_subheader_between_nested_items ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::summary::tests::parse_initial_title ... ok
Dec 13 05:09:26.415 INFO [stdout] test book::summary::tests::convert_markdown_events_to_a_string ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::keep_numbering_after_separator ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::parse_a_numbered_chapter ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::book::tests::load_recursive_link_with_separators ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::parse_a_link ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::parse_nested_numbered_chapters ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::parse_some_prefix_items ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::suffix_items_cannot_be_followed_by_a_list ... ok
Dec 13 05:09:26.419 INFO [stdout] test book::summary::tests::parse_title_with_styling ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::add_a_random_renderer_to_the_config ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::summary::tests::parse_prefix_items_with_a_separator ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::summary::tests::section_number_has_correct_dotted_representation ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::config_defaults_to_html_renderer_if_empty ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::config_defaults_to_link_and_index_preprocessor_if_not_set ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::config_respects_preprocessor_selection ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::can_determine_third_party_preprocessors ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::preprocessor_should_run_falls_back_to_supports_renderer_method ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::add_a_random_renderer_with_custom_command_to_the_config ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::preprocessors_can_provide_their_own_commands ... ok
Dec 13 05:09:26.439 INFO [stdout] test book::tests::use_default_preprocessors_works ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::can_still_load_the_previous_format ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::load_a_complex_config_file ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::load_arbitrary_output_type ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::update_config_using_env_var ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::mutate_some_stuff ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::parse_env_vars ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::set_a_config_item ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::update_book_title_via_env ... ok
Dec 13 05:09:26.439 INFO [stdout] test config::tests::update_config_using_env_var_and_complex_value ... ok
Dec 13 05:09:26.451 INFO [stdout] test preprocess::links::tests::test_find_links_no_link ... ok
Dec 13 05:09:26.451 INFO [stdout] test preprocess::links::tests::test_find_links_partial_link ... ok
Dec 13 05:09:26.451 INFO [stdout] test preprocess::links::tests::test_find_links_simple_link ... ok
Dec 13 05:09:26.451 INFO [stdout] test preprocess::links::tests::test_find_links_unknown_link_type ... ok
Dec 13 05:09:26.455 INFO [stdout] test preprocess::links::tests::test_find_links_with_full_range ... ok
Dec 13 05:09:26.455 INFO [stdout] test preprocess::links::tests::test_find_links_escaped_link ... ok
Dec 13 05:09:26.455 INFO [stdout] test preprocess::links::tests::test_find_all_link_types ... ok
Dec 13 05:09:26.455 INFO [stdout] test preprocess::links::tests::test_find_links_empty_link ... ok
Dec 13 05:09:26.463 INFO [stdout] test preprocess::links::tests::test_find_links_with_from_range ... ok
Dec 13 05:09:26.463 INFO [stdout] test preprocess::links::tests::test_find_links_with_line_number ... ok
Dec 13 05:09:26.463 INFO [stdout] test preprocess::links::tests::test_find_playpens_with_properties ... ok
Dec 13 05:09:26.463 INFO [stdout] test preprocess::links::tests::test_find_links_with_no_range_specified ... ok
Dec 13 05:09:26.463 INFO [stdout] test preprocess::links::tests::test_find_links_with_to_range ... ok
Dec 13 05:09:26.464 INFO [stdout] test preprocess::links::tests::test_find_links_with_range ... ok
Dec 13 05:09:26.479 INFO [stdout] test preprocess::links::tests::test_replace_all_escaped ... ok
Dec 13 05:09:26.488 INFO [stdout] test preprocess::index::tests::file_stem_exactly_matches_readme_case_insensitively ... ok
Dec 13 05:09:26.495 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_first ... ok
Dec 13 05:09:26.495 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_next_previous ... ok
Dec 13 05:09:26.495 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_double_quotes ... ok
Dec 13 05:09:26.501 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_single_quotes ... ok
Dec 13 05:09:26.501 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_last ... ok
Dec 13 05:09:26.514 INFO [stdout] test utils::tests::html_munging::it_generates_anchors ... ok
Dec 13 05:09:26.514 INFO [stdout] test utils::string::tests::take_lines_test ... ok
Dec 13 05:09:26.522 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_treats_tab_as_whitespace ... ok
Dec 13 05:09:26.523 INFO [stdout] test theme::tests::theme_dir_overrides_defaults ... ok
Dec 13 05:09:26.523 INFO [stdout] test theme::tests::theme_uses_defaults_with_nonexistent_src_dir ... ok
Dec 13 05:09:26.523 INFO [stdout] test utils::fs::tests::copy_files_except_ext_test ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::html_munging::it_normalizes_ids ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::html_munging::it_generates_anchors_from_non_ascii_initial ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::render_markdown::preserves_external_links ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::render_markdown::it_can_make_quotes_curly_except_when_they_are_in_code ... ok
Dec 13 05:09:26.525 INFO [stdout] test renderer::html_handlebars::hbs_renderer::tests::original_build_header_links ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::render_markdown::whitespace_outside_of_codeblock_header_is_preserved ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::render_markdown::it_can_keep_quotes_straight ... ok
Dec 13 05:09:26.525 INFO [stdout] test utils::tests::render_markdown::it_can_adjust_markdown_links ... ok
Dec 13 05:09:26.543 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_with_whitespace_are_passed_as_space_delimited_class ... ok
Dec 13 05:09:26.543 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_are_passed_as_space_delimited_class ... ok
Dec 13 05:09:26.543 INFO [stdout] test utils::tests::render_markdown::rust_code_block_without_properties_has_proper_html_class ... ok
Dec 13 05:09:26.610 INFO [stdout] test preprocess::cmd::tests::round_trip_write_and_parse_input ... ok
Dec 13 05:09:26.610 INFO [stdout] 
Dec 13 05:09:26.610 INFO [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:26.610 INFO [stdout] 
Dec 13 05:09:26.611 INFO [stderr]      Running /target/debug/deps/mdbook-5085710c95fa6b36
Dec 13 05:09:26.640 INFO [stdout] 
Dec 13 05:09:26.640 INFO [stdout] running 0 tests
Dec 13 05:09:26.640 INFO [stdout] 
Dec 13 05:09:26.640 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:26.640 INFO [stdout] 
Dec 13 05:09:26.640 INFO [stderr]      Running /target/debug/deps/alternate_backends-a1ed603318aafac9
Dec 13 05:09:26.651 INFO [stdout] 
Dec 13 05:09:26.651 INFO [stdout] running 5 tests
Dec 13 05:09:26.689 INFO [stdout] test missing_backends_arent_fatal ... ok
Dec 13 05:09:26.689 INFO [stdout] {"version":"0.2.3-alpha.0","root":"/tmp/mdbookzCVKW6","book":{"sections":[{"Chapter":{"name":"Chapter 1","content":"# Chapter 1\n","number":[1],"sub_items":[],"path":"chapter_1.md","parent_names":[]}}],"__non_exhaustive":null},"config":{"book":{"authors":[],"multilingual":false,"src":"src"},"output":{"cat-to-file":{"command":"tee /tmp/outputfQH0y7/out.txt"}}},"destination":"/tmp/mdbookzCVKW6/book"}test passing_alternate_backend ... ok
Dec 13 05:09:26.689 INFO [stdout] test failing_alternate_backend ... ok
Dec 13 05:09:26.695 INFO [stdout] Hello World!
Dec 13 05:09:26.695 INFO [stdout] test alternate_backend_with_arguments ... ok
Dec 13 05:09:26.706 INFO [stdout] test backends_receive_render_context_via_stdin ... ok
Dec 13 05:09:26.706 INFO [stderr]      Running /target/debug/deps/build_process-c99ea5056dd05402
Dec 13 05:09:26.706 INFO [stdout] 
Dec 13 05:09:26.706 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:26.706 INFO [stdout] 
Dec 13 05:09:26.715 INFO [stdout] 
Dec 13 05:09:26.715 INFO [stdout] running 2 tests
Dec 13 05:09:27.486 INFO [stdout] test mdbook_runs_renderers ... ok
Dec 13 05:09:27.518 INFO [stdout] test mdbook_runs_preprocessors ... ok
Dec 13 05:09:27.518 INFO [stdout] 
Dec 13 05:09:27.518 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:27.519 INFO [stdout] 
Dec 13 05:09:27.535 INFO [stderr]      Running /target/debug/deps/custom_preprocessors-7fbd2502919d9d7c
Dec 13 05:09:27.535 INFO [stdout] 
Dec 13 05:09:27.535 INFO [stdout] running 4 tests
Dec 13 05:09:28.056 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:09:28.126 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.53s
Dec 13 05:09:28.166 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports not-supported`
Dec 13 05:09:28.176 INFO [stdout] test example_doesnt_support_not_supported ... ok
Dec 13 05:09:28.264 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:09:28.265 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:09:28.283 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.66s
Dec 13 05:09:28.310 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 13 05:09:28.483 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.85s
Dec 13 05:09:28.504 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 13 05:09:28.714 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.14s
Dec 13 05:09:28.783 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports whatever`
Dec 13 05:09:28.807 INFO [stdout] test example_supports_whatever ... ok
Dec 13 05:09:28.867 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.53s
Dec 13 05:09:28.886 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 13 05:09:28.892 INFO [stderr] Boom!!1!
Dec 13 05:09:28.904 INFO [stdout] test ask_the_preprocessor_to_blow_up ... ok
Dec 13 05:09:28.985 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.42s
Dec 13 05:09:28.994 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 13 05:09:29.429 INFO [stdout] test process_the_dummy_book ... ok
Dec 13 05:09:29.429 INFO [stdout] 
Dec 13 05:09:29.429 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:29.429 INFO [stdout] 
Dec 13 05:09:29.430 INFO [stderr]      Running /target/debug/deps/init-9f0000ac8d5633cf
Dec 13 05:09:29.439 INFO [stdout] 
Dec 13 05:09:29.439 INFO [stdout] running 3 tests
Dec 13 05:09:29.475 INFO [stdout] test base_mdbook_init_should_create_default_content ... ok
Dec 13 05:09:29.479 INFO [stdout] test run_mdbook_init_with_custom_book_and_src_locations ... ok
Dec 13 05:09:29.866 INFO [stdout] test book_toml_isnt_required ... ok
Dec 13 05:09:29.866 INFO [stdout] 
Dec 13 05:09:29.866 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:29.866 INFO [stdout] 
Dec 13 05:09:29.873 INFO [stderr]      Running /target/debug/deps/parse_existing_summary_files-f013c7102269c22f
Dec 13 05:09:29.883 INFO [stdout] 
Dec 13 05:09:29.883 INFO [stdout] running 4 tests
Dec 13 05:09:29.899 INFO [stdout] test example_book ... ok
Dec 13 05:09:29.899 INFO [stdout] test rust_ffi_guide ... ok
Dec 13 05:09:29.902 INFO [stdout] test rust_by_example ... ok
Dec 13 05:09:29.916 INFO [stdout] test the_book_2nd_edition ... ok
Dec 13 05:09:29.917 INFO [stdout] 
Dec 13 05:09:29.917 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:29.917 INFO [stdout] 
Dec 13 05:09:29.918 INFO [stderr]      Running /target/debug/deps/rendered_output-8e538c713da314aa
Dec 13 05:09:29.923 INFO [stdout] 
Dec 13 05:09:29.923 INFO [stdout] running 21 tests
Dec 13 05:09:29.971 INFO [stdout] test chapter_files_were_rendered_to_html ... ok
Dec 13 05:09:31.321 INFO [stdout] test able_to_include_files_in_chapters ... ok
Dec 13 05:09:31.395 INFO [stdout] test book_with_a_reserved_filename_does_not_build ... ok
Dec 13 05:09:32.135 INFO [stdout] test by_default_mdbook_use_index_preprocessor_to_convert_readme_to_index ... ok
Dec 13 05:09:32.714 INFO [stdout] test build_the_dummy_book ... ok
Dec 13 05:09:32.724 INFO [stdout] test check_correct_cross_links_in_nested_dir ... ok
Dec 13 05:09:32.745 INFO [stdout] test create_missing_file_with_config ... ok
Dec 13 05:09:32.819 INFO [stdout] test failure_on_missing_file ... ok
Dec 13 05:09:33.043 INFO [stdout] test able_to_include_playpen_files_in_chapters ... ok
Dec 13 05:09:33.163 INFO [stdout] test chapter_content_appears_in_rendered_document ... ok
Dec 13 05:09:33.259 INFO [stdout] test by_default_mdbook_generates_rendered_content_in_the_book_directory ... ok
Dec 13 05:09:33.315 INFO [stdout] test check_first_toc_level ... ok
Dec 13 05:09:34.031 INFO [stdout] test check_second_toc_level ... ok
Dec 13 05:09:35.487 INFO [stdout] test search::book_creates_reasonable_search_index ... ok
Dec 13 05:09:35.527 INFO [stdout] test check_spacers ... ok
Dec 13 05:09:35.552 INFO [stdout] test recursive_includes_are_capped ... ok
Dec 13 05:09:35.847 INFO [stdout] test make_sure_bottom_level_files_contain_links_to_chapters ... ok
Dec 13 05:09:35.955 INFO [stdout] test rendered_code_has_playpen_stuff ... ok
Dec 13 05:09:35.967 INFO [stdout] test search::search_index_hasnt_changed_accidentally ... ok
Dec 13 05:09:39.227 INFO [stdout] test example_book_can_build ... ok
Dec 13 05:09:39.937 INFO [stdout] test theme_dir_overrides_work_correctly ... ok
Dec 13 05:09:39.937 INFO [stdout] 
Dec 13 05:09:39.937 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:39.937 INFO [stdout] 
Dec 13 05:09:39.943 INFO [stderr]      Running /target/debug/deps/testing-4dbcfbe15a732640
Dec 13 05:09:39.959 INFO [stdout] 
Dec 13 05:09:39.959 INFO [stdout] running 2 tests
Dec 13 05:09:41.329 INFO [stdout] test mdbook_can_correctly_test_a_passing_book ... ok
Dec 13 05:09:41.453 INFO [stdout] test mdbook_detects_book_with_failing_tests ... ok
Dec 13 05:09:41.453 INFO [stdout] 
Dec 13 05:09:41.453 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:41.453 INFO [stdout] 
Dec 13 05:09:41.454 INFO [stderr]    Doc-tests mdbook
Dec 13 05:09:44.255 INFO [stdout] 
Dec 13 05:09:44.255 INFO [stdout] running 5 tests
Dec 13 05:09:54.160 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Dec 13 05:09:54.463 INFO [stdout] test src/book/mod.rs - book::MDBook::iter (line 102) ... ok
Dec 13 05:09:54.529 INFO [stdout] test src/lib.rs -  (line 30) ... ok
Dec 13 05:09:58.582 INFO [stdout] test src/utils/fs.rs - utils::fs::path_to_root (line 40) ... ok
Dec 13 05:09:59.527 INFO [stdout] test src/config.rs - config (line 11) ... ok
Dec 13 05:09:59.527 INFO [stdout] 
Dec 13 05:09:59.527 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:59.527 INFO [stdout] 
Dec 13 05:09:59.581 INFO [stderr] su: No module specific data is present
Dec 13 05:10:00.714 INFO running `"docker" "inspect" "57054db3218b5e510987af7a2ae5c2b417624d38d1ac936ae53395a63dfd3133"`
Dec 13 05:10:01.105 INFO running `"docker" "rm" "-f" "57054db3218b5e510987af7a2ae5c2b417624d38d1ac936ae53395a63dfd3133"`
Dec 13 05:10:01.469 INFO [stdout] 57054db3218b5e510987af7a2ae5c2b417624d38d1ac936ae53395a63dfd3133