Nov 18 13:30:32.083 INFO testing gen-epub-book-2.3.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 13:30:32.085 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 13:30:32.295 INFO [stdout] 61afcd4410462695e16e50e0e16a9a1a3f285b7b878db30eed38de8c2d5611d7
Nov 18 13:30:32.297 INFO running `"docker" "start" "-a" "61afcd4410462695e16e50e0e16a9a1a3f285b7b878db30eed38de8c2d5611d7"`
Nov 18 13:30:32.815 INFO [stderr] usermod: no changes
Nov 18 13:30:33.133 INFO [stderr]    Compiling gen-epub-book v2.3.1 (/source)
Nov 18 13:30:55.061 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.22s
Nov 18 13:30:55.077 INFO [stderr] su: No module specific data is present
Nov 18 13:30:55.387 INFO running `"docker" "inspect" "61afcd4410462695e16e50e0e16a9a1a3f285b7b878db30eed38de8c2d5611d7"`
Nov 18 13:30:55.486 INFO running `"docker" "rm" "-f" "61afcd4410462695e16e50e0e16a9a1a3f285b7b878db30eed38de8c2d5611d7"`
Nov 18 13:30:55.613 INFO [stdout] 61afcd4410462695e16e50e0e16a9a1a3f285b7b878db30eed38de8c2d5611d7
Nov 18 13:30:55.620 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 13:30:55.840 INFO [stdout] 8c67d2319ae27b5b437f3282184056a4940f4c208d784fcab240a7ac53bdbcf9
Nov 18 13:30:55.841 INFO running `"docker" "start" "-a" "8c67d2319ae27b5b437f3282184056a4940f4c208d784fcab240a7ac53bdbcf9"`
Nov 18 13:30:56.345 INFO [stderr] usermod: no changes
Nov 18 13:30:56.533 INFO [stderr]    Compiling gen-epub-book v2.3.1 (/source)
Nov 18 13:31:09.896 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.53s
Nov 18 13:31:09.927 INFO [stderr] su: No module specific data is present
Nov 18 13:31:10.380 INFO running `"docker" "inspect" "8c67d2319ae27b5b437f3282184056a4940f4c208d784fcab240a7ac53bdbcf9"`
Nov 18 13:31:10.509 INFO running `"docker" "rm" "-f" "8c67d2319ae27b5b437f3282184056a4940f4c208d784fcab240a7ac53bdbcf9"`
Nov 18 13:31:10.618 INFO [stdout] 8c67d2319ae27b5b437f3282184056a4940f4c208d784fcab240a7ac53bdbcf9
Nov 18 13:31:10.621 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 13:31:10.897 INFO [stdout] 3ae6f32b2aaee70740175f5818da6ea1c9f1cfec8140344872872deb4ee7f896
Nov 18 13:31:10.897 INFO running `"docker" "start" "-a" "3ae6f32b2aaee70740175f5818da6ea1c9f1cfec8140344872872deb4ee7f896"`
Nov 18 13:31:11.348 INFO [stderr] usermod: no changes
Nov 18 13:31:11.543 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Nov 18 13:31:11.551 INFO [stderr]      Running /target/debug/deps/gen_epub_book-368bc77cf6d5714b
Nov 18 13:31:11.552 INFO [stdout] 
Nov 18 13:31:11.552 INFO [stdout] running 0 tests
Nov 18 13:31:11.552 INFO [stdout] 
Nov 18 13:31:11.552 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 13:31:11.552 INFO [stdout] 
Nov 18 13:31:11.559 INFO [stderr]      Running /target/debug/deps/lib-10cb4a83eecc2b63
Nov 18 13:31:11.563 INFO [stdout] 
Nov 18 13:31:11.563 INFO [stdout] running 108 tests
Nov 18 13:31:11.575 INFO [stdout] test error::exit_value::parse ... ok
Nov 18 13:31:11.575 INFO [stdout] test error::exit_value::wrong_element_amount ... ok
Nov 18 13:31:11.575 INFO [stdout] test error::exit_value::required_element_missing ... ok
Nov 18 13:31:11.575 INFO [stdout] test error::exit_value::io ... ok
Nov 18 13:31:11.586 INFO [stdout] test error::exit_value::file_not_found ... ok
Nov 18 13:31:11.586 INFO [stdout] test error::exit_value::wrong_file_state ... ok
Nov 18 13:31:11.586 INFO [stdout] test error::print::io::more::normal_e ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::io::more::empty ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::file_not_found ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::io::more::normal_non_e ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::io::more::single_e ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::parse::more ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::parse::no_more ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::required_element_missing ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::wrong_element_amount ... ok
Nov 18 13:31:11.587 INFO [stdout] test error::print::wrong_file_state ... ok
Nov 18 13:31:11.587 INFO [stdout] test ops::book::from_elements::correct::file_cover ... ok
Nov 18 13:31:11.587 INFO [stdout] test ops::book::from_elements::correct::network_cover ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::no_more::empty ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::more::single_non_e ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::no_more::single_e ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::no_more::single_non_e ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::no_more::normal_e ... ok
Nov 18 13:31:11.599 INFO [stdout] test error::print::io::no_more::normal_non_e ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::correct::no_cover ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_file_first ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_network_first ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::language ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::date ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::author ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_network ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_file ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::missing::date ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::missing::language ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::missing::name ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::missing::ordering ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::no_verbose::bad_type ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::no_verbose::correct ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::no_verbose::nonexistant ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::missing::author ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::name ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::ordering ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::verbose::nonexistant ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::verbose::bad_type ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::book::normalise_paths::verbose::correct ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::date ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::author ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::content ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::include ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::language ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::network_cover ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::name ... ok
Nov 18 13:31:11.599 INFO [stdout] test ops::element::display::network_image_content ... ok
Nov 18 13:31:11.607 INFO [stdout] test ops::element::display::image_content ... ok
Nov 18 13:31:11.607 INFO [stdout] test ops::element::display::cover ... ok
Nov 18 13:31:11.611 INFO [stdout] test ops::element::display::network_include ... ok
Nov 18 13:31:11.611 INFO [stdout] test ops::element::name::image_content ... ok
Nov 18 13:31:11.611 INFO [stdout] test ops::element::name::include ... ok
Nov 18 13:31:11.611 INFO [stdout] test ops::element::display::string_content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::language ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::author ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::cover ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::date ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::name ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::network_image_content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::network_cover ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::string_content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::author ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::include ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::image_content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::language ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::date_free ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::name::network_include ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::date_rigid ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::name ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::string_content ... ok
Nov 18 13:31:11.615 INFO [stdout] test ops::element::parse::elements::network_include ... ok
Nov 18 13:31:11.623 INFO [stdout] test ops::element::parse::elements::network_cover ... ok
Nov 18 13:31:11.623 INFO [stdout] test ops::element::parse::errors::datetime_free ... ok
Nov 18 13:31:11.623 INFO [stdout] test ops::element::parse::elements::cover ... ok
Nov 18 13:31:11.624 INFO [stdout] test ops::element::parse::not_description ... ok
Nov 18 13:31:11.624 INFO [stdout] test ops::element::parse::elements::network_image_content ... ok
Nov 18 13:31:11.624 INFO [stdout] test ops::element::parse::errors::url ... ok
Nov 18 13:31:11.626 INFO [stdout] test ops::element::parse::errors::datetime_rigid ... ok
Nov 18 13:31:11.626 INFO [stdout] test ops::element::parse::unrecognised ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::display::named ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::display::unnamed ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::element::parse::roundtrip ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::correct::named ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::element::parse::trimming ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::unnamed ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::unnamed ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::find_title::containing ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::named ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::named ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::already_upper ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::multi ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::find_title::non_containing ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::empty ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::parse_descriptor::generic ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::parse_descriptor::bubbling ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::single ... ok
Nov 18 13:31:11.627 INFO [stdout] test ops::include_dir::parse::correct::unnamed ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::single_already_upper ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::multi_already_upper ... ok
Nov 18 13:31:11.627 INFO [stdout] test util::uppercase_first::simple ... ok
Nov 18 13:31:11.627 INFO [stdout] 
Nov 18 13:31:11.627 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 13:31:11.627 INFO [stdout] 
Nov 18 13:31:11.642 INFO [stderr]    Doc-tests gen-epub-book
Nov 18 13:31:23.791 INFO [stdout] 
Nov 18 13:31:23.791 INFO [stdout] running 25 tests
Nov 18 13:32:23.799 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
Nov 18 13:32:23.799 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
Nov 18 13:32:23.799 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
Nov 18 13:32:23.800 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
Nov 18 13:32:23.800 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
Nov 18 13:32:23.800 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
Nov 18 13:32:23.800 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
Nov 18 13:32:23.801 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
Nov 18 13:32:57.796 INFO [stdout] test src/ops/element.rs - ops::element::BookElement::name (line 227) ... FAILED
Nov 18 13:32:58.000 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::normalise_paths (line 191) ... FAILED
Nov 18 13:32:58.001 INFO [stdout] test src/error.rs - error::Error::print_error (line 67) ... FAILED
Nov 18 13:33:00.275 INFO [stderr] su: No module specific data is present
Nov 18 13:33:01.365 INFO running `"docker" "inspect" "3ae6f32b2aaee70740175f5818da6ea1c9f1cfec8140344872872deb4ee7f896"`
Nov 18 13:33:01.691 INFO running `"docker" "rm" "-f" "3ae6f32b2aaee70740175f5818da6ea1c9f1cfec8140344872872deb4ee7f896"`
Nov 18 13:33:02.123 INFO [stdout] 3ae6f32b2aaee70740175f5818da6ea1c9f1cfec8140344872872deb4ee7f896