Dec 13 05:04:50.796 INFO testing rust-lang-nursery/mdBook against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 05:04:50.796 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 13 05:04:51.408 INFO [stdout] 46232c3db60e429021a8394e46a26b56848553a54837746ec3a71aa1ff5a80e6
Dec 13 05:04:51.410 INFO running `"docker" "start" "-a" "46232c3db60e429021a8394e46a26b56848553a54837746ec3a71aa1ff5a80e6"`
Dec 13 05:04:54.114 INFO [stderr] usermod: no changes
Dec 13 05:04:54.692 INFO [stderr]    Compiling utf-8 v0.7.4
Dec 13 05:04:54.716 INFO [stderr]    Compiling is-match v0.1.0
Dec 13 05:04:54.724 INFO [stderr]    Compiling strum v0.9.0
Dec 13 05:04:54.727 INFO [stderr]    Compiling inotify-sys v0.1.3
Dec 13 05:04:54.728 INFO [stderr]    Compiling log v0.4.5
Dec 13 05:04:54.729 INFO [stderr]    Compiling filetime v0.2.1
Dec 13 05:04:54.751 INFO [stderr]    Compiling new_debug_unreachable v1.0.1
Dec 13 05:04:54.759 INFO [stderr]    Compiling memchr v2.1.0
Dec 13 05:04:55.599 INFO [stderr]    Compiling pest_derive v1.0.8
Dec 13 05:04:56.308 INFO [stderr]    Compiling futf v0.1.4
Dec 13 05:04:56.839 INFO [stderr]    Compiling strum_macros v0.9.1
Dec 13 05:04:57.095 INFO [stderr]    Compiling string_cache_codegen v0.4.1
Dec 13 05:04:58.870 INFO [stderr]    Compiling log v0.3.9
Dec 13 05:04:58.891 INFO [stderr]    Compiling tokio-io v0.1.9
Dec 13 05:04:58.963 INFO [stderr]    Compiling mio v0.6.16
Dec 13 05:04:59.999 INFO [stderr]    Compiling mime_guess v1.8.6
Dec 13 05:05:15.164 INFO [stderr]    Compiling tendril v0.4.0
Dec 13 05:05:15.312 INFO [stderr]    Compiling mime v0.2.6
Dec 13 05:05:15.896 INFO [stderr]    Compiling aho-corasick v0.6.8
Dec 13 05:05:18.792 INFO [stderr]    Compiling string_cache v0.7.3
Dec 13 05:05:19.404 INFO [stderr]    Compiling markup5ever v0.7.3
Dec 13 05:05:19.746 INFO [stderr]    Compiling tokio-reactor v0.1.6
Dec 13 05:05:20.955 INFO [stderr]    Compiling mio-extras v2.0.5
Dec 13 05:05:23.056 INFO [stderr]    Compiling regex v1.0.5
Dec 13 05:05:23.143 INFO [stderr]    Compiling hyper v0.10.13
Dec 13 05:05:32.480 INFO [stderr]    Compiling ws v0.7.9
Dec 13 05:05:32.800 INFO [stderr]    Compiling inotify v0.6.1
Dec 13 05:05:37.524 INFO [stderr]    Compiling notify v4.0.6
Dec 13 05:06:06.667 INFO [stderr]    Compiling env_logger v0.5.13
Dec 13 05:06:06.867 INFO [stderr]    Compiling elasticlunr-rs v2.3.3
Dec 13 05:06:07.005 INFO [stderr]    Compiling toml-query v0.7.0
Dec 13 05:06:07.223 INFO [stderr]    Compiling handlebars v1.0.5
Dec 13 05:06:07.375 INFO [stderr]    Compiling html5ever v0.22.3
Dec 13 05:06:08.183 INFO [stderr]    Compiling iron v0.6.0
Dec 13 05:06:13.547 INFO [stderr]    Compiling mount v0.4.0
Dec 13 05:06:18.909 INFO [stderr]    Compiling staticfile v0.5.0
Dec 13 05:06:19.611 INFO [stderr]    Compiling ammonia v1.2.0
Dec 13 05:06:32.999 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 13 05:07:03.589 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 09s
Dec 13 05:07:03.619 INFO [stderr] su: No module specific data is present
Dec 13 05:07:04.694 INFO running `"docker" "inspect" "46232c3db60e429021a8394e46a26b56848553a54837746ec3a71aa1ff5a80e6"`
Dec 13 05:07:05.015 INFO running `"docker" "rm" "-f" "46232c3db60e429021a8394e46a26b56848553a54837746ec3a71aa1ff5a80e6"`
Dec 13 05:07:05.275 INFO [stdout] 46232c3db60e429021a8394e46a26b56848553a54837746ec3a71aa1ff5a80e6
Dec 13 05:07:05.277 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 13 05:07:05.811 INFO [stdout] b787c2eba1f47ca5f4736824af2015565ddc4b45a9d663016e425a9dec56c80a
Dec 13 05:07:05.813 INFO running `"docker" "start" "-a" "b787c2eba1f47ca5f4736824af2015565ddc4b45a9d663016e425a9dec56c80a"`
Dec 13 05:07:07.075 INFO [stderr] usermod: no changes
Dec 13 05:07:07.607 INFO [stderr]    Compiling pretty_assertions v0.5.1
Dec 13 05:07:07.607 INFO [stderr]    Compiling html5ever v0.18.0
Dec 13 05:07:07.607 INFO [stderr]    Compiling pulldown-cmark-to-cmark v1.1.0
Dec 13 05:07:07.607 INFO [stderr]    Compiling tendril v0.3.1
Dec 13 05:07:07.611 INFO [stderr]    Compiling string_cache v0.6.2
Dec 13 05:07:07.611 INFO [stderr]    Compiling markup5ever v0.3.2
Dec 13 05:07:26.715 INFO [stderr]    Compiling select v0.4.2
Dec 13 05:07:31.627 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 13 05:08:37.995 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 30s
Dec 13 05:08:38.023 INFO [stderr] su: No module specific data is present
Dec 13 05:08:42.018 INFO running `"docker" "inspect" "b787c2eba1f47ca5f4736824af2015565ddc4b45a9d663016e425a9dec56c80a"`
Dec 13 05:08:42.345 INFO running `"docker" "rm" "-f" "b787c2eba1f47ca5f4736824af2015565ddc4b45a9d663016e425a9dec56c80a"`
Dec 13 05:08:42.743 INFO [stdout] b787c2eba1f47ca5f4736824af2015565ddc4b45a9d663016e425a9dec56c80a
Dec 13 05:08:42.751 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 13 05:08:43.311 INFO [stdout] 762a4069cb7ff0b479da9f6c01b5ed97f0a96fbf727dbcca3fab673155330737
Dec 13 05:08:43.313 INFO running `"docker" "start" "-a" "762a4069cb7ff0b479da9f6c01b5ed97f0a96fbf727dbcca3fab673155330737"`
Dec 13 05:08:45.906 INFO [stderr] usermod: no changes
Dec 13 05:08:46.287 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.34s
Dec 13 05:08:46.302 INFO [stderr]      Running /target/debug/deps/mdbook-32fd1a2cfab93d73
Dec 13 05:08:46.312 INFO [stdout] 
Dec 13 05:08:46.312 INFO [stdout] running 79 tests
Dec 13 05:08:46.340 INFO [stdout] test book::book::tests::cant_load_a_nonexistent_chapter ... ok
Dec 13 05:08:46.343 INFO [stdout] test book::book::tests::for_each_mut_visits_all_items ... ok
Dec 13 05:08:46.343 INFO [stdout] test book::book::tests::book_iter_iterates_over_sequential_items ... ok
Dec 13 05:08:46.344 INFO [stdout] test book::book::tests::cant_load_chapters_with_an_empty_path ... ok
Dec 13 05:08:46.344 INFO [stdout] test book::book::tests::iterate_over_nested_book_items ... ok
Dec 13 05:08:46.345 INFO [stdout] test book::book::tests::load_a_single_chapter_from_disk ... ok
Dec 13 05:08:46.345 INFO [stdout] test book::book::tests::load_a_book_with_a_single_chapter ... ok
Dec 13 05:08:46.345 INFO [stdout] test book::summary::tests::convert_markdown_events_to_a_string ... ok
Dec 13 05:08:46.359 INFO [stdout] test book::summary::tests::parse_initial_title ... ok
Dec 13 05:08:46.359 INFO [stdout] test book::summary::tests::an_empty_link_location_is_an_error ... ok
Dec 13 05:08:46.375 INFO [stdout] test book::summary::tests::keep_numbering_after_separator ... ok
Dec 13 05:08:46.375 INFO [stdout] test book::summary::tests::parse_a_numbered_chapter ... ok
Dec 13 05:08:46.375 INFO [stdout] test book::summary::tests::parse_a_link ... ok
Dec 13 05:08:46.375 INFO [stdout] test book::summary::tests::parse_nested_numbered_chapters ... ok
Dec 13 05:08:46.376 INFO [stdout] test book::summary::tests::section_number_has_correct_dotted_representation ... ok
Dec 13 05:08:46.383 INFO [stdout] test book::summary::tests::parse_some_prefix_items ... ok
Dec 13 05:08:46.383 INFO [stdout] test book::summary::tests::can_have_a_subheader_between_nested_items ... ok
Dec 13 05:08:46.387 INFO [stdout] test book::summary::tests::parse_title_with_styling ... ok
Dec 13 05:08:46.387 INFO [stdout] test book::summary::tests::suffix_items_cannot_be_followed_by_a_list ... ok
Dec 13 05:08:46.399 INFO [stdout] test book::book::tests::cant_load_chapters_when_the_link_is_a_directory ... ok
Dec 13 05:08:46.405 INFO [stdout] test book::summary::tests::parse_prefix_items_with_a_separator ... ok
Dec 13 05:08:46.411 INFO [stdout] test book::tests::config_defaults_to_link_and_index_preprocessor_if_not_set ... ok
Dec 13 05:08:46.411 INFO [stdout] test book::tests::config_defaults_to_html_renderer_if_empty ... ok
Dec 13 05:08:46.411 INFO [stdout] test book::tests::config_respects_preprocessor_selection ... ok
Dec 13 05:08:46.411 INFO [stdout] test book::tests::add_a_random_renderer_with_custom_command_to_the_config ... ok
Dec 13 05:08:46.419 INFO [stdout] test book::tests::can_determine_third_party_preprocessors ... ok
Dec 13 05:08:46.419 INFO [stdout] test book::tests::add_a_random_renderer_to_the_config ... ok
Dec 13 05:08:46.419 INFO [stdout] test book::tests::preprocessor_should_run_falls_back_to_supports_renderer_method ... ok
Dec 13 05:08:46.419 INFO [stdout] test book::tests::use_default_preprocessors_works ... ok
Dec 13 05:08:46.419 INFO [stdout] test book::book::tests::load_recursive_link_with_separators ... ok
Dec 13 05:08:46.419 INFO [stdout] test config::tests::parse_env_vars ... ok
Dec 13 05:08:46.420 INFO [stdout] test config::tests::update_config_using_env_var ... ok
Dec 13 05:08:46.420 INFO [stdout] test config::tests::can_still_load_the_previous_format ... ok
Dec 13 05:08:46.420 INFO [stdout] test book::tests::preprocessors_can_provide_their_own_commands ... ok
Dec 13 05:08:46.421 INFO [stdout] test config::tests::load_a_complex_config_file ... ok
Dec 13 05:08:46.422 INFO [stdout] test config::tests::update_config_using_env_var_and_complex_value ... ok
Dec 13 05:08:46.443 INFO [stdout] test config::tests::set_a_config_item ... ok
Dec 13 05:08:46.443 INFO [stdout] test config::tests::update_book_title_via_env ... ok
Dec 13 05:08:46.444 INFO [stdout] test config::tests::mutate_some_stuff ... ok
Dec 13 05:08:46.445 INFO [stdout] test config::tests::load_arbitrary_output_type ... ok
Dec 13 05:08:46.481 INFO [stdout] test preprocess::index::tests::file_stem_exactly_matches_readme_case_insensitively ... ok
Dec 13 05:08:46.483 INFO [stdout] test preprocess::links::tests::test_find_links_no_link ... ok
Dec 13 05:08:46.483 INFO [stdout] test preprocess::links::tests::test_find_links_partial_link ... ok
Dec 13 05:08:46.491 INFO [stdout] test preprocess::links::tests::test_find_links_empty_link ... ok
Dec 13 05:08:46.497 INFO [stdout] test preprocess::links::tests::test_find_links_escaped_link ... ok
Dec 13 05:08:46.500 INFO [stdout] test preprocess::links::tests::test_find_links_with_full_range ... ok
Dec 13 05:08:46.501 INFO [stdout] test preprocess::links::tests::test_find_links_unknown_link_type ... ok
Dec 13 05:08:46.507 INFO [stdout] test preprocess::links::tests::test_find_all_link_types ... ok
Dec 13 05:08:46.510 INFO [stdout] test preprocess::links::tests::test_find_links_with_from_range ... ok
Dec 13 05:08:46.510 INFO [stdout] test preprocess::links::tests::test_find_links_simple_link ... ok
Dec 13 05:08:46.512 INFO [stdout] test preprocess::links::tests::test_find_links_with_no_range_specified ... ok
Dec 13 05:08:46.512 INFO [stdout] test preprocess::links::tests::test_find_links_with_range ... ok
Dec 13 05:08:46.523 INFO [stdout] test preprocess::links::tests::test_find_playpens_with_properties ... ok
Dec 13 05:08:46.523 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_last ... ok
Dec 13 05:08:46.524 INFO [stdout] test preprocess::links::tests::test_find_links_with_to_range ... ok
Dec 13 05:08:46.527 INFO [stdout] test preprocess::links::tests::test_find_links_with_line_number ... ok
Dec 13 05:08:46.528 INFO [stdout] test theme::tests::theme_uses_defaults_with_nonexistent_src_dir ... ok
Dec 13 05:08:46.533 INFO [stdout] test utils::string::tests::take_lines_test ... ok
Dec 13 05:08:46.533 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_double_quotes ... ok
Dec 13 05:08:46.533 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_single_quotes ... ok
Dec 13 05:08:46.533 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_treats_tab_as_whitespace ... ok
Dec 13 05:08:46.533 INFO [stdout] test utils::tests::html_munging::it_generates_anchors ... ok
Dec 13 05:08:46.540 INFO [stdout] test utils::tests::html_munging::it_generates_anchors_from_non_ascii_initial ... ok
Dec 13 05:08:46.540 INFO [stdout] test preprocess::links::tests::test_replace_all_escaped ... ok
Dec 13 05:08:46.551 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_first ... ok
Dec 13 05:08:46.551 INFO [stdout] test utils::tests::html_munging::it_normalizes_ids ... ok
Dec 13 05:08:46.567 INFO [stdout] test utils::tests::render_markdown::it_can_keep_quotes_straight ... ok
Dec 13 05:08:46.567 INFO [stdout] test utils::tests::render_markdown::it_can_make_quotes_curly_except_when_they_are_in_code ... ok
Dec 13 05:08:46.592 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_are_passed_as_space_delimited_class ... ok
Dec 13 05:08:46.592 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_with_whitespace_are_passed_as_space_delimited_class ... ok
Dec 13 05:08:46.599 INFO [stdout] test utils::tests::render_markdown::preserves_external_links ... ok
Dec 13 05:08:46.603 INFO [stdout] test utils::tests::render_markdown::it_can_adjust_markdown_links ... ok
Dec 13 05:08:46.619 INFO [stdout] test theme::tests::theme_dir_overrides_defaults ... ok
Dec 13 05:08:46.651 INFO [stdout] test utils::tests::render_markdown::rust_code_block_without_properties_has_proper_html_class ... ok
Dec 13 05:08:46.651 INFO [stdout] test utils::tests::render_markdown::whitespace_outside_of_codeblock_header_is_preserved ... ok
Dec 13 05:08:46.651 INFO [stdout] test utils::fs::tests::copy_files_except_ext_test ... ok
Dec 13 05:08:46.651 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_next_previous ... ok
Dec 13 05:08:46.670 INFO [stdout] test renderer::html_handlebars::hbs_renderer::tests::original_build_header_links ... ok
Dec 13 05:08:46.682 INFO [stdout] test preprocess::cmd::tests::round_trip_write_and_parse_input ... ok
Dec 13 05:08:46.682 INFO [stdout] 
Dec 13 05:08:46.682 INFO [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:46.682 INFO [stdout] 
Dec 13 05:08:46.692 INFO [stderr]      Running /target/debug/deps/mdbook-b2b28d909e8fe48b
Dec 13 05:08:46.698 INFO [stdout] 
Dec 13 05:08:46.698 INFO [stdout] running 0 tests
Dec 13 05:08:46.698 INFO [stdout] 
Dec 13 05:08:46.698 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:46.698 INFO [stdout] 
Dec 13 05:08:46.699 INFO [stderr]      Running /target/debug/deps/alternate_backends-6cc839f9f59707dd
Dec 13 05:08:46.703 INFO [stdout] 
Dec 13 05:08:46.703 INFO [stdout] running 5 tests
Dec 13 05:08:46.786 INFO [stdout] Hello World!
Dec 13 05:08:46.787 INFO [stdout] test missing_backends_arent_fatal ... {"version":"0.2.3-alpha.0","root":"/tmp/mdbookFKtOfm","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/outputipX0a5/out.txt"}}},"destination":"/tmp/mdbookFKtOfm/book"}ok
Dec 13 05:08:46.793 INFO [stdout] test alternate_backend_with_arguments ... ok
Dec 13 05:08:46.793 INFO [stdout] test passing_alternate_backend ... ok
Dec 13 05:08:46.793 INFO [stdout] test backends_receive_render_context_via_stdin ... ok
Dec 13 05:08:46.794 INFO [stdout] test failing_alternate_backend ... ok
Dec 13 05:08:46.794 INFO [stdout] 
Dec 13 05:08:46.794 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:46.794 INFO [stdout] 
Dec 13 05:08:46.803 INFO [stderr]      Running /target/debug/deps/build_process-afd198b4ae237a30
Dec 13 05:08:46.810 INFO [stdout] 
Dec 13 05:08:46.810 INFO [stdout] running 2 tests
Dec 13 05:08:47.731 INFO [stdout] test mdbook_runs_preprocessors ... ok
Dec 13 05:08:47.766 INFO [stdout] test mdbook_runs_renderers ... ok
Dec 13 05:08:47.766 INFO [stdout] 
Dec 13 05:08:47.766 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:47.766 INFO [stdout] 
Dec 13 05:08:47.771 INFO [stderr]      Running /target/debug/deps/custom_preprocessors-188c09000e29db70
Dec 13 05:08:47.803 INFO [stdout] 
Dec 13 05:08:47.803 INFO [stdout] running 4 tests
Dec 13 05:08:48.520 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:08:48.560 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:08:48.601 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 13 05:08:48.705 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.88s
Dec 13 05:08:48.748 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports whatever`
Dec 13 05:08:48.756 INFO [stdout] test example_supports_whatever ... ok
Dec 13 05:08:48.841 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.88s
Dec 13 05:08:48.891 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 13 05:08:49.010 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.18s
Dec 13 05:08:49.074 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports not-supported`
Dec 13 05:08:49.084 INFO [stdout] test example_doesnt_support_not_supported ... ok
Dec 13 05:08:49.179 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.23s
Dec 13 05:08:49.199 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 13 05:08:49.389 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.48s
Dec 13 05:08:49.415 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 13 05:08:49.424 INFO [stderr] Boom!!1!
Dec 13 05:08:49.436 INFO [stdout] test ask_the_preprocessor_to_blow_up ... ok
Dec 13 05:08:49.586 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.37s
Dec 13 05:08:49.597 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 13 05:08:50.107 INFO [stdout] test process_the_dummy_book ... ok
Dec 13 05:08:50.107 INFO [stdout] 
Dec 13 05:08:50.107 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:50.107 INFO [stdout] 
Dec 13 05:08:50.109 INFO [stderr]      Running /target/debug/deps/init-c4e5782c15a2e650
Dec 13 05:08:50.113 INFO [stdout] 
Dec 13 05:08:50.113 INFO [stdout] running 3 tests
Dec 13 05:08:50.131 INFO [stdout] test base_mdbook_init_should_create_default_content ... ok
Dec 13 05:08:50.143 INFO [stdout] test run_mdbook_init_with_custom_book_and_src_locations ... ok
Dec 13 05:08:50.501 INFO [stdout] test book_toml_isnt_required ... ok
Dec 13 05:08:50.501 INFO [stdout] 
Dec 13 05:08:50.501 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:50.501 INFO [stdout] 
Dec 13 05:08:50.507 INFO [stderr]      Running /target/debug/deps/parse_existing_summary_files-a64c8e8438847888
Dec 13 05:08:50.518 INFO [stdout] 
Dec 13 05:08:50.518 INFO [stdout] running 4 tests
Dec 13 05:08:50.564 INFO [stdout] test rust_ffi_guide ... ok
Dec 13 05:08:50.564 INFO [stdout] test example_book ... ok
Dec 13 05:08:50.570 INFO [stdout] test the_book_2nd_edition ... ok
Dec 13 05:08:50.585 INFO [stdout] test rust_by_example ... ok
Dec 13 05:08:50.585 INFO [stdout] 
Dec 13 05:08:50.585 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:08:50.585 INFO [stdout] 
Dec 13 05:08:50.591 INFO [stderr]      Running /target/debug/deps/rendered_output-258a6dde684f1c2b
Dec 13 05:08:50.595 INFO [stdout] 
Dec 13 05:08:50.595 INFO [stdout] running 21 tests
Dec 13 05:08:50.631 INFO [stdout] test chapter_files_were_rendered_to_html ... ok
Dec 13 05:08:52.139 INFO [stdout] test book_with_a_reserved_filename_does_not_build ... ok
Dec 13 05:08:52.939 INFO [stdout] test by_default_mdbook_use_index_preprocessor_to_convert_readme_to_index ... ok
Dec 13 05:08:53.851 INFO [stdout] test build_the_dummy_book ... ok
Dec 13 05:08:53.871 INFO [stdout] test chapter_content_appears_in_rendered_document ... ok
Dec 13 05:08:53.871 INFO [stdout] test check_correct_cross_links_in_nested_dir ... ok
Dec 13 05:08:53.970 INFO [stdout] test able_to_include_files_in_chapters ... ok
Dec 13 05:08:53.978 INFO [stdout] test create_missing_file_with_config ... ok
Dec 13 05:08:53.980 INFO [stdout] test able_to_include_playpen_files_in_chapters ... ok
Dec 13 05:08:53.993 INFO [stdout] test failure_on_missing_file ... ok
Dec 13 05:08:54.047 INFO [stdout] test by_default_mdbook_generates_rendered_content_in_the_book_directory ... ok
Dec 13 05:08:55.095 INFO [stdout] test check_first_toc_level ... ok
Dec 13 05:08:55.659 INFO [stdout] test check_second_toc_level ... ok
Dec 13 05:08:56.811 INFO [stdout] test rendered_code_has_playpen_stuff ... ok
Dec 13 05:08:56.851 INFO [stdout] test make_sure_bottom_level_files_contain_links_to_chapters ... ok
Dec 13 05:08:56.879 INFO [stdout] test recursive_includes_are_capped ... ok
Dec 13 05:08:56.927 INFO [stdout] test check_spacers ... ok
Dec 13 05:08:56.999 INFO [stdout] test search::book_creates_reasonable_search_index ... ok
Dec 13 05:08:57.367 INFO [stdout] test search::search_index_hasnt_changed_accidentally ... ok
Dec 13 05:09:01.264 INFO [stdout] test example_book_can_build ... ok
Dec 13 05:09:01.373 INFO [stdout] test theme_dir_overrides_work_correctly ... ok
Dec 13 05:09:01.373 INFO [stdout] 
Dec 13 05:09:01.373 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:01.373 INFO [stdout] 
Dec 13 05:09:01.376 INFO [stderr]      Running /target/debug/deps/testing-b59a1b0f6abbf036
Dec 13 05:09:01.387 INFO [stdout] 
Dec 13 05:09:01.387 INFO [stdout] running 2 tests
Dec 13 05:09:02.651 INFO [stdout] test mdbook_can_correctly_test_a_passing_book ... ok
Dec 13 05:09:03.259 INFO [stdout] test mdbook_detects_book_with_failing_tests ... ok
Dec 13 05:09:03.259 INFO [stderr]    Doc-tests mdbook
Dec 13 05:09:03.259 INFO [stdout] 
Dec 13 05:09:03.259 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:03.259 INFO [stdout] 
Dec 13 05:09:06.069 INFO [stdout] 
Dec 13 05:09:06.069 INFO [stdout] running 5 tests
Dec 13 05:09:16.644 INFO [stdout] test src/book/mod.rs - book::MDBook::iter (line 102) ... ok
Dec 13 05:09:16.999 INFO [stdout] test src/lib.rs -  (line 30) ... ok
Dec 13 05:09:17.031 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Dec 13 05:09:21.878 INFO [stdout] test src/utils/fs.rs - utils::fs::path_to_root (line 40) ... ok
Dec 13 05:09:22.095 INFO [stdout] test src/config.rs - config (line 11) ... ok
Dec 13 05:09:22.095 INFO [stdout] 
Dec 13 05:09:22.095 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 05:09:22.095 INFO [stdout] 
Dec 13 05:09:22.128 INFO [stderr] su: No module specific data is present
Dec 13 05:09:23.336 INFO running `"docker" "inspect" "762a4069cb7ff0b479da9f6c01b5ed97f0a96fbf727dbcca3fab673155330737"`
Dec 13 05:09:23.585 INFO running `"docker" "rm" "-f" "762a4069cb7ff0b479da9f6c01b5ed97f0a96fbf727dbcca3fab673155330737"`
Dec 13 05:09:23.812 INFO [stdout] 762a4069cb7ff0b479da9f6c01b5ed97f0a96fbf727dbcca3fab673155330737