Nov 16 14:59:47.581 INFO testing rust-lang-nursery/mdBook against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 14:59:47.583 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 14:59:48.159 INFO [stdout] 3ee32e10ac1ba799b5f3af376f31b22b6f5e31b8a86a9f7b217bde25e459d876
Nov 16 14:59:48.161 INFO running `"docker" "start" "-a" "3ee32e10ac1ba799b5f3af376f31b22b6f5e31b8a86a9f7b217bde25e459d876"`
Nov 16 14:59:51.007 INFO [stderr] usermod: no changes
Nov 16 14:59:51.433 INFO [stderr]    Compiling sequence_trie v0.3.5
Nov 16 14:59:51.433 INFO [stderr]    Compiling pulldown-cmark v0.1.2
Nov 16 14:59:51.447 INFO [stderr]    Compiling is-match v0.1.0
Nov 16 14:59:51.456 INFO [stderr]    Compiling strum v0.9.0
Nov 16 14:59:51.487 INFO [stderr]    Compiling new_debug_unreachable v1.0.1
Nov 16 14:59:51.488 INFO [stderr]    Compiling log v0.3.9
Nov 16 14:59:51.490 INFO [stderr]    Compiling syn v0.13.11
Nov 16 14:59:51.491 INFO [stderr]    Compiling mio-extras v2.0.5
Nov 16 14:59:52.018 INFO [stderr]    Compiling pest_derive v1.0.8
Nov 16 14:59:52.746 INFO [stderr]    Compiling syn v0.14.9
Nov 16 14:59:53.077 INFO [stderr]    Compiling futf v0.1.4
Nov 16 14:59:54.669 INFO [stderr]    Compiling error-chain v0.12.0
Nov 16 14:59:54.718 INFO [stderr]    Compiling string_cache_codegen v0.4.1
Nov 16 14:59:54.733 INFO [stderr]    Compiling mime v0.2.6
Nov 16 14:59:58.024 INFO [stderr]    Compiling mime_guess v1.8.6
Nov 16 15:00:01.579 INFO [stderr]    Compiling tendril v0.4.0
Nov 16 15:00:01.659 INFO [stderr]    Compiling ws v0.7.9
Nov 16 15:00:12.399 INFO [stderr]    Compiling toml-query v0.7.0
Nov 16 15:00:19.859 INFO [stderr]    Compiling inotify v0.6.1
Nov 16 15:00:20.060 INFO [stderr]    Compiling hyper v0.10.13
Nov 16 15:00:20.139 INFO [stderr]    Compiling string_cache v0.7.3
Nov 16 15:00:24.536 INFO [stderr]    Compiling markup5ever v0.7.3
Nov 16 15:00:31.565 INFO [stderr]    Compiling handlebars v1.0.5
Nov 16 15:00:39.581 INFO [stderr]    Compiling notify v4.0.6
Nov 16 15:00:39.960 INFO [stderr]    Compiling strum_macros v0.9.1
Nov 16 15:01:25.664 INFO [stderr]    Compiling elasticlunr-rs v2.3.3
Nov 16 15:01:26.340 INFO [stderr]    Compiling iron v0.6.0
Nov 16 15:01:31.769 INFO [stderr]    Compiling html5ever v0.22.3
Nov 16 15:01:32.275 INFO [stderr]    Compiling mount v0.4.0
Nov 16 15:01:34.623 INFO [stderr]    Compiling staticfile v0.5.0
Nov 16 15:01:54.859 INFO [stderr]    Compiling ammonia v1.2.0
Nov 16 15:02:00.361 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Nov 16 15:02:29.764 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 38s
Nov 16 15:02:29.789 INFO [stderr] su: No module specific data is present
Nov 16 15:02:32.251 INFO running `"docker" "inspect" "3ee32e10ac1ba799b5f3af376f31b22b6f5e31b8a86a9f7b217bde25e459d876"`
Nov 16 15:02:32.551 INFO running `"docker" "rm" "-f" "3ee32e10ac1ba799b5f3af376f31b22b6f5e31b8a86a9f7b217bde25e459d876"`
Nov 16 15:02:32.923 INFO [stdout] 3ee32e10ac1ba799b5f3af376f31b22b6f5e31b8a86a9f7b217bde25e459d876
Nov 16 15:02:32.931 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 15:02:33.726 INFO [stdout] 4512aa9d44993d31dfa2f9656fcbd6613d70998ca413c1c6cdaf418c0a6c7165
Nov 16 15:02:33.728 INFO running `"docker" "start" "-a" "4512aa9d44993d31dfa2f9656fcbd6613d70998ca413c1c6cdaf418c0a6c7165"`
Nov 16 15:02:35.304 INFO [stderr] usermod: no changes
Nov 16 15:02:35.899 INFO [stderr]    Compiling pretty_assertions v0.5.1
Nov 16 15:02:35.906 INFO [stderr]    Compiling pulldown-cmark-to-cmark v1.1.0
Nov 16 15:02:35.906 INFO [stderr]    Compiling tendril v0.3.1
Nov 16 15:02:35.907 INFO [stderr]    Compiling string_cache v0.6.2
Nov 16 15:02:35.907 INFO [stderr]    Compiling markup5ever v0.3.2
Nov 16 15:02:50.819 INFO [stderr]    Compiling html5ever v0.18.0
Nov 16 15:02:54.711 INFO [stderr]    Compiling select v0.4.2
Nov 16 15:02:59.946 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Nov 16 15:03:08.875 INFO [stderr] warning: struct is never constructed: `Deemphasize`
Nov 16 15:03:08.875 INFO [stderr]   --> examples/de-emphasize.rs:20:1
Nov 16 15:03:08.875 INFO [stderr]    |
Nov 16 15:03:08.876 INFO [stderr] 20 | struct Deemphasize;
Nov 16 15:03:08.876 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^
Nov 16 15:03:08.876 INFO [stderr]    |
Nov 16 15:03:08.876 INFO [stderr]    = note: #[warn(dead_code)] on by default
Nov 16 15:03:08.876 INFO [stderr] 
Nov 16 15:04:04.930 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 29s
Nov 16 15:04:04.984 INFO [stderr] su: No module specific data is present
Nov 16 15:04:06.163 INFO running `"docker" "inspect" "4512aa9d44993d31dfa2f9656fcbd6613d70998ca413c1c6cdaf418c0a6c7165"`
Nov 16 15:04:06.491 INFO running `"docker" "rm" "-f" "4512aa9d44993d31dfa2f9656fcbd6613d70998ca413c1c6cdaf418c0a6c7165"`
Nov 16 15:04:06.827 INFO [stdout] 4512aa9d44993d31dfa2f9656fcbd6613d70998ca413c1c6cdaf418c0a6c7165
Nov 16 15:04:06.837 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 16 15:04:07.410 INFO [stdout] b9f5387a95df985b608f2de6ca29398c58411fe4e6909f916a2e876dfaae6e23
Nov 16 15:04:07.413 INFO running `"docker" "start" "-a" "b9f5387a95df985b608f2de6ca29398c58411fe4e6909f916a2e876dfaae6e23"`
Nov 16 15:04:09.464 INFO [stderr] usermod: no changes
Nov 16 15:04:09.986 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.49s
Nov 16 15:04:10.030 INFO [stderr]      Running /target/debug/deps/mdbook-1f84811b4c0a2529
Nov 16 15:04:10.045 INFO [stdout] 
Nov 16 15:04:10.046 INFO [stdout] running 79 tests
Nov 16 15:04:10.046 INFO [stdout] test book::book::tests::load_a_single_chapter_from_disk ... ok
Nov 16 15:04:10.047 INFO [stdout] test book::book::tests::load_a_book_with_a_single_chapter ... ok
Nov 16 15:04:10.048 INFO [stdout] test book::book::tests::iterate_over_nested_book_items ... ok
Nov 16 15:04:10.049 INFO [stdout] test book::book::tests::load_recursive_link_with_separators ... ok
Nov 16 15:04:10.049 INFO [stdout] test book::book::tests::for_each_mut_visits_all_items ... ok
Nov 16 15:04:10.050 INFO [stdout] test book::summary::tests::an_empty_link_location_is_an_error ... ok
Nov 16 15:04:10.050 INFO [stdout] test book::book::tests::cant_load_chapters_with_an_empty_path ... ok
Nov 16 15:04:10.051 INFO [stdout] test book::summary::tests::convert_markdown_events_to_a_string ... ok
Nov 16 15:04:10.051 INFO [stdout] test book::book::tests::cant_load_chapters_when_the_link_is_a_directory ... ok
Nov 16 15:04:10.051 INFO [stdout] test book::book::tests::cant_load_a_nonexistent_chapter ... ok
Nov 16 15:04:10.052 INFO [stdout] test book::summary::tests::can_have_a_subheader_between_nested_items ... ok
Nov 16 15:04:10.052 INFO [stdout] test book::summary::tests::keep_numbering_after_separator ... ok
Nov 16 15:04:10.053 INFO [stdout] test book::summary::tests::parse_a_link ... ok
Nov 16 15:04:10.054 INFO [stdout] test book::summary::tests::parse_a_numbered_chapter ... ok
Nov 16 15:04:10.054 INFO [stdout] test book::summary::tests::parse_initial_title ... ok
Nov 16 15:04:10.055 INFO [stdout] test book::book::tests::book_iter_iterates_over_sequential_items ... ok
Nov 16 15:04:10.055 INFO [stdout] test book::summary::tests::parse_nested_numbered_chapters ... ok
Nov 16 15:04:10.056 INFO [stdout] test book::summary::tests::parse_prefix_items_with_a_separator ... ok
Nov 16 15:04:10.057 INFO [stdout] test book::summary::tests::parse_some_prefix_items ... ok
Nov 16 15:04:10.057 INFO [stdout] test book::summary::tests::parse_title_with_styling ... ok
Nov 16 15:04:10.058 INFO [stdout] test book::summary::tests::section_number_has_correct_dotted_representation ... ok
Nov 16 15:04:10.058 INFO [stdout] test book::summary::tests::suffix_items_cannot_be_followed_by_a_list ... ok
Nov 16 15:04:10.059 INFO [stdout] test book::tests::add_a_random_renderer_to_the_config ... ok
Nov 16 15:04:10.060 INFO [stdout] test book::tests::add_a_random_renderer_with_custom_command_to_the_config ... ok
Nov 16 15:04:10.061 INFO [stdout] test book::tests::can_determine_third_party_preprocessors ... ok
Nov 16 15:04:10.061 INFO [stdout] test book::tests::config_defaults_to_html_renderer_if_empty ... ok
Nov 16 15:04:10.062 INFO [stdout] test book::tests::config_defaults_to_link_and_index_preprocessor_if_not_set ... ok
Nov 16 15:04:10.062 INFO [stdout] test book::tests::config_respects_preprocessor_selection ... ok
Nov 16 15:04:10.063 INFO [stdout] test book::tests::preprocessor_should_run_falls_back_to_supports_renderer_method ... ok
Nov 16 15:04:10.064 INFO [stdout] test book::tests::preprocessors_can_provide_their_own_commands ... ok
Nov 16 15:04:10.064 INFO [stdout] test book::tests::use_default_preprocessors_works ... ok
Nov 16 15:04:10.066 INFO [stdout] test config::tests::load_a_complex_config_file ... ok
Nov 16 15:04:10.071 INFO [stdout] test config::tests::load_arbitrary_output_type ... ok
Nov 16 15:04:10.071 INFO [stdout] test config::tests::can_still_load_the_previous_format ... ok
Nov 16 15:04:10.073 INFO [stdout] test config::tests::mutate_some_stuff ... ok
Nov 16 15:04:10.073 INFO [stdout] test config::tests::parse_env_vars ... ok
Nov 16 15:04:10.074 INFO [stdout] test config::tests::set_a_config_item ... ok
Nov 16 15:04:10.074 INFO [stdout] test config::tests::update_book_title_via_env ... ok
Nov 16 15:04:10.075 INFO [stdout] test config::tests::update_config_using_env_var ... ok
Nov 16 15:04:10.076 INFO [stdout] test config::tests::update_config_using_env_var_and_complex_value ... ok
Nov 16 15:04:10.078 INFO [stdout] test preprocess::links::tests::test_find_links_empty_link ... ok
Nov 16 15:04:10.087 INFO [stdout] test preprocess::links::tests::test_find_all_link_types ... ok
Nov 16 15:04:10.087 INFO [stdout] test preprocess::links::tests::test_find_links_escaped_link ... ok
Nov 16 15:04:10.087 INFO [stdout] test preprocess::links::tests::test_find_links_no_link ... ok
Nov 16 15:04:10.087 INFO [stdout] test preprocess::links::tests::test_find_links_partial_link ... ok
Nov 16 15:04:10.098 INFO [stdout] test preprocess::links::tests::test_find_links_simple_link ... ok
Nov 16 15:04:10.098 INFO [stdout] test preprocess::links::tests::test_find_links_with_from_range ... ok
Nov 16 15:04:10.103 INFO [stdout] test preprocess::links::tests::test_find_links_with_range ... ok
Nov 16 15:04:10.103 INFO [stdout] test preprocess::links::tests::test_find_links_with_to_range ... ok
Nov 16 15:04:10.104 INFO [stdout] test preprocess::links::tests::test_find_links_with_line_number ... ok
Nov 16 15:04:10.105 INFO [stdout] test preprocess::links::tests::test_find_links_with_no_range_specified ... ok
Nov 16 15:04:10.107 INFO [stdout] test preprocess::links::tests::test_find_links_unknown_link_type ... ok
Nov 16 15:04:10.108 INFO [stdout] test preprocess::index::tests::file_stem_exactly_matches_readme_case_insensitively ... ok
Nov 16 15:04:10.115 INFO [stdout] test preprocess::links::tests::test_find_links_with_full_range ... ok
Nov 16 15:04:10.115 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_first ... ok
Nov 16 15:04:10.116 INFO [stdout] test preprocess::links::tests::test_replace_all_escaped ... ok
Nov 16 15:04:10.131 INFO [stdout] test preprocess::links::tests::test_find_playpens_with_properties ... ok
Nov 16 15:04:10.131 INFO [stdout] test theme::tests::theme_uses_defaults_with_nonexistent_src_dir ... ok
Nov 16 15:04:10.132 INFO [stdout] test utils::fs::tests::copy_files_except_ext_test ... ok
Nov 16 15:04:10.132 INFO [stdout] test utils::string::tests::take_lines_test ... ok
Nov 16 15:04:10.135 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_single_quotes ... ok
Nov 16 15:04:10.135 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_double_quotes ... ok
Nov 16 15:04:10.135 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_treats_tab_as_whitespace ... ok
Nov 16 15:04:10.139 INFO [stdout] test utils::tests::html_munging::it_generates_anchors ... ok
Nov 16 15:04:10.139 INFO [stdout] test utils::tests::html_munging::it_generates_anchors_from_non_ascii_initial ... ok
Nov 16 15:04:10.139 INFO [stdout] test utils::tests::html_munging::it_normalizes_ids ... ok
Nov 16 15:04:10.167 INFO [stdout] test utils::tests::render_markdown::it_can_make_quotes_curly_except_when_they_are_in_code ... ok
Nov 16 15:04:10.167 INFO [stdout] test utils::tests::render_markdown::it_can_keep_quotes_straight ... ok
Nov 16 15:04:10.167 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_last ... ok
Nov 16 15:04:10.167 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_are_passed_as_space_delimited_class ... ok
Nov 16 15:04:10.171 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_with_whitespace_are_passed_as_space_delimited_class ... ok
Nov 16 15:04:10.171 INFO [stdout] test utils::tests::render_markdown::rust_code_block_without_properties_has_proper_html_class ... ok
Nov 16 15:04:10.176 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_next_previous ... ok
Nov 16 15:04:10.177 INFO [stdout] test utils::tests::render_markdown::preserves_external_links ... ok
Nov 16 15:04:10.177 INFO [stdout] test utils::tests::render_markdown::whitespace_outside_of_codeblock_header_is_preserved ... ok
Nov 16 15:04:10.189 INFO [stdout] test utils::tests::render_markdown::it_can_adjust_markdown_links ... ok
Nov 16 15:04:10.199 INFO [stdout] test theme::tests::theme_dir_overrides_defaults ... ok
Nov 16 15:04:10.239 INFO [stdout] test renderer::html_handlebars::hbs_renderer::tests::original_build_header_links ... ok
Nov 16 15:04:10.251 INFO [stdout] test preprocess::cmd::tests::round_trip_write_and_parse_input ... ok
Nov 16 15:04:10.252 INFO [stdout] 
Nov 16 15:04:10.252 INFO [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:10.252 INFO [stdout] 
Nov 16 15:04:10.254 INFO [stderr]      Running /target/debug/deps/mdbook-a0b1616653cdee60
Nov 16 15:04:10.275 INFO [stdout] 
Nov 16 15:04:10.276 INFO [stdout] running 0 tests
Nov 16 15:04:10.276 INFO [stdout] 
Nov 16 15:04:10.276 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:10.277 INFO [stdout] 
Nov 16 15:04:10.282 INFO [stderr]      Running /target/debug/deps/alternate_backends-fb9b401fc206568c
Nov 16 15:04:10.282 INFO [stdout] 
Nov 16 15:04:10.283 INFO [stdout] running 5 tests
Nov 16 15:04:10.307 INFO [stdout] test missing_backends_arent_fatal ... ok
Nov 16 15:04:10.312 INFO [stdout] test passing_alternate_backend ... ok
Nov 16 15:04:10.318 INFO [stdout] test failing_alternate_backend ... ok
Nov 16 15:04:10.335 INFO [stdout] Hello World!
Nov 16 15:04:10.335 INFO [stdout] {"version":"0.2.3-alpha.0","root":"/tmp/mdbookDHKYzs","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/output4OHfSk/out.txt"}}},"destination":"/tmp/mdbookDHKYzs/book"}test alternate_backend_with_arguments ... ok
Nov 16 15:04:10.343 INFO [stdout] test backends_receive_render_context_via_stdin ... ok
Nov 16 15:04:10.343 INFO [stdout] 
Nov 16 15:04:10.343 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:10.343 INFO [stdout] 
Nov 16 15:04:10.347 INFO [stderr]      Running /target/debug/deps/build_process-5d3d671872b4851d
Nov 16 15:04:10.359 INFO [stdout] 
Nov 16 15:04:10.359 INFO [stdout] running 2 tests
Nov 16 15:04:11.177 INFO [stdout] test mdbook_runs_preprocessors ... ok
Nov 16 15:04:11.247 INFO [stderr]      Running /target/debug/deps/custom_preprocessors-ab682e08dba9d4f5
Nov 16 15:04:11.248 INFO [stdout] test mdbook_runs_renderers ... ok
Nov 16 15:04:11.248 INFO [stdout] 
Nov 16 15:04:11.248 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:11.249 INFO [stdout] 
Nov 16 15:04:11.249 INFO [stdout] 
Nov 16 15:04:11.249 INFO [stdout] running 4 tests
Nov 16 15:04:12.011 INFO [stderr]     Blocking waiting for file lock on build directory
Nov 16 15:04:12.055 INFO [stderr]     Blocking waiting for file lock on build directory
Nov 16 15:04:12.171 INFO [stderr]     Blocking waiting for file lock on build directory
Nov 16 15:04:12.204 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.93s
Nov 16 15:04:12.230 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports whatever`
Nov 16 15:04:12.247 INFO [stdout] test example_supports_whatever ... ok
Nov 16 15:04:12.471 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.16s
Nov 16 15:04:12.494 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports not-supported`
Nov 16 15:04:12.499 INFO [stdout] test example_doesnt_support_not_supported ... ok
Nov 16 15:04:12.655 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.25s
Nov 16 15:04:12.703 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Nov 16 15:04:12.915 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.49s
Nov 16 15:04:12.955 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Nov 16 15:04:13.253 INFO [stderr]     Blocking waiting for file lock on build directory
Nov 16 15:04:13.371 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.64s
Nov 16 15:04:13.390 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Nov 16 15:04:13.563 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.59s
Nov 16 15:04:13.590 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Nov 16 15:04:13.599 INFO [stderr] Boom!!1!
Nov 16 15:04:13.615 INFO [stdout] test ask_the_preprocessor_to_blow_up ... ok
Nov 16 15:04:13.819 INFO [stderr]      Running /target/debug/deps/init-f27c2a8067076b4f
Nov 16 15:04:13.820 INFO [stdout] test process_the_dummy_book ... ok
Nov 16 15:04:13.820 INFO [stdout] 
Nov 16 15:04:13.820 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:13.820 INFO [stdout] 
Nov 16 15:04:13.836 INFO [stdout] 
Nov 16 15:04:13.836 INFO [stdout] running 3 tests
Nov 16 15:04:13.842 INFO [stdout] test base_mdbook_init_should_create_default_content ... ok
Nov 16 15:04:13.883 INFO [stdout] test run_mdbook_init_with_custom_book_and_src_locations ... ok
Nov 16 15:04:14.176 INFO [stdout] test book_toml_isnt_required ... ok
Nov 16 15:04:14.177 INFO [stdout] 
Nov 16 15:04:14.178 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:14.178 INFO [stdout] 
Nov 16 15:04:14.181 INFO [stderr]      Running /target/debug/deps/parse_existing_summary_files-4b86493a7c27c4bb
Nov 16 15:04:14.193 INFO [stdout] 
Nov 16 15:04:14.193 INFO [stdout] running 4 tests
Nov 16 15:04:14.231 INFO [stdout] test rust_ffi_guide ... ok
Nov 16 15:04:14.231 INFO [stdout] test example_book ... ok
Nov 16 15:04:14.239 INFO [stdout] test the_book_2nd_edition ... ok
Nov 16 15:04:14.247 INFO [stdout] test rust_by_example ... ok
Nov 16 15:04:14.247 INFO [stdout] 
Nov 16 15:04:14.247 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:14.247 INFO [stdout] 
Nov 16 15:04:14.248 INFO [stderr]      Running /target/debug/deps/rendered_output-29378fc0d42e93ec
Nov 16 15:04:14.266 INFO [stdout] 
Nov 16 15:04:14.266 INFO [stdout] running 21 tests
Nov 16 15:04:14.331 INFO [stdout] test chapter_files_were_rendered_to_html ... ok
Nov 16 15:04:15.431 INFO [stdout] test chapter_content_appears_in_rendered_document ... ok
Nov 16 15:04:15.858 INFO [stdout] test book_with_a_reserved_filename_does_not_build ... ok
Nov 16 15:04:16.569 INFO [stdout] test by_default_mdbook_use_index_preprocessor_to_convert_readme_to_index ... ok
Nov 16 15:04:17.499 INFO [stdout] test by_default_mdbook_generates_rendered_content_in_the_book_directory ... ok
Nov 16 15:04:17.515 INFO [stdout] test check_first_toc_level ... ok
Nov 16 15:04:17.650 INFO [stdout] test create_missing_file_with_config ... ok
Nov 16 15:04:17.689 INFO [stdout] test failure_on_missing_file ... ok
Nov 16 15:04:17.962 INFO [stdout] test able_to_include_files_in_chapters ... ok
Nov 16 15:04:17.975 INFO [stdout] test able_to_include_playpen_files_in_chapters ... ok
Nov 16 15:04:17.975 INFO [stdout] test check_correct_cross_links_in_nested_dir ... ok
Nov 16 15:04:18.019 INFO [stdout] test build_the_dummy_book ... ok
Nov 16 15:04:18.631 INFO [stdout] test check_second_toc_level ... ok
Nov 16 15:04:19.403 INFO [stdout] test check_spacers ... ok
Nov 16 15:04:19.963 INFO [stdout] test rendered_code_has_playpen_stuff ... ok
Nov 16 15:04:20.155 INFO [stdout] test make_sure_bottom_level_files_contain_links_to_chapters ... ok
Nov 16 15:04:20.191 INFO [stdout] test search::search_index_hasnt_changed_accidentally ... ok
Nov 16 15:04:20.585 INFO [stdout] test recursive_includes_are_capped ... ok
Nov 16 15:04:20.593 INFO [stdout] test search::book_creates_reasonable_search_index ... ok
Nov 16 15:04:24.789 INFO [stdout] test theme_dir_overrides_work_correctly ... ok
Nov 16 15:04:24.887 INFO [stdout] test example_book_can_build ... ok
Nov 16 15:04:24.887 INFO [stderr]      Running /target/debug/deps/testing-a322b85dca4b97f2
Nov 16 15:04:24.887 INFO [stdout] 
Nov 16 15:04:24.887 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:24.887 INFO [stdout] 
Nov 16 15:04:24.895 INFO [stdout] 
Nov 16 15:04:24.895 INFO [stdout] running 2 tests
Nov 16 15:04:26.063 INFO [stdout] test mdbook_can_correctly_test_a_passing_book ... ok
Nov 16 15:04:26.481 INFO [stdout] test mdbook_detects_book_with_failing_tests ... ok
Nov 16 15:04:26.481 INFO [stdout] 
Nov 16 15:04:26.482 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:26.483 INFO [stdout] 
Nov 16 15:04:26.489 INFO [stderr]    Doc-tests mdbook
Nov 16 15:04:28.964 INFO [stdout] 
Nov 16 15:04:28.964 INFO [stdout] running 5 tests
Nov 16 15:04:36.939 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Nov 16 15:04:37.355 INFO [stdout] test src/lib.rs -  (line 30) ... ok
Nov 16 15:04:37.371 INFO [stdout] test src/book/mod.rs - book::MDBook::iter (line 101) ... ok
Nov 16 15:04:42.163 INFO [stdout] test src/config.rs - config (line 11) ... ok
Nov 16 15:04:42.490 INFO [stdout] test src/utils/fs.rs - utils::fs::path_to_root (line 40) ... ok
Nov 16 15:04:42.491 INFO [stdout] 
Nov 16 15:04:42.492 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 15:04:42.492 INFO [stdout] 
Nov 16 15:04:42.543 INFO [stderr] su: No module specific data is present
Nov 16 15:04:44.056 INFO running `"docker" "inspect" "b9f5387a95df985b608f2de6ca29398c58411fe4e6909f916a2e876dfaae6e23"`
Nov 16 15:04:44.443 INFO running `"docker" "rm" "-f" "b9f5387a95df985b608f2de6ca29398c58411fe4e6909f916a2e876dfaae6e23"`
Nov 16 15:04:44.791 INFO [stdout] b9f5387a95df985b608f2de6ca29398c58411fe4e6909f916a2e876dfaae6e23