Nov 18 13:30:33.352 INFO testing gen-epub-book-2.3.1 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 13:30:33.352 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:33.661 INFO [stdout] 8753cd8a481c6ad3bbe0a9e106d512a5d7cea17ddaf4250b310f331a7a0bc677
Nov 18 13:30:33.662 INFO running `"docker" "start" "-a" "8753cd8a481c6ad3bbe0a9e106d512a5d7cea17ddaf4250b310f331a7a0bc677"`
Nov 18 13:30:34.295 INFO [stderr] usermod: no changes
Nov 18 13:30:34.695 INFO [stderr]    Compiling gen-epub-book v2.3.1 (/source)
Nov 18 13:30:56.857 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.55s
Nov 18 13:30:56.873 INFO [stderr] su: No module specific data is present
Nov 18 13:30:57.360 INFO running `"docker" "inspect" "8753cd8a481c6ad3bbe0a9e106d512a5d7cea17ddaf4250b310f331a7a0bc677"`
Nov 18 13:30:57.555 INFO running `"docker" "rm" "-f" "8753cd8a481c6ad3bbe0a9e106d512a5d7cea17ddaf4250b310f331a7a0bc677"`
Nov 18 13:30:57.873 INFO [stdout] 8753cd8a481c6ad3bbe0a9e106d512a5d7cea17ddaf4250b310f331a7a0bc677
Nov 18 13:30:57.877 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:58.209 INFO [stdout] 38197938fee5f3e49b3d08437262880067f40c58cec12fd90c570a44207f4068
Nov 18 13:30:58.209 INFO running `"docker" "start" "-a" "38197938fee5f3e49b3d08437262880067f40c58cec12fd90c570a44207f4068"`
Nov 18 13:30:58.857 INFO [stderr] usermod: no changes
Nov 18 13:30:59.163 INFO [stderr]    Compiling gen-epub-book v2.3.1 (/source)
Nov 18 13:31:13.702 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.82s
Nov 18 13:31:13.723 INFO [stderr] su: No module specific data is present
Nov 18 13:31:14.190 INFO running `"docker" "inspect" "38197938fee5f3e49b3d08437262880067f40c58cec12fd90c570a44207f4068"`
Nov 18 13:31:14.345 INFO running `"docker" "rm" "-f" "38197938fee5f3e49b3d08437262880067f40c58cec12fd90c570a44207f4068"`
Nov 18 13:31:14.650 INFO [stdout] 38197938fee5f3e49b3d08437262880067f40c58cec12fd90c570a44207f4068
Nov 18 13:31:14.652 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:14.887 INFO [stdout] ce9fb99259085dc377aba984ca28cbb1c524bbad53422ae0bf201063b30190b3
Nov 18 13:31:14.891 INFO running `"docker" "start" "-a" "ce9fb99259085dc377aba984ca28cbb1c524bbad53422ae0bf201063b30190b3"`
Nov 18 13:31:15.434 INFO [stderr] usermod: no changes
Nov 18 13:31:15.697 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Nov 18 13:31:15.717 INFO [stderr]      Running /target/debug/deps/gen_epub_book-38e2bd2e0036a321
Nov 18 13:31:15.731 INFO [stdout] 
Nov 18 13:31:15.731 INFO [stdout] running 0 tests
Nov 18 13:31:15.731 INFO [stdout] 
Nov 18 13:31:15.731 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 13:31:15.731 INFO [stdout] 
Nov 18 13:31:15.732 INFO [stderr]      Running /target/debug/deps/lib-2dbc4a773dfcd16b
Nov 18 13:31:15.739 INFO [stdout] 
Nov 18 13:31:15.739 INFO [stdout] running 108 tests
Nov 18 13:31:15.739 INFO [stdout] test error::exit_value::file_not_found ... ok
Nov 18 13:31:15.739 INFO [stdout] test error::exit_value::parse ... ok
Nov 18 13:31:15.739 INFO [stdout] test error::exit_value::wrong_element_amount ... ok
Nov 18 13:31:15.740 INFO [stdout] test error::print::file_not_found ... ok
Nov 18 13:31:15.740 INFO [stdout] test error::exit_value::wrong_file_state ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::exit_value::required_element_missing ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::exit_value::io ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::more::normal_non_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::more::single_non_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::more::single_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::more::normal_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::more::empty ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::no_more::normal_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::no_more::normal_non_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::no_more::single_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::no_more::single_non_e ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::parse::more ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::parse::no_more ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::required_element_missing ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::wrong_element_amount ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::wrong_file_state ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::correct::file_cover ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::correct::network_cover ... ok
Nov 18 13:31:15.747 INFO [stdout] test error::print::io::no_more::empty ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::correct::no_cover ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_file_first ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::cover::mixed_network_first ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_file ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::cover::two_network ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::author ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::date ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::name ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::ordering ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::missing::author ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::missing::date ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::duplicate::language ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::missing::language ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::missing::name ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::from_elements::incorrect::missing::ordering ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::normalise_paths::no_verbose::bad_type ... ok
Nov 18 13:31:15.747 INFO [stdout] test ops::book::normalise_paths::no_verbose::correct ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::book::normalise_paths::no_verbose::nonexistant ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::book::normalise_paths::verbose::bad_type ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::book::normalise_paths::verbose::correct ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::book::normalise_paths::verbose::nonexistant ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::author ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::cover ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::date ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::image_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::include ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::language ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::name ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::network_cover ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::network_image_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::network_include ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::display::string_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::author ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::cover ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::date ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::image_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::include ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::language ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::name ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::network_cover ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::network_image_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::network_include ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::name::string_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::author ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::cover ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::date_free ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::date_rigid ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::image_content ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::include ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::language ... ok
Nov 18 13:31:15.754 INFO [stdout] test ops::element::parse::elements::name ... ok
Nov 18 13:31:15.760 INFO [stdout] test ops::element::parse::elements::network_cover ... ok
Nov 18 13:31:15.760 INFO [stdout] test ops::element::parse::elements::network_image_content ... ok
Nov 18 13:31:15.760 INFO [stdout] test ops::element::parse::elements::network_include ... ok
Nov 18 13:31:15.760 INFO [stdout] test ops::element::parse::elements::string_content ... ok
Nov 18 13:31:15.761 INFO [stdout] test ops::element::parse::errors::datetime_free ... ok
Nov 18 13:31:15.761 INFO [stdout] test ops::element::parse::trimming ... ok
Nov 18 13:31:15.761 INFO [stdout] test ops::element::parse::errors::datetime_rigid ... ok
Nov 18 13:31:15.761 INFO [stdout] test ops::element::parse::errors::url ... ok
Nov 18 13:31:15.761 INFO [stdout] test ops::element::parse::not_description ... ok
Nov 18 13:31:15.763 INFO [stdout] test ops::element::parse::unrecognised ... ok
Nov 18 13:31:15.763 INFO [stdout] test ops::element::parse::roundtrip ... ok
Nov 18 13:31:15.763 INFO [stdout] test ops::find_title::containing ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::display::unnamed ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::display::named ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::parse::correct::unnamed ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::named ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::parse::incorrect::invalid::unnamed ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::find_title::non_containing ... ok
Nov 18 13:31:15.787 INFO [stdout] test ops::include_dir::parse::correct::named ... ok
Nov 18 13:31:15.795 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::unnamed ... ok
Nov 18 13:31:15.795 INFO [stdout] test ops::parse_descriptor::generic ... ok
Nov 18 13:31:15.795 INFO [stdout] test util::uppercase_first::empty ... ok
Nov 18 13:31:15.795 INFO [stdout] test util::uppercase_first::multi ... ok
Nov 18 13:31:15.795 INFO [stdout] test ops::parse_descriptor::bubbling ... ok
Nov 18 13:31:15.795 INFO [stdout] test util::uppercase_first::already_upper ... ok
Nov 18 13:31:15.795 INFO [stdout] test util::uppercase_first::multi_already_upper ... ok
Nov 18 13:31:15.795 INFO [stdout] test ops::include_dir::parse::incorrect::nonexistant::named ... ok
Nov 18 13:31:15.807 INFO [stdout] test util::uppercase_first::simple ... ok
Nov 18 13:31:15.807 INFO [stdout] test util::uppercase_first::single_already_upper ... ok
Nov 18 13:31:15.807 INFO [stdout] test util::uppercase_first::single ... ok
Nov 18 13:31:15.807 INFO [stdout] 
Nov 18 13:31:15.807 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 13:31:15.807 INFO [stdout] 
Nov 18 13:31:15.808 INFO [stderr]    Doc-tests gen-epub-book
Nov 18 13:31:28.806 INFO [stdout] 
Nov 18 13:31:28.807 INFO [stdout] running 25 tests
Nov 18 13:32:28.815 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:28.815 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:28.816 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:28.816 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:28.816 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:28.816 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:28.817 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:28.817 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:33:03.855 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip_ext (line 311) ... FAILED
Nov 18 13:33:03.864 INFO [stdout] test src/ops/book.rs - ops::book::EPubBook::write_zip (line 253) ... FAILED
Nov 18 13:33:05.815 INFO [stdout] test src/error.rs - error::Error::exit_value (line 114) ... FAILED
Nov 18 13:33:08.255 INFO [stderr] su: No module specific data is present
Nov 18 13:33:08.927 INFO running `"docker" "inspect" "ce9fb99259085dc377aba984ca28cbb1c524bbad53422ae0bf201063b30190b3"`
Nov 18 13:33:09.201 INFO running `"docker" "rm" "-f" "ce9fb99259085dc377aba984ca28cbb1c524bbad53422ae0bf201063b30190b3"`
Nov 18 13:33:09.559 INFO [stdout] ce9fb99259085dc377aba984ca28cbb1c524bbad53422ae0bf201063b30190b3