Dec 01 04:04:10.829 INFO testing rust-lang-nursery/mdBook against 1.30.1 for beta-1.31-5
Dec 01 04:04:10.830 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 04:04:11.257 INFO [stdout] 9f582131fc2f7c5f4eb260145ca51bfe847c562ab6ad851ca7d403573f4c262c
Dec 01 04:04:11.260 INFO running `"docker" "start" "-a" "9f582131fc2f7c5f4eb260145ca51bfe847c562ab6ad851ca7d403573f4c262c"`
Dec 01 04:04:12.829 INFO [stderr] usermod: no changes
Dec 01 04:04:13.295 INFO [stderr]    Compiling sequence_trie v0.3.5
Dec 01 04:04:13.297 INFO [stderr]    Compiling pulldown-cmark v0.1.2
Dec 01 04:04:13.304 INFO [stderr]    Compiling strum v0.9.0
Dec 01 04:04:13.305 INFO [stderr]    Compiling is-match v0.1.0
Dec 01 04:04:13.305 INFO [stderr]    Compiling open v1.2.2
Dec 01 04:04:13.306 INFO [stderr]    Compiling shlex v0.1.1
Dec 01 04:04:13.308 INFO [stderr]    Compiling inotify-sys v0.1.3
Dec 01 04:04:13.316 INFO [stderr]    Compiling log v0.4.5
Dec 01 04:04:14.659 INFO [stderr]    Compiling filetime v0.2.1
Dec 01 04:04:14.695 INFO [stderr]    Compiling base64 v0.6.0
Dec 01 04:04:14.955 INFO [stderr]    Compiling tempfile v3.0.4
Dec 01 04:04:15.215 INFO [stderr]    Compiling serde_json v1.0.32
Dec 01 04:04:16.924 INFO [stderr]    Compiling pest_derive v1.0.8
Dec 01 04:04:16.924 INFO [stderr]    Compiling syn v0.15.12
Dec 01 04:04:16.944 INFO [stderr]    Compiling syn v0.14.9
Dec 01 04:04:17.051 INFO [stderr]    Compiling log v0.3.9
Dec 01 04:04:19.369 INFO [stderr]    Compiling mio v0.6.16
Dec 01 04:04:19.499 INFO [stderr]    Compiling tokio-io v0.1.9
Dec 01 04:04:22.541 INFO [stderr]    Compiling error-chain v0.12.0
Dec 01 04:04:29.991 INFO [stderr]    Compiling env_logger v0.5.13
Dec 01 04:04:31.916 INFO [stderr]    Compiling mime v0.2.6
Dec 01 04:04:46.230 INFO [stderr]    Compiling toml-query v0.7.0
Dec 01 04:04:46.404 INFO [stderr]    Compiling hyper v0.10.13
Dec 01 04:04:46.440 INFO [stderr]    Compiling mio-extras v2.0.5
Dec 01 04:04:46.449 INFO [stderr]    Compiling tokio-reactor v0.1.6
Dec 01 04:05:04.296 INFO [stderr]    Compiling handlebars v1.0.5
Dec 01 04:05:04.704 INFO [stderr]    Compiling ws v0.7.9
Dec 01 04:05:05.196 INFO [stderr]    Compiling strum_macros v0.9.1
Dec 01 04:05:06.817 INFO [stderr]    Compiling mime_guess v1.8.6
Dec 01 04:05:10.747 INFO [stderr]    Compiling string_cache v0.7.3
Dec 01 04:05:11.423 INFO [stderr]    Compiling inotify v0.6.1
Dec 01 04:05:12.271 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 01 04:05:25.093 INFO [stderr]    Compiling notify v4.0.6
Dec 01 04:05:40.355 INFO [stderr]    Compiling iron v0.6.0
Dec 01 04:05:50.194 INFO [stderr]    Compiling mount v0.4.0
Dec 01 04:05:52.649 INFO [stderr]    Compiling staticfile v0.5.0
Dec 01 04:06:04.245 INFO [stderr]    Compiling markup5ever v0.7.3
Dec 01 04:06:04.246 INFO [stderr]    Compiling elasticlunr-rs v2.3.3
Dec 01 04:06:18.872 INFO [stderr]    Compiling html5ever v0.22.3
Dec 01 04:06:22.259 INFO [stderr]    Compiling ammonia v1.2.0
Dec 01 04:06:27.072 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 01 04:06:52.971 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 40s
Dec 01 04:06:52.996 INFO [stderr] su: No module specific data is present
Dec 01 04:06:56.179 INFO running `"docker" "inspect" "9f582131fc2f7c5f4eb260145ca51bfe847c562ab6ad851ca7d403573f4c262c"`
Dec 01 04:06:56.557 INFO running `"docker" "rm" "-f" "9f582131fc2f7c5f4eb260145ca51bfe847c562ab6ad851ca7d403573f4c262c"`
Dec 01 04:06:56.947 INFO [stdout] 9f582131fc2f7c5f4eb260145ca51bfe847c562ab6ad851ca7d403573f4c262c
Dec 01 04:06:56.951 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 04:06:57.465 INFO [stdout] 67c488a4c5577581061274663654ec36f7fbc7ba31c5f7a7f95a526635a483b8
Dec 01 04:06:57.468 INFO running `"docker" "start" "-a" "67c488a4c5577581061274663654ec36f7fbc7ba31c5f7a7f95a526635a483b8"`
Dec 01 04:06:59.663 INFO [stderr] usermod: no changes
Dec 01 04:07:00.069 INFO [stderr]    Compiling rustc-serialize v0.3.24
Dec 01 04:07:00.086 INFO [stderr]    Compiling pretty_assertions v0.5.1
Dec 01 04:07:00.100 INFO [stderr]    Compiling html5ever v0.18.0
Dec 01 04:07:00.101 INFO [stderr]    Compiling pulldown-cmark-to-cmark v1.1.0
Dec 01 04:07:00.119 INFO [stderr]    Compiling tendril v0.3.1
Dec 01 04:07:00.128 INFO [stderr]    Compiling string_cache v0.6.2
Dec 01 04:07:13.815 INFO [stderr]    Compiling markup5ever v0.3.2
Dec 01 04:07:27.928 INFO [stderr]    Compiling select v0.4.2
Dec 01 04:07:32.259 INFO [stderr]    Compiling mdbook v0.2.3-alpha.0 (/source)
Dec 01 04:08:09.643 INFO [stderr] warning: struct is never constructed: `Deemphasize`
Dec 01 04:08:09.643 INFO [stderr]   --> examples/de-emphasize.rs:20:1
Dec 01 04:08:09.643 INFO [stderr]    |
Dec 01 04:08:09.643 INFO [stderr] 20 | struct Deemphasize;
Dec 01 04:08:09.643 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^
Dec 01 04:08:09.643 INFO [stderr]    |
Dec 01 04:08:09.643 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 01 04:08:09.643 INFO [stderr] 
Dec 01 04:08:42.692 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 43s
Dec 01 04:08:42.739 INFO [stderr] su: No module specific data is present
Dec 01 04:08:45.442 INFO running `"docker" "inspect" "67c488a4c5577581061274663654ec36f7fbc7ba31c5f7a7f95a526635a483b8"`
Dec 01 04:08:45.807 INFO running `"docker" "rm" "-f" "67c488a4c5577581061274663654ec36f7fbc7ba31c5f7a7f95a526635a483b8"`
Dec 01 04:08:46.097 INFO [stdout] 67c488a4c5577581061274663654ec36f7fbc7ba31c5f7a7f95a526635a483b8
Dec 01 04:08:46.104 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 04:08:46.644 INFO [stdout] e34c3b84a7f925aa4514ccfec1c6bd11698ef3968361720bd520d7b09ce36827
Dec 01 04:08:46.647 INFO running `"docker" "start" "-a" "e34c3b84a7f925aa4514ccfec1c6bd11698ef3968361720bd520d7b09ce36827"`
Dec 01 04:08:48.054 INFO [stderr] usermod: no changes
Dec 01 04:08:48.691 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.60s
Dec 01 04:08:48.739 INFO [stderr]      Running /target/debug/deps/mdbook-aa3c237ff888a485
Dec 01 04:08:48.747 INFO [stdout] 
Dec 01 04:08:48.747 INFO [stdout] running 79 tests
Dec 01 04:08:48.751 INFO [stdout] test book::book::tests::cant_load_a_nonexistent_chapter ... ok
Dec 01 04:08:48.751 INFO [stdout] test book::book::tests::book_iter_iterates_over_sequential_items ... ok
Dec 01 04:08:48.751 INFO [stdout] test book::book::tests::cant_load_chapters_when_the_link_is_a_directory ... ok
Dec 01 04:08:48.751 INFO [stdout] test book::summary::tests::can_have_a_subheader_between_nested_items ... ok
Dec 01 04:08:48.751 INFO [stdout] test book::book::tests::load_a_single_chapter_from_disk ... ok
Dec 01 04:08:48.751 INFO [stdout] test book::summary::tests::an_empty_link_location_is_an_error ... ok
Dec 01 04:08:48.752 INFO [stdout] test book::summary::tests::convert_markdown_events_to_a_string ... ok
Dec 01 04:08:48.752 INFO [stdout] test book::summary::tests::parse_a_link ... ok
Dec 01 04:08:48.752 INFO [stdout] test book::summary::tests::keep_numbering_after_separator ... ok
Dec 01 04:08:48.752 INFO [stdout] test book::book::tests::load_recursive_link_with_separators ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_initial_title ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_a_numbered_chapter ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_nested_numbered_chapters ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_prefix_items_with_a_separator ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_title_with_styling ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::parse_some_prefix_items ... ok
Dec 01 04:08:48.753 INFO [stdout] test book::summary::tests::section_number_has_correct_dotted_representation ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::summary::tests::suffix_items_cannot_be_followed_by_a_list ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::add_a_random_renderer_with_custom_command_to_the_config ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::add_a_random_renderer_to_the_config ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::can_determine_third_party_preprocessors ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::config_defaults_to_html_renderer_if_empty ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::config_respects_preprocessor_selection ... ok
Dec 01 04:08:48.755 INFO [stdout] test book::tests::config_defaults_to_link_and_index_preprocessor_if_not_set ... ok
Dec 01 04:08:48.757 INFO [stdout] test book::tests::preprocessor_should_run_falls_back_to_supports_renderer_method ... ok
Dec 01 04:08:48.757 INFO [stdout] test book::tests::preprocessors_can_provide_their_own_commands ... ok
Dec 01 04:08:48.757 INFO [stdout] test book::book::tests::load_a_book_with_a_single_chapter ... ok
Dec 01 04:08:48.757 INFO [stdout] test book::tests::use_default_preprocessors_works ... ok
Dec 01 04:08:48.757 INFO [stdout] test book::book::tests::iterate_over_nested_book_items ... ok
Dec 01 04:08:48.775 INFO [stdout] test config::tests::can_still_load_the_previous_format ... ok
Dec 01 04:08:48.775 INFO [stdout] test config::tests::parse_env_vars ... ok
Dec 01 04:08:48.776 INFO [stdout] test config::tests::mutate_some_stuff ... ok
Dec 01 04:08:48.776 INFO [stdout] test config::tests::set_a_config_item ... ok
Dec 01 04:08:48.776 INFO [stdout] test book::book::tests::cant_load_chapters_with_an_empty_path ... ok
Dec 01 04:08:48.776 INFO [stdout] test book::book::tests::for_each_mut_visits_all_items ... ok
Dec 01 04:08:48.776 INFO [stdout] test config::tests::load_a_complex_config_file ... ok
Dec 01 04:08:48.776 INFO [stdout] test config::tests::load_arbitrary_output_type ... ok
Dec 01 04:08:48.776 INFO [stdout] test config::tests::update_book_title_via_env ... ok
Dec 01 04:08:48.819 INFO [stdout] test preprocess::links::tests::test_find_links_no_link ... ok
Dec 01 04:08:48.819 INFO [stdout] test preprocess::links::tests::test_find_links_empty_link ... ok
Dec 01 04:08:48.828 INFO [stdout] test config::tests::update_config_using_env_var_and_complex_value ... ok
Dec 01 04:08:48.828 INFO [stdout] test config::tests::update_config_using_env_var ... ok
Dec 01 04:08:48.828 INFO [stdout] test preprocess::links::tests::test_find_links_partial_link ... ok
Dec 01 04:08:48.828 INFO [stdout] test preprocess::links::tests::test_find_all_link_types ... ok
Dec 01 04:08:48.828 INFO [stdout] test preprocess::links::tests::test_find_links_simple_link ... ok
Dec 01 04:08:48.828 INFO [stdout] test preprocess::links::tests::test_find_links_unknown_link_type ... ok
Dec 01 04:08:48.829 INFO [stdout] test preprocess::links::tests::test_find_links_with_from_range ... ok
Dec 01 04:08:48.829 INFO [stdout] test preprocess::links::tests::test_find_links_with_full_range ... ok
Dec 01 04:08:48.829 INFO [stdout] test preprocess::links::tests::test_find_links_with_line_number ... ok
Dec 01 04:08:48.829 INFO [stdout] test preprocess::links::tests::test_find_links_with_no_range_specified ... ok
Dec 01 04:08:48.829 INFO [stdout] test preprocess::links::tests::test_find_links_with_range ... ok
Dec 01 04:08:48.843 INFO [stdout] test preprocess::index::tests::file_stem_exactly_matches_readme_case_insensitively ... ok
Dec 01 04:08:48.843 INFO [stdout] test preprocess::links::tests::test_replace_all_escaped ... ok
Dec 01 04:08:48.843 INFO [stdout] test preprocess::links::tests::test_find_links_with_to_range ... ok
Dec 01 04:08:48.859 INFO [stdout] test preprocess::links::tests::test_find_playpens_with_properties ... ok
Dec 01 04:08:48.859 INFO [stdout] test preprocess::links::tests::test_find_links_escaped_link ... ok
Dec 01 04:08:48.863 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_next_previous ... ok
Dec 01 04:08:48.863 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_last ... ok
Dec 01 04:08:48.863 INFO [stdout] test utils::fs::tests::copy_files_except_ext_test ... ok
Dec 01 04:08:48.883 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_single_quotes ... ok
Dec 01 04:08:48.883 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_converts_double_quotes ... ok
Dec 01 04:08:48.883 INFO [stdout] test renderer::html_handlebars::helpers::navigation::tests::test_first ... ok
Dec 01 04:08:48.883 INFO [stdout] test theme::tests::theme_uses_defaults_with_nonexistent_src_dir ... ok
Dec 01 04:08:48.891 INFO [stdout] test utils::string::tests::take_lines_test ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::convert_quotes_to_curly::it_treats_tab_as_whitespace ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::html_munging::it_generates_anchors ... ok
Dec 01 04:08:48.911 INFO [stdout] test theme::tests::theme_dir_overrides_defaults ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::html_munging::it_normalizes_ids ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::html_munging::it_generates_anchors_from_non_ascii_initial ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_are_passed_as_space_delimited_class ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::rust_code_block_properties_with_whitespace_are_passed_as_space_delimited_class ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::preserves_external_links ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::it_can_make_quotes_curly_except_when_they_are_in_code ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::it_can_keep_quotes_straight ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::whitespace_outside_of_codeblock_header_is_preserved ... ok
Dec 01 04:08:48.911 INFO [stdout] test utils::tests::render_markdown::rust_code_block_without_properties_has_proper_html_class ... ok
Dec 01 04:08:48.946 INFO [stdout] test utils::tests::render_markdown::it_can_adjust_markdown_links ... ok
Dec 01 04:08:48.946 INFO [stdout] test renderer::html_handlebars::hbs_renderer::tests::original_build_header_links ... ok
Dec 01 04:08:48.951 INFO [stdout] test preprocess::cmd::tests::round_trip_write_and_parse_input ... ok
Dec 01 04:08:48.951 INFO [stdout] 
Dec 01 04:08:48.951 INFO [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:48.951 INFO [stdout] 
Dec 01 04:08:48.951 INFO [stderr]      Running /target/debug/deps/mdbook-bd856eba52510f4c
Dec 01 04:08:48.959 INFO [stderr]      Running /target/debug/deps/alternate_backends-dc7840e5c044f516
Dec 01 04:08:48.959 INFO [stdout] 
Dec 01 04:08:48.959 INFO [stdout] running 0 tests
Dec 01 04:08:48.959 INFO [stdout] 
Dec 01 04:08:48.959 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:48.959 INFO [stdout] 
Dec 01 04:08:48.967 INFO [stdout] 
Dec 01 04:08:48.967 INFO [stdout] running 5 tests
Dec 01 04:08:48.995 INFO [stdout] test missing_backends_arent_fatal ... ok
Dec 01 04:08:48.995 INFO [stdout] test failing_alternate_backend ... ok
Dec 01 04:08:48.995 INFO [stdout] Hello World!
Dec 01 04:08:49.003 INFO [stdout] test alternate_backend_with_arguments ... ok
Dec 01 04:08:49.007 INFO [stdout] {"version":"0.2.3-alpha.0","root":"/tmp/mdbook8IQOU0","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/output9Nw2M1/out.txt"}}},"destination":"/tmp/mdbook8IQOU0/book"}test backends_receive_render_context_via_stdin ... ok
Dec 01 04:08:49.015 INFO [stdout] test passing_alternate_backend ... ok
Dec 01 04:08:49.015 INFO [stdout] 
Dec 01 04:08:49.015 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:49.015 INFO [stdout] 
Dec 01 04:08:49.023 INFO [stderr]      Running /target/debug/deps/build_process-9e2c4fa5b9c38d2a
Dec 01 04:08:49.023 INFO [stdout] 
Dec 01 04:08:49.023 INFO [stdout] running 2 tests
Dec 01 04:08:49.647 INFO [stdout] test mdbook_runs_renderers ... ok
Dec 01 04:08:49.762 INFO [stdout] test mdbook_runs_preprocessors ... ok
Dec 01 04:08:49.762 INFO [stdout] 
Dec 01 04:08:49.762 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:49.762 INFO [stdout] 
Dec 01 04:08:49.768 INFO [stderr]      Running /target/debug/deps/custom_preprocessors-882b4e0dd07570ad
Dec 01 04:08:49.771 INFO [stdout] 
Dec 01 04:08:49.771 INFO [stdout] running 4 tests
Dec 01 04:08:50.447 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 01 04:08:50.460 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 01 04:08:50.481 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 01 04:08:50.598 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.74s
Dec 01 04:08:50.620 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 01 04:08:50.832 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.01s
Dec 01 04:08:50.869 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports not-supported`
Dec 01 04:08:50.891 INFO [stdout] test example_doesnt_support_not_supported ... ok
Dec 01 04:08:50.979 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 01 04:08:51.079 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.14s
Dec 01 04:08:51.091 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports html`
Dec 01 04:08:51.311 INFO [stderr]     Blocking waiting for file lock on build directory
Dec 01 04:08:51.327 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.68s
Dec 01 04:08:51.363 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 01 04:08:51.371 INFO [stderr] Boom!!1!
Dec 01 04:08:51.379 INFO [stdout] test ask_the_preprocessor_to_blow_up ... ok
Dec 01 04:08:51.487 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.37s
Dec 01 04:08:51.503 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor`
Dec 01 04:08:51.707 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.83s
Dec 01 04:08:51.731 INFO [stderr]      Running `/target/debug/examples/nop-preprocessor supports whatever`
Dec 01 04:08:51.739 INFO [stdout] test example_supports_whatever ... ok
Dec 01 04:08:52.098 INFO [stdout] test process_the_dummy_book ... ok
Dec 01 04:08:52.098 INFO [stdout] 
Dec 01 04:08:52.098 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:52.098 INFO [stdout] 
Dec 01 04:08:52.099 INFO [stderr]      Running /target/debug/deps/init-f3858858581ab1d1
Dec 01 04:08:52.143 INFO [stdout] 
Dec 01 04:08:52.143 INFO [stdout] running 3 tests
Dec 01 04:08:52.248 INFO [stdout] test run_mdbook_init_with_custom_book_and_src_locations ... ok
Dec 01 04:08:52.255 INFO [stdout] test base_mdbook_init_should_create_default_content ... ok
Dec 01 04:08:52.608 INFO [stdout] test book_toml_isnt_required ... ok
Dec 01 04:08:52.608 INFO [stdout] 
Dec 01 04:08:52.608 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:52.608 INFO [stdout] 
Dec 01 04:08:52.610 INFO [stderr]      Running /target/debug/deps/parse_existing_summary_files-91e7b4595014c249
Dec 01 04:08:52.633 INFO [stdout] 
Dec 01 04:08:52.633 INFO [stdout] running 4 tests
Dec 01 04:08:52.701 INFO [stdout] test example_book ... ok
Dec 01 04:08:52.701 INFO [stdout] test rust_ffi_guide ... ok
Dec 01 04:08:52.701 INFO [stdout] test the_book_2nd_edition ... ok
Dec 01 04:08:52.711 INFO [stdout] test rust_by_example ... ok
Dec 01 04:08:52.712 INFO [stdout] 
Dec 01 04:08:52.712 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:08:52.712 INFO [stdout] 
Dec 01 04:08:52.716 INFO [stderr]      Running /target/debug/deps/rendered_output-5db60c1e587be8fc
Dec 01 04:08:52.719 INFO [stdout] 
Dec 01 04:08:52.719 INFO [stdout] running 21 tests
Dec 01 04:08:52.753 INFO [stdout] test chapter_files_were_rendered_to_html ... ok
Dec 01 04:08:53.980 INFO [stdout] test book_with_a_reserved_filename_does_not_build ... ok
Dec 01 04:08:53.999 INFO [stdout] test by_default_mdbook_use_index_preprocessor_to_convert_readme_to_index ... ok
Dec 01 04:08:54.912 INFO [stdout] test chapter_content_appears_in_rendered_document ... ok
Dec 01 04:08:54.988 INFO [stdout] test able_to_include_playpen_files_in_chapters ... ok
Dec 01 04:08:54.995 INFO [stdout] test create_missing_file_with_config ... ok
Dec 01 04:08:55.152 INFO [stdout] test by_default_mdbook_generates_rendered_content_in_the_book_directory ... ok
Dec 01 04:08:55.195 INFO [stdout] test failure_on_missing_file ... ok
Dec 01 04:08:55.195 INFO [stdout] test check_correct_cross_links_in_nested_dir ... ok
Dec 01 04:08:55.291 INFO [stdout] test build_the_dummy_book ... ok
Dec 01 04:08:55.539 INFO [stdout] test able_to_include_files_in_chapters ... ok
Dec 01 04:08:55.571 INFO [stdout] test check_second_toc_level ... ok
Dec 01 04:08:56.094 INFO [stdout] test check_first_toc_level ... ok
Dec 01 04:08:56.468 INFO [stdout] test make_sure_bottom_level_files_contain_links_to_chapters ... ok
Dec 01 04:08:56.671 INFO [stdout] test check_spacers ... ok
Dec 01 04:08:56.843 INFO [stdout] test recursive_includes_are_capped ... ok
Dec 01 04:08:57.063 INFO [stdout] test search::book_creates_reasonable_search_index ... ok
Dec 01 04:08:57.403 INFO [stdout] test rendered_code_has_playpen_stuff ... ok
Dec 01 04:08:57.503 INFO [stdout] test search::search_index_hasnt_changed_accidentally ... ok
Dec 01 04:09:00.203 INFO [stdout] test example_book_can_build ... ok
Dec 01 04:09:01.175 INFO [stderr]      Running /target/debug/deps/testing-0f1f618e92295412
Dec 01 04:09:01.175 INFO [stdout] test theme_dir_overrides_work_correctly ... ok
Dec 01 04:09:01.175 INFO [stdout] 
Dec 01 04:09:01.175 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:09:01.176 INFO [stdout] 
Dec 01 04:09:01.179 INFO [stdout] 
Dec 01 04:09:01.179 INFO [stdout] running 2 tests
Dec 01 04:09:02.678 INFO [stdout] test mdbook_can_correctly_test_a_passing_book ... ok
Dec 01 04:09:03.072 INFO [stdout] test mdbook_detects_book_with_failing_tests ... ok
Dec 01 04:09:03.072 INFO [stdout] 
Dec 01 04:09:03.073 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:09:03.073 INFO [stdout] 
Dec 01 04:09:03.075 INFO [stderr]    Doc-tests mdbook
Dec 01 04:09:06.015 INFO [stdout] 
Dec 01 04:09:06.015 INFO [stdout] running 5 tests
Dec 01 04:09:13.817 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Dec 01 04:09:14.463 INFO [stdout] test src/book/mod.rs - book::MDBook::iter (line 101) ... ok
Dec 01 04:09:14.927 INFO [stdout] test src/lib.rs -  (line 30) ... ok
Dec 01 04:09:20.159 INFO [stdout] test src/utils/fs.rs - utils::fs::path_to_root (line 40) ... ok
Dec 01 04:09:20.467 INFO [stdout] test src/config.rs - config (line 11) ... ok
Dec 01 04:09:20.468 INFO [stdout] 
Dec 01 04:09:20.468 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 04:09:20.468 INFO [stdout] 
Dec 01 04:09:20.502 INFO [stderr] su: No module specific data is present
Dec 01 04:09:21.861 INFO running `"docker" "inspect" "e34c3b84a7f925aa4514ccfec1c6bd11698ef3968361720bd520d7b09ce36827"`
Dec 01 04:09:22.249 INFO running `"docker" "rm" "-f" "e34c3b84a7f925aa4514ccfec1c6bd11698ef3968361720bd520d7b09ce36827"`
Dec 01 04:09:22.591 INFO [stdout] e34c3b84a7f925aa4514ccfec1c6bd11698ef3968361720bd520d7b09ce36827
