Dec 15 13:51:15.071 INFO testing gen-epub-book-2.3.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 13:51:15.071 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 15 13:51:15.275 INFO [stdout] 48a2ed16a731cb76d1ba3434dec87098078e679c548468a928c4997fb483613a Dec 15 13:51:15.280 INFO running `"docker" "start" "-a" "48a2ed16a731cb76d1ba3434dec87098078e679c548468a928c4997fb483613a"` Dec 15 13:51:15.824 INFO [stderr] usermod: no changes Dec 15 13:51:16.204 INFO [stderr] Compiling gen-epub-book v2.3.1 (/source) Dec 15 13:51:42.944 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 27.07s Dec 15 13:51:42.960 INFO [stderr] su: No module specific data is present Dec 15 13:51:43.501 INFO running `"docker" "inspect" "48a2ed16a731cb76d1ba3434dec87098078e679c548468a928c4997fb483613a"` Dec 15 13:51:43.596 INFO running `"docker" "rm" "-f" "48a2ed16a731cb76d1ba3434dec87098078e679c548468a928c4997fb483613a"` Dec 15 13:51:43.705 INFO [stdout] 48a2ed16a731cb76d1ba3434dec87098078e679c548468a928c4997fb483613a Dec 15 13:51:43.706 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 15 13:51:44.003 INFO [stdout] 065457a565ad9c8af832ca0dd98c7021024bdf8ce904d364900d621b1e9abdfa Dec 15 13:51:44.005 INFO running `"docker" "start" "-a" "065457a565ad9c8af832ca0dd98c7021024bdf8ce904d364900d621b1e9abdfa"` Dec 15 13:51:44.679 INFO [stderr] usermod: no changes Dec 15 13:51:44.980 INFO [stderr] Compiling gen-epub-book v2.3.1 (/source) Dec 15 13:52:00.506 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 15.80s Dec 15 13:52:00.523 INFO [stderr] su: No module specific data is present Dec 15 13:52:01.093 INFO running `"docker" "inspect" "065457a565ad9c8af832ca0dd98c7021024bdf8ce904d364900d621b1e9abdfa"` Dec 15 13:52:01.420 INFO running `"docker" "rm" "-f" "065457a565ad9c8af832ca0dd98c7021024bdf8ce904d364900d621b1e9abdfa"` Dec 15 13:52:01.715 INFO [stdout] 065457a565ad9c8af832ca0dd98c7021024bdf8ce904d364900d621b1e9abdfa Dec 15 13:52:01.720 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 15 13:52:02.269 INFO [stdout] 27b478d7b93e000cf7b6b749523fdb3b1bfb272fc94410673a7a05ca8aac9efa Dec 15 13:52:02.271 INFO running `"docker" "start" "-a" "27b478d7b93e000cf7b6b749523fdb3b1bfb272fc94410673a7a05ca8aac9efa"` Dec 15 13:52:03.777 INFO [stderr] usermod: no changes Dec 15 13:52:04.039 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.24s Dec 15 13:52:04.053 INFO [stderr] Running /target/debug/deps/gen_epub_book-4eb86e1a5b113e55 Dec 15 13:52:04.059 INFO [stdout] Dec 15 13:52:04.059 INFO [stdout] running 0 tests Dec 15 13:52:04.059 INFO [stdout] Dec 15 13:52:04.059 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 13:52:04.059 INFO [stdout] Dec 15 13:52:04.063 INFO [stderr] Running /target/debug/deps/lib-90bbef956088983f Dec 15 13:52:04.081 INFO [stdout] Dec 15 13:52:04.081 INFO [stdout] running 108 tests Dec 15 13:52:04.082 INFO [stdout] test error::print::file_not_found ... ok Dec 15 13:52:04.082 INFO [stdout] test error::print::io::more::empty ... ok Dec 15 13:52:04.083 INFO [stdout] test error::exit_value::wrong_file_state ... ok Dec 15 13:52:04.083 INFO [stdout] test error::exit_value::wrong_element_amount ... ok Dec 15 13:52:04.083 INFO [stdout] test error::exit_value::required_element_missing ... ok Dec 15 13:52:04.084 INFO [stdout] test error::print::io::more::normal_non_e ... ok Dec 15 13:52:04.084 INFO [stdout] test error::print::io::more::single_e ... ok Dec 15 13:52:04.085 INFO [stdout] test error::exit_value::parse ... ok Dec 15 13:52:04.085 INFO [stdout] test error::print::io::more::normal_e ... ok Dec 15 13:52:04.086 INFO [stdout] test error::print::io::no_more::single_e ... ok Dec 15 13:52:04.086 INFO [stdout] test error::exit_value::io ... ok Dec 15 13:52:04.087 INFO [stdout] test error::print::io::more::single_non_e ... ok Dec 15 13:52:04.087 INFO [stdout] test error::print::io::no_more::normal_e ... ok Dec 15 13:52:04.087 INFO [stdout] test error::print::io::no_more::empty ... ok Dec 15 13:52:04.087 INFO [stdout] test error::print::parse::more ... ok Dec 15 13:52:04.088 INFO [stdout] test error::print::io::no_more::normal_non_e ... ok Dec 15 13:52:04.088 INFO [stdout] test error::print::parse::no_more ... ok Dec 15 13:52:04.089 INFO [stdout] test error::print::required_element_missing ... ok Dec 15 13:52:04.089 INFO [stdout] test error::print::io::no_more::single_non_e ... ok Dec 15 13:52:04.090 INFO [stdout] test error::print::wrong_element_amount ... ok Dec 15 13:52:04.090 INFO [stdout] test error::print::wrong_file_state ... ok Dec 15 13:52:04.090 INFO [stdout] test error::exit_value::file_not_found ... ok Dec 15 13:52:04.115 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_network_first ... ok Dec 15 13:52:04.120 INFO [stdout] test ops::book::from_elements::correct::network_cover ... ok Dec 15 13:52:04.120 INFO [stdout] test ops::book::from_elements::correct::file_cover ... ok Dec 15 13:52:04.123 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::date ... ok Dec 15 13:52:04.123 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_network ... ok Dec 15 13:52:04.123 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::author ... ok Dec 15 13:52:04.136 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_file ... ok Dec 15 13:52:04.136 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::language ... ok Dec 15 13:52:04.137 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::ordering ... ok Dec 15 13:52:04.138 INFO [stdout] test ops::book::from_elements::correct::no_cover ... ok Dec 15 13:52:04.138 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_file_first ... ok Dec 15 13:52:04.143 INFO [stdout] test ops::book::from_elements::incorrect::missing::author ... ok Dec 15 13:52:04.143 INFO [stdout] test ops::book::from_elements::incorrect::missing::name ... ok Dec 15 13:52:04.143 INFO [stdout] test ops::book::from_elements::incorrect::missing::language ... ok Dec 15 13:52:04.144 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::name ... ok Dec 15 13:52:04.144 INFO [stdout] test ops::book::from_elements::incorrect::missing::ordering ... ok Dec 15 13:52:04.144 INFO [stdout] test ops::book::from_elements::incorrect::missing::date ... ok Dec 15 13:52:04.144 INFO [stdout] test ops::book::normalise_paths::no_verbose::bad_type ... ok Dec 15 13:52:04.145 INFO [stdout] test ops::book::normalise_paths::verbose::bad_type ... ok Dec 15 13:52:04.146 INFO [stdout] test ops::book::normalise_paths::no_verbose::correct ... ok Dec 15 13:52:04.147 INFO [stdout] test ops::book::normalise_paths::no_verbose::nonexistant ... ok Dec 15 13:52:04.147 INFO [stdout] test ops::element::display::content ... ok Dec 15 13:52:04.147 INFO [stdout] test ops::element::display::author ... ok Dec 15 13:52:04.155 INFO [stdout] test ops::element::display::name ... ok Dec 15 13:52:04.155 INFO [stdout] test ops::element::display::image_content ... ok Dec 15 13:52:04.155 INFO [stdout] test ops::element::display::include ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::network_include ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::network_cover ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::date ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::language ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::cover ... ok Dec 15 13:52:04.156 INFO [stdout] test ops::element::display::network_image_content ... ok Dec 15 13:52:04.157 INFO [stdout] test ops::element::name::cover ... ok Dec 15 13:52:04.157 INFO [stdout] test ops::book::normalise_paths::verbose::nonexistant ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::book::normalise_paths::verbose::correct ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::include ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::image_content ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::language ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::date ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::display::string_content ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::author ... ok Dec 15 13:52:04.163 INFO [stdout] test ops::element::name::content ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::name::name ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::name::network_include ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::name::network_cover ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::name::network_image_content ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::parse::elements::cover ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::name::string_content ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::parse::elements::content ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::parse::elements::author ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::parse::elements::date_free ... ok Dec 15 13:52:04.171 INFO [stdout] test ops::element::parse::elements::date_rigid ... ok Dec 15 13:52:04.172 INFO [stdout] test ops::element::parse::elements::name ... ok Dec 15 13:52:04.172 INFO [stdout] test ops::element::parse::elements::language ... ok Dec 15 13:52:04.172 INFO [stdout] test ops::element::parse::elements::include ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::elements::network_include ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::elements::network_image_content ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::elements::network_cover ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::elements::string_content ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::elements::image_content ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::errors::datetime_free ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::not_description ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::unrecognised ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::errors::url ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::errors::datetime_rigid ... ok Dec 15 13:52:04.175 INFO [stdout] test ops::element::parse::roundtrip ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::include_dir::parse::correct::named ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::element::parse::trimming ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::include_dir::parse::correct::unnamed ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::named ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::include_dir::display::named ... ok Dec 15 13:52:04.179 INFO [stdout] test ops::find_title::non_containing ... ok Dec 15 13:52:04.181 INFO [stdout] test ops::find_title::containing ... ok Dec 15 13:52:04.181 INFO [stdout] test ops::include_dir::display::unnamed ... ok Dec 15 13:52:04.185 INFO [stdout] test util::uppercase_first::multi ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::empty ... ok Dec 15 13:52:04.186 INFO [stdout] test ops::parse_descriptor::bubbling ... ok Dec 15 13:52:04.186 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::unnamed ... ok Dec 15 13:52:04.186 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::unnamed ... ok Dec 15 13:52:04.186 INFO [stdout] test ops::parse_descriptor::generic ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::already_upper ... ok Dec 15 13:52:04.186 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::named ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::single_already_upper ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::simple ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::multi_already_upper ... ok Dec 15 13:52:04.186 INFO [stdout] test util::uppercase_first::single ... ok Dec 15 13:52:04.186 INFO [stdout] Dec 15 13:52:04.186 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 13:52:04.186 INFO [stdout] Dec 15 13:52:04.186 INFO [stderr] Doc-tests gen-epub-book Dec 15 13:52:19.532 INFO [stdout] Dec 15 13:52:19.532 INFO [stdout] running 25 tests Dec 15 13:53:19.543 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::from_elements (line 64) ... test src/ops/book.rs - ops::book::EPubBook::from_elements (line 64) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/ops/element.rs - ops::element::BookElement (line 16) ... test src/ops/element.rs - ops::element::BookElement (line 16) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/error.rs - error::Error::exit_value (line 114) ... test src/error.rs - error::Error::exit_value (line 114) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip (line 253) ... test src/ops/book.rs - ops::book::EPubBook::write_zip (line 253) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::normalise_paths (line 191) ... test src/ops/book.rs - ops::book::EPubBook::normalise_paths (line 191) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::name (line 227) ... test src/ops/element.rs - ops::element::BookElement::name (line 227) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip_ext (line 311) ... test src/ops/book.rs - ops::book::EPubBook::write_zip_ext (line 311) has been running for over 60 seconds Dec 15 13:53:19.543 INFO [stdout] test src/error.rs - error::Error::print_error (line 67) ... test src/error.rs - error::Error::print_error (line 67) has been running for over 60 seconds Dec 15 13:54:12.735 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::from_elements (line 64) ... ok Dec 15 13:54:13.241 INFO [stdout] test src/ops/element.rs - ops::element::BookElement (line 16) ... ok Dec 15 13:54:16.503 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::name (line 227) ... ok Dec 15 13:54:17.982 INFO [stdout] test src/error.rs - error::Error::print_error (line 67) ... ok Dec 15 13:54:23.235 INFO [stdout] test src/error.rs - error::Error::exit_value (line 114) ... ok Dec 15 13:54:23.987 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::normalise_paths (line 191) ... ok Dec 15 13:54:30.267 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip_ext (line 311) ... ok Dec 15 13:54:40.487 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip (line 253) ... ok Dec 15 13:55:12.743 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 150) ... test src/ops/element.rs - ops::element::BookElement::parse (line 150) has been running for over 60 seconds Dec 15 13:55:13.243 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 160) ... test src/ops/element.rs - ops::element::BookElement::parse (line 160) has been running for over 60 seconds Dec 15 13:55:16.499 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 171) ... test src/ops/element.rs - ops::element::BookElement::parse (line 171) has been running for over 60 seconds Dec 15 13:55:17.979 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::directory_name (line 38) ... test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::directory_name (line 38) has been running for over 60 seconds Dec 15 13:55:23.239 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_id (line 99) ... test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_id (line 99) has been running for over 60 seconds Dec 15 13:55:23.999 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_name (line 68) ... test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_name (line 68) has been running for over 60 seconds Dec 15 13:55:30.272 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::resolve (line 127) ... test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::resolve (line 127) has been running for over 60 seconds Dec 15 13:55:40.486 INFO [stdout] test src/ops/mod.rs - ops::find_file (line 138) ... test src/ops/mod.rs - ops::find_file (line 138) has been running for over 60 seconds Dec 15 13:55:54.439 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_id (line 99) ... ok Dec 15 13:55:56.083 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 160) ... ok Dec 15 13:55:56.112 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 150) ... ok Dec 15 13:56:07.903 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::directory_name (line 38) ... ok Dec 15 13:56:11.023 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::packed_name (line 68) ... ok Dec 15 13:56:12.667 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::parse (line 171) ... ok Dec 15 13:56:15.324 INFO [stdout] test src/ops/mod.rs - ops::find_file (line 138) ... ok Dec 15 13:56:26.383 INFO [stdout] test src/ops/include_dir.rs - ops::include_dir::IncludeDirectory::resolve (line 127) ... ok Dec 15 13:56:54.444 INFO [stdout] test src/ops/mod.rs - ops::find_title (line 92) ... test src/ops/mod.rs - ops::find_title (line 92) has been running for over 60 seconds Dec 15 13:56:56.087 INFO [stdout] test src/ops/mod.rs - ops::parse_descriptor (line 36) ... test src/ops/mod.rs - ops::parse_descriptor (line 36) has been running for over 60 seconds Dec 15 13:56:56.119 INFO [stdout] test src/options.rs - options (line 7) ... test src/options.rs - options (line 7) has been running for over 60 seconds Dec 15 13:57:05.227 INFO [stdout] test src/options.rs - options (line 7) ... ok Dec 15 13:57:07.900 INFO [stdout] test src/util.rs - util::book_filename (line 65) ... test src/util.rs - util::book_filename (line 65) has been running for over 60 seconds Dec 15 13:57:10.031 INFO [stdout] test src/util.rs - util::uppercase_first (line 35) ... ok Dec 15 13:57:10.205 INFO [stdout] test src/ops/mod.rs - ops::find_title (line 92) ... ok Dec 15 13:57:11.027 INFO [stdout] test src/ops/mod.rs - ops::parse_descriptor (line 36) ... ok Dec 15 13:57:11.027 INFO [stdout] test src/util.rs - util::download_to (line 140) ... test src/util.rs - util::download_to (line 140) has been running for over 60 seconds Dec 15 13:57:11.666 INFO [stdout] test src/util.rs - util::book_filename (line 65) ... ok Dec 15 13:57:14.778 INFO [stdout] test src/util.rs - util::download_to (line 140) ... FAILED Dec 15 13:57:15.322 INFO [stdout] test src/util.rs - util::xhtml_path_id (line 53) ... ok Dec 15 13:57:15.327 INFO [stdout] test src/util.rs - util::write_string_content (line 104) ... test src/util.rs - util::write_string_content (line 104) has been running for over 60 seconds Dec 15 13:57:15.392 INFO [stdout] test src/util.rs - util::write_string_content (line 104) ... ok Dec 15 13:57:26.329 INFO [stdout] test src/util.rs - util::xhtml_url_id (line 85) ... ok Dec 15 13:57:26.330 INFO [stdout] Dec 15 13:57:26.330 INFO [stdout] failures: Dec 15 13:57:26.330 INFO [stdout] Dec 15 13:57:26.330 INFO [stdout] ---- src/util.rs - util::download_to (line 140) stdout ---- Dec 15 13:57:26.330 INFO [stdout] thread 'src/util.rs - util::download_to (line 140)' panicked at 'test executable failed: Dec 15 13:57:26.330 INFO [stdout] Dec 15 13:57:26.330 INFO [stdout] thread 'main' panicked at 'assertion failed: `(left == right)` Dec 15 13:57:26.330 INFO [stdout] left: `Err(Io { desc: "network content", op: "request", more: None })`, Dec 15 13:57:26.330 INFO [stdout] right: `Ok(())`', src/util.rs:8:1 Dec 15 13:57:26.330 INFO [stdout] stack backtrace: Dec 15 13:57:26.330 INFO [stdout] 0: 0x55da011fd413 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 13:57:26.330 INFO [stdout] 1: 0x55da011f72c8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 13:57:26.330 INFO [stdout] 2: 0x55da011fba62 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 13:57:26.330 INFO [stdout] 3: 0x55da011fb7d4 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:224 Dec 15 13:57:26.330 INFO [stdout] 4: 0x55da011fc170 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:487 Dec 15 13:57:26.330 INFO [stdout] 5: 0x55da011fbcf1 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 13:57:26.330 INFO [stdout] 6: 0x55da011fbc3e - std::panicking::begin_panic_fmt::h33c12d7eb9e26fea Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:349 Dec 15 13:57:26.330 INFO [stdout] 7: 0x55da00cd80e3 - rust_out::main::h19287f9e3b324627 Dec 15 13:57:26.330 INFO [stdout] 8: 0x55da00cd7e32 - std::rt::lang_start::{{closure}}::h5e0dad62fb26e8ce Dec 15 13:57:26.330 INFO [stdout] 9: 0x55da011fbb72 - std::panicking::try::do_call::hd22385b9fba3aa8f Dec 15 13:57:26.330 INFO [stdout] at src/libstd/rt.rs:59 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:306 Dec 15 13:57:26.330 INFO [stdout] 10: 0x55da0120b599 - __rust_maybe_catch_panic Dec 15 13:57:26.330 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 13:57:26.330 INFO [stdout] 11: 0x55da011fc603 - std::rt::lang_start_internal::h884c76ddf53aa8b4 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:285 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panic.rs:398 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/rt.rs:58 Dec 15 13:57:26.330 INFO [stdout] 12: 0x55da00cd7e14 - std::rt::lang_start::h468f714fb643dc8b Dec 15 13:57:26.330 INFO [stdout] 13: 0x55da00cd833a - main Dec 15 13:57:26.330 INFO [stdout] 14: 0x7f5488cd182f - __libc_start_main Dec 15 13:57:26.330 INFO [stdout] 15: 0x55da00cd6598 - _start Dec 15 13:57:26.330 INFO [stdout] 16: 0x0 - Dec 15 13:57:26.330 INFO [stdout] Dec 15 13:57:26.330 INFO [stdout] ', src/librustdoc/test.rs:361:17 Dec 15 13:57:26.330 INFO [stdout] stack backtrace: Dec 15 13:57:26.330 INFO [stdout] 0: 0x7fc8f356cf43 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 13:57:26.330 INFO [stdout] 1: 0x7fc8f3563fa8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 13:57:26.330 INFO [stdout] 2: 0x7fc8f3569222 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 13:57:26.330 INFO [stdout] 3: 0x7fc8f3568f17 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:218 Dec 15 13:57:26.330 INFO [stdout] 4: 0x7fc8f5773681 - rustc::util::common::panic_hook::h5f1ef819f65e49ce Dec 15 13:57:26.330 INFO [stdout] 5: 0x7fc8f3569a09 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:491 Dec 15 13:57:26.330 INFO [stdout] 6: 0x7fc8f35694b1 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 13:57:26.330 INFO [stdout] 7: 0x7fc8f35693fe - std::panicking::begin_panic_fmt::h33c12d7eb9e26fea Dec 15 13:57:26.330 INFO [stdout] at src/libstd/panicking.rs:349 Dec 15 13:57:26.330 INFO [stdout] 8: 0x55e447ba5dd0 - ::add_test::{{closure}}::{{closure}}::{{closure}}::h03844441a87ed622 Dec 15 13:57:26.330 INFO [stdout] at src/librustdoc/test.rs:361 Dec 15 13:57:26.330 INFO [stdout] at src/librustdoc/test.rs:653 Dec 15 13:57:26.330 INFO [stdout] 9: 0x55e447b62398 - >::set::ha4da892a47d9254a Dec 15 13:57:26.330 INFO [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libsyntax/lib.rs:123 Dec 15 13:57:26.330 INFO [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155 Dec 15 13:57:26.330 INFO [stdout] 10: 0x55e447a9a53f - syntax::with_globals::hd5f80a8e81630563 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libsyntax/lib.rs:122 Dec 15 13:57:26.330 INFO [stdout] 11: 0x55e447a2fd24 - std::panicking::try::do_call::haa456735219e3377 Dec 15 13:57:26.330 INFO [stdout] at src/librustdoc/test.rs:650 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 13:57:26.330 INFO [stdout] 12: 0x7fc8f3586649 - __rust_maybe_catch_panic Dec 15 13:57:26.330 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 13:57:26.330 INFO [stdout] 13: 0x55e447a84e49 - rustc_driver::in_named_rustc_thread::he2bcf78be8ab5538 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/librustc_driver/lib.rs:1556 Dec 15 13:57:26.330 INFO [stdout] 14: 0x55e447b7b140 - >::call_box::h418f2961326e63a6 Dec 15 13:57:26.330 INFO [stdout] at src/librustdoc/test.rs:650 Dec 15 13:57:26.330 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 13:57:26.331 INFO [stdout] 15: 0x7fc8f4bd5102 - >::call_box::hb274b5baa0755876 Dec 15 13:57:26.331 INFO [stdout] at src/libtest/lib.rs:1476 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 13:57:26.331 INFO [stdout] 16: 0x7fc8f3586649 - __rust_maybe_catch_panic Dec 15 13:57:26.331 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 13:57:26.331 INFO [stdout] 17: 0x7fc8f4bf3427 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 13:57:26.331 INFO [stdout] at src/libtest/lib.rs:1438 Dec 15 13:57:26.331 INFO [stdout] 18: 0x7fc8f4bcd334 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 15 13:57:26.331 INFO [stdout] 19: 0x7fc8f4bcd9a4 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 13:57:26.331 INFO [stdout] 20: 0x7fc8f3586649 - __rust_maybe_catch_panic Dec 15 13:57:26.331 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 13:57:26.331 INFO [stdout] 21: 0x7fc8f4bd4e4c - >::call_box::h260fd1ad8e2854a6 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 13:57:26.331 INFO [stdout] 22: 0x7fc8f3579aed - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 15 13:57:26.331 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 15 13:57:26.331 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 15 13:57:26.331 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 15 13:57:26.331 INFO [stdout] 23: 0x7fc8f32ed6b9 - start_thread Dec 15 13:57:26.331 INFO [stdout] 24: 0x7fc8f2c0541c - clone Dec 15 13:57:26.331 INFO [stdout] 25: 0x0 - Dec 15 13:57:26.331 INFO [stdout] query stack during panic: Dec 15 13:57:26.331 INFO [stdout] end of query stack Dec 15 13:57:26.331 INFO [stdout] Dec 15 13:57:26.331 INFO [stdout] Dec 15 13:57:26.331 INFO [stdout] failures: Dec 15 13:57:26.331 INFO [stdout] src/util.rs - util::download_to (line 140) Dec 15 13:57:26.331 INFO [stdout] Dec 15 13:57:26.331 INFO [stdout] test result: FAILED. 24 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 13:57:26.331 INFO [stdout] Dec 15 13:57:26.371 INFO [stderr] error: test failed, to rerun pass '--doc' Dec 15 13:57:26.374 INFO [stderr] su: No module specific data is present Dec 15 13:57:26.691 INFO running `"docker" "inspect" "27b478d7b93e000cf7b6b749523fdb3b1bfb272fc94410673a7a05ca8aac9efa"` Dec 15 13:57:26.886 INFO running `"docker" "rm" "-f" "27b478d7b93e000cf7b6b749523fdb3b1bfb272fc94410673a7a05ca8aac9efa"` Dec 15 13:57:27.127 INFO [stdout] 27b478d7b93e000cf7b6b749523fdb3b1bfb272fc94410673a7a05ca8aac9efa