[INFO] cloning repository https://github.com/sschober/mato
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/sschober/mato" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsschober%2Fmato", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsschober%2Fmato'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] e6e04382e6ab41c6f6cfdd28328cfde3df5da98c
[INFO] testing sschober/mato against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsschober%2Fmato" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-3-tc2/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/sschober/mato
[INFO] finished tweaking git repo https://github.com/sschober/mato
[INFO] tweaked toml for git repo https://github.com/sschober/mato written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/sschober/mato on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/sschober/mato already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f082ed508f18d7646611b9bcbeffc63e7b1230a51e06d341bc8af299d6635ac3
[INFO] running `Command { std: "docker" "start" "-a" "f082ed508f18d7646611b9bcbeffc63e7b1230a51e06d341bc8af299d6635ac3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f082ed508f18d7646611b9bcbeffc63e7b1230a51e06d341bc8af299d6635ac3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f082ed508f18d7646611b9bcbeffc63e7b1230a51e06d341bc8af299d6635ac3", kill_on_drop: false }`
[INFO] [stdout] f082ed508f18d7646611b9bcbeffc63e7b1230a51e06d341bc8af299d6635ac3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a205408056bb824da2114200f5cad923b7b5da047f99c902a3a71911a8ffd8c5
[INFO] running `Command { std: "docker" "start" "-a" "a205408056bb824da2114200f5cad923b7b5da047f99c902a3a71911a8ffd8c5", kill_on_drop: false }`
[INFO] [stderr]    Compiling mato v0.2.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.58s
[INFO] running `Command { std: "docker" "inspect" "a205408056bb824da2114200f5cad923b7b5da047f99c902a3a71911a8ffd8c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a205408056bb824da2114200f5cad923b7b5da047f99c902a3a71911a8ffd8c5", kill_on_drop: false }`
[INFO] [stdout] a205408056bb824da2114200f5cad923b7b5da047f99c902a3a71911a8ffd8c5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0549dd1122d89af5df19d181de9ee763be340e582df0496764f730f399b5ed3f
[INFO] running `Command { std: "docker" "start" "-a" "0549dd1122d89af5df19d181de9ee763be340e582df0496764f730f399b5ed3f", kill_on_drop: false }`
[INFO] [stderr]    Compiling mato v0.2.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.51s
[INFO] running `Command { std: "docker" "inspect" "0549dd1122d89af5df19d181de9ee763be340e582df0496764f730f399b5ed3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0549dd1122d89af5df19d181de9ee763be340e582df0496764f730f399b5ed3f", kill_on_drop: false }`
[INFO] [stdout] 0549dd1122d89af5df19d181de9ee763be340e582df0496764f730f399b5ed3f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ec015f3cf1ed1007cc847c978df88a947f0e61252698e9f6728d3401d417019f
[INFO] running `Command { std: "docker" "start" "-a" "ec015f3cf1ed1007cc847c978df88a947f0e61252698e9f6728d3401d417019f", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mato-731d5085daf2e853)
[INFO] [stdout] 
[INFO] [stdout] running 114 tests
[INFO] [stdout] test config::tests::empty_args ... ok
[INFO] [stdout] test opts::tests::parse_short_opt_cluster ... ok
[INFO] [stdout] test opts::tests::parse_source_file_val_opt ... ok
[INFO] [stdout] test opts::tests::parse_short_opt_cluster_with_val ... ok
[INFO] [stdout] test parser::tests::ampersand ... ok
[INFO] [stdout] test opts::tests::add_opts ... ok
[INFO] [stdout] test opts::tests::parse_version_opt ... ok
[INFO] [stdout] test parser::tests::backslash_without_brace_is_literal ... ok
[INFO] [stdout] test parser::tests::bang_without_bracket_is_literal ... ok
[INFO] [stdout] test config::tests::many_args ... ok
[INFO] [stdout] test parser::tests::bare_brackets_not_link ... ok
[INFO] [stdout] test parser::tests::caret_without_paren_is_literal ... ok
[INFO] [stdout] test parser::tests::chapter_mark ... ok
[INFO] [stdout] test parser::tests::dot ... ok
[INFO] [stdout] test parser::tests::double_newline_produces_paragraph ... ok
[INFO] [stdout] test parser::tests::code_block_plain ... ok
[INFO] [stdout] test parser::tests::code_block_with_type ... ok
[INFO] [stdout] test parser::tests::em_dash_inline ... ok
[INFO] [stdout] test parser::tests::em_dash_not_at_line_start ... ok
[INFO] [stdout] test parser::tests::color_spec ... ok
[INFO] [stdout] test parser::tests::bold ... ok
[INFO] [stdout] test parser::tests::expression ... ok
[INFO] [stdout] test parser::tests::construction ... ok
[INFO] [stdout] test parser::tests::en_dash_inline ... ok
[INFO] [stdout] test parser::tests::heading_level_3 ... ok
[INFO] [stdout] test parser::tests::footnote ... ok
[INFO] [stdout] test parser::tests::heading_level_1 ... ok
[INFO] [stdout] test parser::tests::heading_level_2 ... ok
[INFO] [stdout] test parser::tests::gt_without_paren_is_literal ... ok
[INFO] [stdout] test parser::tests::heading_with_named_anchor ... ok
[INFO] [stdout] test parser::tests::hyperlink ... ok
[INFO] [stdout] test parser::tests::image_basic ... ok
[INFO] [stdout] test parser::tests::image_with_size ... ok
[INFO] [stdout] test parser::tests::internal_link ... ok
[INFO] [stdout] test parser::tests::italic ... ok
[INFO] [stdout] test parser::tests::list_multiline_item ... ok
[INFO] [stdout] test parser::tests::list_single_item_star ... ok
[INFO] [stdout] test parser::tests::list_nested ... ok
[INFO] [stdout] test parser::tests::inline_code ... ok
[INFO] [stdout] test parser::tests::metadata_block ... ok
[INFO] [stdout] test parser::tests::inline_code_dot_prefix ... ok
[INFO] [stdout] test parser::tests::pass_through ... ok
[INFO] [stdout] test parser::tests::right_sidenote ... ok
[INFO] [stdout] test parser::tests::single_newline_is_linebreak ... ok
[INFO] [stdout] test process::canonicalize::tests::bold_wrapping_italic_becomes_bold_italic ... ok
[INFO] [stdout] test parser::tests::single_slash_is_literal ... ok
[INFO] [stdout] test parser::tests::small_caps ... ok
[INFO] [stdout] test parser::tests::wrong_nesting ... ok
[INFO] [stdout] test process::canonicalize::tests::italic_wrapping_bold_becomes_bold_italic ... ok
[INFO] [stdout] test process::canonicalize::tests::plain_italic_stays_italic ... ok
[INFO] [stdout] test process::canonicalize::tests::bold_inside_italic_context_becomes_bold_italic ... ok
[INFO] [stdout] test process::canonicalize::tests::numerals_not_replaced_when_disabled ... ok
[INFO] [stdout] test process::canonicalize::tests::numerals_not_replaced_inside_list_items ... ok
[INFO] [stdout] test parser::tests::list_single_item_dash ... ok
[INFO] [stdout] test parser::tests::list_two_items_star ... ok
[INFO] [stdout] test process::canonicalize::tests::plain_bold_stays_bold ... ok
[INFO] [stdout] test process::canonicalize::tests::prelit_backslash_is_doubled ... ok
[INFO] [stdout] test process::canonicalize::tests::prelit_caret_is_escaped ... ok
[INFO] [stdout] test process::canonicalize::tests::prelit_dot_at_line_start_is_escaped ... ok
[INFO] [stdout] test process::canonicalize::tests::replaces_digits_with_oldstyle ... ok
[INFO] [stdout] test process::canonicalize::tests::small_caps_alphabetic_chars_get_sc_suffix ... ok
[INFO] [stdout] test process::canonicalize::tests::small_caps_non_alphabetic_chars_unchanged ... ok
[INFO] [stdout] test process::image_converter::tests::absolute_path_is_unchanged ... ok
[INFO] [stdout] test process::image_converter::tests::cat_is_walked_recursively ... ok
[INFO] [stdout] test process::image_converter::tests::non_image_nodes_are_passed_through ... ok
[INFO] [stdout] test process::image_converter::tests::relative_path_is_joined_with_source_dir ... ok
[INFO] [stdout] test render::markdown::tests::bold_italic_node ... ok
[INFO] [stdout] test render::markdown::tests::bold_node ... ok
[INFO] [stdout] test render::markdown::tests::bold_starting_with_newline_moves_star_to_newline ... ok
[INFO] [stdout] test render::markdown::tests::cat_concatenates ... ok
[INFO] [stdout] test render::markdown::tests::chapter_mark_node ... ok
[INFO] [stdout] test render::markdown::tests::code_block_plain ... ok
[INFO] [stdout] test render::markdown::tests::code_block_with_type ... ok
[INFO] [stdout] test render::markdown::tests::color_node ... ok
[INFO] [stdout] test render::markdown::tests::document_renders_body ... ok
[INFO] [stdout] test render::markdown::tests::em_dash ... ok
[INFO] [stdout] test render::markdown::tests::en_dash ... ok
[INFO] [stdout] test process::canonicalize::tests::preserves_non_empty_cat ... ok
[INFO] [stdout] test process::canonicalize::tests::removes_empty_from_cat ... ok
[INFO] [stdout] test render::markdown::tests::empty_node ... ok
[INFO] [stdout] test render::markdown::tests::heading_level_1 ... ok
[INFO] [stdout] test render::markdown::tests::heading_level_2 ... ok
[INFO] [stdout] test render::markdown::tests::hyperref_node ... ok
[INFO] [stdout] test render::markdown::tests::image_node ... ok
[INFO] [stdout] test render::markdown::tests::italic_node ... ok
[INFO] [stdout] test render::markdown::tests::inline_code_node ... ok
[INFO] [stdout] test render::markdown::tests::footnote_node ... ok
[INFO] [stdout] test render::markdown::tests::escape_lit_renders_as_is ... ok
[INFO] [stdout] test render::markdown::tests::list_item_level_0 ... ok
[INFO] [stdout] test render::markdown::tests::list_item_level_1_indented ... ok
[INFO] [stdout] test render::markdown::tests::list_renders_inner_content ... ok
[INFO] [stdout] test render::markdown::tests::literal ... ok
[INFO] [stdout] test render::markdown::tests::long_line_is_wrapped ... ok
[INFO] [stdout] test render::markdown::tests::meta_data_block_node ... ok
[INFO] [stdout] test render::markdown::tests::preformatted_literal ... ok
[INFO] [stdout] test render::markdown::tests::paragraph ... ok
[INFO] [stdout] test render::markdown::tests::meta_data_item_node ... ok
[INFO] [stdout] test render::markdown::tests::quote_node ... ok
[INFO] [stdout] test render::markdown::tests::right_sidenote_node ... ok
[INFO] [stdout] test render::markdown::tests::short_line_is_not_wrapped ... ok
[INFO] [stdout] test render::markdown::tests::vspace ... ok
[INFO] [stdout] test render::markdown::tests::small_caps_node ... ok
[INFO] [stdout] test tests::log_level_debug_is_two ... ok
[INFO] [stdout] test tests::log_level_default_is_zero ... ok
[INFO] [stdout] test tests::log_level_trace_is_three ... ok
[INFO] [stdout] test tests::log_level_verbose_is_one ... ok
[INFO] [stdout] test render::markdown::tests::line_break ... ok
[INFO] [stdout] test tests::skip_preamble_returns_empty_string ... ok
[INFO] [stdout] test watch::imp::tests::kqueue_construction ... ok
[INFO] [stdout] test tests::falls_back_to_default_preamble_when_no_file_found ... ok
[INFO] [stdout] test tests::loads_sibling_preamble_when_present ... ok
[INFO] [stdout] test tests::parent_dir_returns_containing_directory ... ok
[INFO] [stdout] test tests::replace_extension_changes_suffix ... ok
[INFO] [stdout] test tests::replace_extension_on_path_with_dirs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 114 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/matochk.rs (/opt/rustwide/target/debug/deps/matochk-a6300adbe15c6f41)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/matoedit.rs (/opt/rustwide/target/debug/deps/matoedit-73a313382d0f38d4)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/matofmt.rs (/opt/rustwide/target/debug/deps/matofmt-0778bb3b4dcfd707)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test tests::literal ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/matogro.rs (/opt/rustwide/target/debug/deps/matogro-12a970377c6f6c54)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/matopdf.rs (/opt/rustwide/target/debug/deps/matopdf-a85d64700ab6090d)
[INFO] [stdout] 
[INFO] [stdout] running 60 tests
[INFO] [stdout] test golden_tests::font_drop_caps ... ok
[INFO] [stdout] test golden_tests::chapters ... ok
[INFO] [stdout] test golden_tests::refactoring_warp_at ... ok
[INFO] [stdout] test golden_tests::font_bold_italics_code ... ok
[INFO] [stdout] test golden_tests::font_old_style_figures ... ok
[INFO] [stdout] test golden_tests::font_small_caps ... ok
[INFO] [stdout] test golden_tests::simple_footnote ... ok
[INFO] [stdout] test golden_tests::simple_codeblock ... ok
[INFO] [stdout] test golden_tests::simple_heading ... ok
[INFO] [stdout] test golden_tests::simple_list ... ok
[INFO] [stdout] test golden_tests::simple_doc ... ok
[INFO] [stdout] test golden_tests::simple_missing_dot ... ok
[INFO] [stdout] test groff_smoke_tests::chapters ... ignored
[INFO] [stdout] test golden_tests::simple_sidenote ... ok
[INFO] [stdout] test groff_smoke_tests::font_bold_italics_code ... ignored
[INFO] [stdout] test golden_tests::simple_minimal ... ok
[INFO] [stdout] test golden_tests::slides ... ok
[INFO] [stdout] test groff_smoke_tests::font_drop_caps ... ignored
[INFO] [stdout] test groff_smoke_tests::font_old_style_figures ... ignored
[INFO] [stdout] test groff_smoke_tests::font_small_caps ... ignored
[INFO] [stdout] test groff_smoke_tests::refactoring_lose_context ... ignored
[INFO] [stdout] test groff_smoke_tests::refactoring_warp_at ... ignored
[INFO] [stdout] test groff_smoke_tests::references ... ignored
[INFO] [stdout] test groff_smoke_tests::showcase ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_codeblock ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_doc ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_footnote ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_heading ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_list ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_minimal ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_missing_dot ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_paragraph_no_break ... ignored
[INFO] [stdout] test groff_smoke_tests::simple_sidenote ... ignored
[INFO] [stdout] test groff_smoke_tests::slides ... ignored
[INFO] [stdout] test tests::bold ... ok
[INFO] [stdout] test tests::code_block ... ok
[INFO] [stdout] test tests::complex_code ... ok
[INFO] [stdout] test tests::code_escape_literal ... ok
[INFO] [stdout] test golden_tests::simple_paragraph_no_break ... ok
[INFO] [stdout] test tests::em_dash ... ok
[INFO] [stdout] test tests::en_dash ... ok
[INFO] [stdout] test tests::foot_note ... ok
[INFO] [stdout] test tests::heading_and_paragraph ... ok
[INFO] [stdout] test tests::heading_and_subheading ... ok
[INFO] [stdout] test tests::italic ... ok
[INFO] [stdout] test tests::link ... ok
[INFO] [stdout] test tests::list_1 ... ok
[INFO] [stdout] test tests::list_1_multiline_item ... ok
[INFO] [stdout] test tests::list_2 ... ok
[INFO] [stdout] test tests::literal ... ok
[INFO] [stdout] test tests::nested_list ... ok
[INFO] [stdout] test tests::not_chapter_mark ... ok
[INFO] [stdout] test tests::not_link ... ok
[INFO] [stdout] test tests::not_right_side_note ... ok
[INFO] [stdout] test tests::paragraph_and_heading ... ok
[INFO] [stdout] test tests::right_side_note ... ok
[INFO] [stdout] test golden_tests::references ... FAILED
[INFO] [stdout] test golden_tests::refactoring_lose_context ... FAILED
[INFO] [stdout] test golden_tests::showcase ... FAILED
[INFO] [stdout] test tests::chapter_mark ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- golden_tests::references stdout ----
[INFO] [stdout] current char: ], Empty
[INFO] [stdout] 
[INFO] [stdout] thread 'golden_tests::references' (145) panicked at src/bin/matopdf.rs:444:9:
[INFO] [stdout] assertion `left == right` failed: groff output changed for samples/references/references.md
[INFO] [stdout]   left: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Mato\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.PP\n.MN RIGHT\n.PT_SIZE +48\n.COLOR mato_dark_blue\nm\n.MN OFF\n.COLOR lightgrey\n\\v'15c'\\h'0c'\\s[500]m\\s[0]\\v'-15c'\\h'-0c'\n.COLOR black\n.FT B\n.EW 2\n.HEADING 2 NAMED intro \"Introduction \"\n.EW 0\n.FT R\n.SP 1v\n.PP\n\n.DROPCAP I 2\nn the following we describe, the motivation,\narchitecture and implementation of \\*[CODE]mato\\*[CODE OFF]\\&.\n\\*[CODE]mato\\*[CODE OFF] is a set of tools to transform markdown\nformatted text into pdf files. It uses \\*[CODE]groff\\*[CODE OFF],\nGNU roff, for the production of pdfs.\n.EW 2\n.HEADING 3 NAMED moti \"Motivation \"\n.EW 0\n.SP 1v\n.PP\n\n.DROPCAP H 2\nere in this section, we present the motivation\nfor creating \\*[CODE]mato\\*[CODE OFF]\\&. Please see \\c\n.PDF_LINK arch \"later\"\\c\n\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nRendering performance\n.MN OFF\n.SP 1v\n.PP\nUsing \\*[CODE]groff\\*[CODE OFF] was primarily motivated by its\nvery quick rendering times, as opposed to LaTeX\nfor example. A prior version of \\*[CODE]mato\\*[CODE OFF] even used\nLaTeX as a backend, or rendering engine. But upon\ntrying \\*[CODE]groff\\*[CODE OFF] it quickly became apparent, that\nit gave much better response times.\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nInstallation size\n.MN OFF\n.SP 1v\n.PP\nBut in addition to that, installation size of the\nrendering backend dependencies drove the decision\nto use \\*[CODE]groff\\*[CODE OFF]\\&. Modern LaTe distributions, like\nTeX-Live can easily take up above \\[one.oldstyle]GB of disk\nspace and be very unwieldy to handle.\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nCommunity\n.MN OFF\n.SP 1v\n.PP\nAnother point, why we chose \\*[CODE]groff\\*[CODE OFF] is its really\nactive and live community. The mailing list has\nconstant activity and since quite some time the\ncode base is being developed rather constantly.\n.PP\nBugs that were reported are being addressed\nthoroughly and quickly. And discussions are\nfriendly and in a welcoming tone.\n.EW 2\n.HEADING 3 \"Structure\"\n.EW 0\n.SP 1v\n.PP\nThe structure of this document is as follows: first,\nwe present the architecture of the chosen solution.\nThen we detail the implentational approach we chose.\n.FT B\n.EW 2\n.HEADING 2 NAMED arch \"Architecture \"\n.EW 0\n.FT R\n.SP 1v\n.PP\nIn the \\c\n.PDF_LINK intro \"previous section *\"\\c\n, we presented the\nmotivation, why we created \\*[CODE]mato\\*[CODE OFF]\\&.\n.FT B\n.EW 2\n.HEADING 2 NAMED impl \"Implementation \"\n.EW 0\n.FT R\n.FT B\n.EW 2\n.HEADING 2 \"Conclusion\"\n.EW 0\n.FT R\n.SP 1v\n"
[INFO] [stdout]  right: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Mato\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.PP\n.MN RIGHT\n.PT_SIZE +48\n.COLOR mato_dark_blue\nm\n.MN OFF\n.FT B\n.EW 2\n.HEADING 2 NAMED intro \"Introduction \"\n.EW 0\n.FT R\n.SP 1v\n.PP\n\n.DROPCAP I 2\nn the following we describe, the motivation,\narchitecture and implementation of \\*[CODE]mato\\*[CODE OFF]\\&.\n\\*[CODE]mato\\*[CODE OFF] is a set of tools to transform markdown\nformatted text into pdf files. It uses \\*[CODE]groff\\*[CODE OFF],\nGNU roff, for the production of pdfs.\n.EW 2\n.HEADING 3 NAMED moti \"Motivation \"\n.EW 0\n.SP 1v\n.PP\n\n.DROPCAP H 2\nere in this section, we present the motivation\nfor creating \\*[CODE]mato\\*[CODE OFF]\\&. Please see \\c\n.PDF_LINK arch \"later\"\\c\n\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nRendering performance\n.MN OFF\n.SP 1v\n.PP\nUsing \\*[CODE]groff\\*[CODE OFF] was primarily motivated by its\nvery quick rendering times, as opposed to LaTeX\nfor example. A prior version of \\*[CODE]mato\\*[CODE OFF] even used\nLaTeX as a backend, or rendering engine. But upon\ntrying \\*[CODE]groff\\*[CODE OFF] it quickly became apparent, that\nit gave much better response times.\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nInstallation size\n.MN OFF\n.SP 1v\n.PP\nBut in addition to that, installation size of the\nrendering backend dependencies drove the decision\nto use \\*[CODE]groff\\*[CODE OFF]\\&. Modern LaTe distributions, like\nTeX-Live can easily take up above \\[one.oldstyle]GB of disk\nspace and be very unwieldy to handle.\n.SPACE -1v\n.MN LEFT\n\\!.ALD 1v\nCommunity\n.MN OFF\n.SP 1v\n.PP\nAnother point, why we chose \\*[CODE]groff\\*[CODE OFF] is its really\nactive and live community. The mailing list has\nconstant activity and since quite some time the\ncode base is being developed rather constantly.\n.PP\nBugs that were reported are being addressed\nthoroughly and quickly. And discussions are\nfriendly and in a welcoming tone.\n.EW 2\n.HEADING 3 \"Structure\"\n.EW 0\n.SP 1v\n.PP\nThe structure of this document is as follows: first,\nwe present the architecture of the chosen solution.\nThen we detail the implentational approach we chose.\n.FT B\n.EW 2\n.HEADING 2 NAMED arch \"Architecture \"\n.EW 0\n.FT R\n.SP 1v\n.PP\nIn the \\c\n.PDF_LINK intro \"previous section *\"\\c\n, we presented the\nmotivation, why we created \\*[CODE]mato\\*[CODE OFF]\\&.\n.FT B\n.EW 2\n.HEADING 2 NAMED impl \"Implementation \"\n.EW 0\n.FT R\n.FT B\n.EW 2\n.HEADING 2 \"Conclusion\"\n.EW 0\n.FT R\n.SP 1v\n"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c8515938eca - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5c8515938eca - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5c851594e98a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5c851594e98a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x5c851593da12 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5c851593da12 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5c8515916eff - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5c8515916eff - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5c8515930e59 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5c85158a288c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5c85158a288c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5c8515931012 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5c8515931012 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5c8515916fb8 - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5c851590e809 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5c8515917ccd - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5c851594f0dc - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5c851594eff3 - core[35159d6ffb30e017]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5c851589077e - core[35159d6ffb30e017]::panicking::assert_failed::<alloc[a8a7aba520129d98]::string::String, alloc[a8a7aba520129d98]::string::String>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5c8515891f16 - matopdf[57b08dce1b909f57]::golden_tests::assert_golden
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:444:9
[INFO] [stdout]   22:     0x5c85158922d2 - matopdf[57b08dce1b909f57]::golden_tests::references
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:465:45
[INFO] [stdout]   23:     0x5c8515890a67 - matopdf[57b08dce1b909f57]::golden_tests::references::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:465:28
[INFO] [stdout]   24:     0x5c8515894c06 - <matopdf[57b08dce1b909f57]::golden_tests::references::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5c8515895b6b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5c8515895b6b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x5c85158a335b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5c85158a335b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x5c85158a335b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x5c851589dd34 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x5c851589dd34 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5c85158a5f62 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stderr] error: test failed, to rerun pass `--bin matopdf`
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5c85158a5f62 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5c85158a5f62 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5c85159382bf - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   44:     0x5c85159382bf - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x7e340addaaa4 - <unknown>
[INFO] [stdout]   46:     0x7e340ae67a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- golden_tests::refactoring_lose_context stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'golden_tests::refactoring_lose_context' (143) panicked at src/bin/matopdf.rs:444:9:
[INFO] [stdout] assertion `left == right` failed: groff output changed for samples/refactorings/lose_the_context.md
[INFO] [stdout]   left: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Lose the evil Context\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.MN RIGHT\n.PT_SIZE +48\n.COLOR mato_dark_red\nC\n.MN OFF\n.COLOR lightgrey\n\\v'15c'\\h'0c'\\s[500]C\\s[0]\\v'-15c'\\h'-0c'\n.COLOR black\n.FT B\n.EW 2\n.HEADING 2 \"Motivation\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nCurrently, \\*[CODE]mato\\*[CODE OFF] parses markdown formatted files with a parser into\nan abstract syntax tree (\\[A.sc]\\[S.sc]\\[T.sc]). For example, the following little\nfile:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nThis is a sentence\\[ha](And this is a footnote.)\nthat is quite long.\n.QUOTE OFF\n.PP\nis parsed into an \\[A.sc]\\[S.sc]\\[T.sc], like the following:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nDocument(DEFAULT, \n  Cat(\n    Cat(\n      Cat(\n        Literal(\"This is a sentence\"), \n        Footnote(\n          Literal(\"And this is a footnote.\"))), \n      Literal(\" that is quite long.\")), \n    LineBreak))\n.QUOTE OFF\n.PP\nBut, not all information that is necessary to render a \\*[CODE]groff\\*[CODE OFF] file\nis present in this \\[A.sc]\\[S.sc]\\[T.sc]\\&. What is missing is outlined in the following\nparagraphs.\n.EW 2\n.HEADING 3 \"Preamble is missing from AST\"\n.EW 0\n.SP 1v\n.PP\nEvery \\*[CODE]groff\\*[CODE OFF] file that is generated by \\*[CODE]mato\\*[CODE OFF] has a \\*[IT]preamble\\*[ROM],\ni.e., a default section of \\*[CODE]groff\\*[CODE OFF] macros, that defines a certain\ndefault style.  The first few lines of the default preamble look\nlike the following:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\n.PRINTSTYLE TYPESET\n\\&.PAGE 5.8i 9i 1i 1i .75i .75i\n\\&.PAPER A5\n\\&.FAMILY Minion\n\\&.PT_SIZE 11\n\\&.AUTOLEAD 2\n.QUOTE OFF\n.PP\nThis preamble is basically a character string, that\nis being read in during startup of \\*[CODE]matopdf\\*[CODE OFF]\\c\n.FOOTNOTE\nOr even during compile\ntime.\n.FOOTNOTE END\n and spat out as is during rendering at the beginning of the\ndocument.\n.PP\nAs it is not part of the \\[A.sc]\\[S.sc]\\[T.sc], as shown above, it has to be communicated\nto the renderer in some other way. That other way is a \\*[BD]context\\*[ROM]\nobject. And that context object is then being consulted in the\nrenderer.\n.PP\nWe can see the code location, where the storing of the preamble\nhappens in the constructor of an \\[A.sc]\\[S.sc]\\[T.sc] processor called \\*[CODE]MetaDataExtractor\\*[CODE OFF]:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\npub fn new(preamble: &str) -> Box<dyn Process> {\n    let mut map = HashMap::new();\n    let cp = preamble.to_string();\n    if !cp.is_empty() {\n        map.insert(\"preamble\".to_string(),\n          preamble.to_string());\n    }\n    Box::new(MetaDataExtractor {\n      ctx: map, doc_type: \"\".to_owned() })\n}\n.QUOTE OFF\n.PP\nHere, we see, that a new \\*[CODE]HashMap\\*[CODE OFF] is being created and the preamble\nis stored in there under a key called \\*[CODE]preamble\\*[CODE OFF]\\&.\n.PP\nAn interesting aside, that already is hinting at the problem, is the\n\\[A.sc]\\[S.sc]\\[T.sc] line of the constructor, where a new object, or struct of type\n\\*[CODE]MetaDataExtractor\\*[CODE OFF] is created and the context object is being put\nin.\n.PP\nAnd we can find the location where said preamble is looked up again\nin the renderer:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nif self.ctx.contains_key(\"preamble\") {\n    let value = self.ctx.get(\"preamble\").unwrap();\n    result = format!(\"{}\\\\\\\\n{}\\\\\\\\n\", result, value);\n    self.ctx.remove(\"preamble\");\n}\n.QUOTE OFF\n.FT B\n.EW 2\n.HEADING 2 \"Problem statement and analysis\"\n.EW 0\n.FT R\n.EW 2\n.HEADING 3 \"Problem statement\"\n.EW 0\n.SP 1v\n.PP\nI claim that \\*[IT]context objects are a bad thing\\*[ROM]\\&.\n.PP\nBut, why, you probably ask. Aren't they used almost everywhere in\nsoftware development? Aren't they a vital part of many software\narchitectures?\n.PP\nI see currently the following problems:\n.PP\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\n\\*[IT]Loss of type safety\\*[ROM] - You put strings into the context object and you look-up strings again. Nobody guards you against dumb errors, misspelling the key name. You have to look out for yourself and that convolutes your code, as you must guard against a key not being present, or no value being stored under a present key.\n.ITEM\n.PP\n\\*[IT]Loss of locality\\*[ROM] - You put something into the context object at one place in your code and it can be very hard to find the places where the key is looked up again. The same argument applies vice versa.\n.ITEM\n.PP\n\\*[IT]Loss of conciseness\\*[ROM] - Your code get's ugly, as you begin to look-up stuff in context objects. The context object tends to creep into every function parameter list.\n.ITEM\n.PP\n\\*[IT]Loss of expressiveness\\*[ROM] - A function parameter list with a context object does not communicate which information is really needed by the function.\n.LIST OFF\n\nAs all of these problems aggregate, I think it is favorable to \nstrive towards other solutions. Solutions that are \\*[IT]type safe\\*[ROM], \npreserve \\*[IT]locality\\*[ROM] and \\*[IT]conciseness\\*[ROM] and keeps your function\nsignatures expressive.\n.FT B\n.EW 2\n.HEADING 2 \"Approach\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nIn my current understanding, it might be a good idea to store\nas much information in the \\[A.sc]\\[S.sc]\\[T.sc] as possible. That way it is \nat every moment obvious, what information is being communicated\nbetween different phases of processing. Furthermore, the rendering\nis much more obvious, as \\*[CODE]rust\\*[CODE OFF]s \\*[CODE]match\\*[CODE OFF] expressions help here.\n.EW 2\n.HEADING 3 \"Document Type as a first example\"\n.EW 0\n.SP 1v\n.PP\nIn the \\[A.sc]\\[S.sc]\\[T.sc] above, we see that \\*[CODE]Document\\*[CODE OFF] has a member with a \nvalue of \\*[CODE]DEFAULT\\*[CODE OFF]\\&. \n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nDocument(DEFAULT, \n\\&...\n.QUOTE OFF\n.PP\nNot obvious in that snapshot of an \\[A.sc]\\[S.sc]\\[T.sc] is\nthat this is a member called \\*[CODE]doc_type\\*[CODE OFF]:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\npub enum Exp {\n    Document(DocType, Box<Exp>),\n\\&...\n.QUOTE OFF\n.PP\nFormerly, the document type was also stored in the context object.\nAt some point in the past, I decided to investigate the possibilites\nof reducing the amount of information stored in the context object.\n.PP\nThis member is the result of this endeavours."
[INFO] [stdout]  right: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Lose the evil Context\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.MN RIGHT\n.PT_SIZE +48\n.COLOR mato_dark_red\nC\n.MN OFF\n.FT B\n.EW 2\n.HEADING 2 \"Motivation\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nCurrently, \\*[CODE]mato\\*[CODE OFF] parses markdown formatted files with a parser into\nan abstract syntax tree (\\[A.sc]\\[S.sc]\\[T.sc]). For example, the following little\nfile:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nThis is a sentence\\[ha](And this is a footnote.)\nthat is quite long.\n.QUOTE OFF\n.PP\nis parsed into an \\[A.sc]\\[S.sc]\\[T.sc], like the following:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nDocument(DEFAULT, \n  Cat(\n    Cat(\n      Cat(\n        Literal(\"This is a sentence\"), \n        Footnote(\n          Literal(\"And this is a footnote.\"))), \n      Literal(\" that is quite long.\")), \n    LineBreak))\n.QUOTE OFF\n.PP\nBut, not all information that is necessary to render a \\*[CODE]groff\\*[CODE OFF] file\nis present in this \\[A.sc]\\[S.sc]\\[T.sc]\\&. What is missing is outlined in the following\nparagraphs.\n.EW 2\n.HEADING 3 \"Preamble is missing from AST\"\n.EW 0\n.SP 1v\n.PP\nEvery \\*[CODE]groff\\*[CODE OFF] file that is generated by \\*[CODE]mato\\*[CODE OFF] has a \\*[IT]preamble\\*[ROM],\ni.e., a default section of \\*[CODE]groff\\*[CODE OFF] macros, that defines a certain\ndefault style.  The first few lines of the default preamble look\nlike the following:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\n.PRINTSTYLE TYPESET\n\\&.PAGE 5.8i 9i 1i 1i .75i .75i\n\\&.PAPER A5\n\\&.FAMILY Minion\n\\&.PT_SIZE 11\n\\&.AUTOLEAD 2\n.QUOTE OFF\n.PP\nThis preamble is basically a character string, that\nis being read in during startup of \\*[CODE]matopdf\\*[CODE OFF]\\c\n.FOOTNOTE\nOr even during compile\ntime.\n.FOOTNOTE END\n and spat out as is during rendering at the beginning of the\ndocument.\n.PP\nAs it is not part of the \\[A.sc]\\[S.sc]\\[T.sc], as shown above, it has to be communicated\nto the renderer in some other way. That other way is a \\*[BD]context\\*[ROM]\nobject. And that context object is then being consulted in the\nrenderer.\n.PP\nWe can see the code location, where the storing of the preamble\nhappens in the constructor of an \\[A.sc]\\[S.sc]\\[T.sc] processor called \\*[CODE]MetaDataExtractor\\*[CODE OFF]:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\npub fn new(preamble: &str) -> Box<dyn Process> {\n    let mut map = HashMap::new();\n    let cp = preamble.to_string();\n    if !cp.is_empty() {\n        map.insert(\"preamble\".to_string(),\n          preamble.to_string());\n    }\n    Box::new(MetaDataExtractor {\n      ctx: map, doc_type: \"\".to_owned() })\n}\n.QUOTE OFF\n.PP\nHere, we see, that a new \\*[CODE]HashMap\\*[CODE OFF] is being created and the preamble\nis stored in there under a key called \\*[CODE]preamble\\*[CODE OFF]\\&.\n.PP\nAn interesting aside, that already is hinting at the problem, is the\n\\[A.sc]\\[S.sc]\\[T.sc] line of the constructor, where a new object, or struct of type\n\\*[CODE]MetaDataExtractor\\*[CODE OFF] is created and the context object is being put\nin.\n.PP\nAnd we can find the location where said preamble is looked up again\nin the renderer:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nif self.ctx.contains_key(\"preamble\") {\n    let value = self.ctx.get(\"preamble\").unwrap();\n    result = format!(\"{}\\\\\\\\n{}\\\\\\\\n\", result, value);\n    self.ctx.remove(\"preamble\");\n}\n.QUOTE OFF\n.FT B\n.EW 2\n.HEADING 2 \"Problem statement and analysis\"\n.EW 0\n.FT R\n.EW 2\n.HEADING 3 \"Problem statement\"\n.EW 0\n.SP 1v\n.PP\nI claim that \\*[IT]context objects are a bad thing\\*[ROM]\\&.\n.PP\nBut, why, you probably ask. Aren't they used almost everywhere in\nsoftware development? Aren't they a vital part of many software\narchitectures?\n.PP\nI see currently the following problems:\n.PP\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\n\\*[IT]Loss of type safety\\*[ROM] - You put strings into the context object and you look-up strings again. Nobody guards you against dumb errors, misspelling the key name. You have to look out for yourself and that convolutes your code, as you must guard against a key not being present, or no value being stored under a present key.\n.ITEM\n.PP\n\\*[IT]Loss of locality\\*[ROM] - You put something into the context object at one place in your code and it can be very hard to find the places where the key is looked up again. The same argument applies vice versa.\n.ITEM\n.PP\n\\*[IT]Loss of conciseness\\*[ROM] - Your code get's ugly, as you begin to look-up stuff in context objects. The context object tends to creep into every function parameter list.\n.ITEM\n.PP\n\\*[IT]Loss of expressiveness\\*[ROM] - A function parameter list with a context object does not communicate which information is really needed by the function.\n.LIST OFF\n\nAs all of these problems aggregate, I think it is favorable to \nstrive towards other solutions. Solutions that are \\*[IT]type safe\\*[ROM], \npreserve \\*[IT]locality\\*[ROM] and \\*[IT]conciseness\\*[ROM] and keeps your function\nsignatures expressive.\n.FT B\n.EW 2\n.HEADING 2 \"Approach\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nIn my current understanding, it might be a good idea to store\nas much information in the \\[A.sc]\\[S.sc]\\[T.sc] as possible. That way it is \nat every moment obvious, what information is being communicated\nbetween different phases of processing. Furthermore, the rendering\nis much more obvious, as \\*[CODE]rust\\*[CODE OFF]s \\*[CODE]match\\*[CODE OFF] expressions help here.\n.EW 2\n.HEADING 3 \"Document Type as a first example\"\n.EW 0\n.SP 1v\n.PP\nIn the \\[A.sc]\\[S.sc]\\[T.sc] above, we see that \\*[CODE]Document\\*[CODE OFF] has a member with a \nvalue of \\*[CODE]DEFAULT\\*[CODE OFF]\\&. \n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\nDocument(DEFAULT, \n\\&...\n.QUOTE OFF\n.PP\nNot obvious in that snapshot of an \\[A.sc]\\[S.sc]\\[T.sc] is\nthat this is a member called \\*[CODE]doc_type\\*[CODE OFF]:\n.PP\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\npub enum Exp {\n    Document(DocType, Box<Exp>),\n\\&...\n.QUOTE OFF\n.PP\nFormerly, the document type was also stored in the context object.\nAt some point in the past, I decided to investigate the possibilites\nof reducing the amount of information stored in the context object.\n.PP\nThis member is the result of this endeavours."
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c8515938eca - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5c8515938eca - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5c851594e98a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5c851594e98a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x5c851593da12 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5c851593da12 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5c8515916eff - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5c8515916eff - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5c8515930e59 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5c85158a288c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5c85158a288c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5c8515931012 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5c8515931012 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5c8515916fb8 - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5c851590e809 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5c8515917ccd - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5c851594f0dc - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5c851594eff3 - core[35159d6ffb30e017]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5c851589077e - core[35159d6ffb30e017]::panicking::assert_failed::<alloc[a8a7aba520129d98]::string::String, alloc[a8a7aba520129d98]::string::String>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5c8515891f16 - matopdf[57b08dce1b909f57]::golden_tests::assert_golden
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:444:9
[INFO] [stdout]   22:     0x5c8515892492 - matopdf[57b08dce1b909f57]::golden_tests::refactoring_lose_context
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:471:45
[INFO] [stdout]   23:     0x5c8515890f37 - matopdf[57b08dce1b909f57]::golden_tests::refactoring_lose_context::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:471:42
[INFO] [stdout]   24:     0x5c8515894f86 - <matopdf[57b08dce1b909f57]::golden_tests::refactoring_lose_context::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5c8515895b6b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5c8515895b6b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x5c85158a335b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5c85158a335b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x5c85158a335b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x5c851589dd34 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x5c851589dd34 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5c85158a5f62 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5c85158a5f62 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5c85158a5f62 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5c85159382bf - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   44:     0x5c85159382bf - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x7e340addaaa4 - <unknown>
[INFO] [stdout]   46:     0x7e340ae67a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- golden_tests::showcase stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'golden_tests::showcase' (146) panicked at src/bin/matopdf.rs:444:9:
[INFO] [stdout] assertion `left == right` failed: groff output changed for samples/showcase/doc.md
[INFO] [stdout]   left: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Showcase\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.MN RIGHT\n.PT_SIZE +48\nS\n.MN OFF\n.COLOR lightgrey\n\\v'15c'\\h'0c'\\s[500]S\\s[0]\\v'-15c'\\h'-0c'\n.COLOR black\nThis document shows all the features, we have.\n.EW 2\n.HEADING 3 \"Markup\"\n.EW 0\n.SP 1v\n.PP\n\\*[IT]Italic text\\*[ROM], \\*[BD]fat text\\*[ROM], \\*[CODE]code formatting\\*[CODE OFF]\\&.\n.EW 2\n.HEADING 3 \"Listings and Side notes\"\n.EW 0\n.SP 1v\n.PP\nThe following is a listing:\n.PP\n\n.MN RIGHT\n.PT_SIZE -2\nA simple bash script\n.MN OFF\n\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\n$ echo hello world\nhello world\n.QUOTE OFF\n.FT B\n.EW 2\n.HEADING 2 \"Features\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nLet's describe, what we see.\n.EW 2\n.HEADING 3 \"Enumeration\"\n.EW 0\n.SP 1v\n.PP\nYou can see:\n.PP\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nThe toplevel heading (Showcase).\n.LIST OFF\n\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nA sub level heading (Features).\n.LIST OFF\n\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nA right chapter mark (S)\n.LIST OFF\n\n.PP\n"
[INFO] [stdout]  right: ".DOCTYPE DEFAULT\n.PRINTSTYLE TYPESET\n\\\" fill with your presets\n\\\" see samples/preambles/bringhurst.mom\n.START\n.FT B\n.EW 2\n.HEADING 1 \"Showcase\"\n.EW 0\n.FT R\n.DRH\n.SP 1v\n.MN RIGHT\n.PT_SIZE +48\nS\n.MN OFF\nThis document shows all the features, we have.\n.EW 2\n.HEADING 3 \"Markup\"\n.EW 0\n.SP 1v\n.PP\n\\*[IT]Italic text\\*[ROM], \\*[BD]fat text\\*[ROM], \\*[CODE]code formatting\\*[CODE OFF]\\&.\n.EW 2\n.HEADING 3 \"Listings and Side notes\"\n.EW 0\n.SP 1v\n.PP\nThe following is a listing:\n.PP\n\n.MN RIGHT\n.PT_SIZE -2\nA simple bash script\n.MN OFF\n\n.QUOTE_STYLE INDENT 1\n.QUOTE\n.CODE\n$ echo hello world\nhello world\n.QUOTE OFF\n.FT B\n.EW 2\n.HEADING 2 \"Features\"\n.EW 0\n.FT R\n.SP 1v\n.PP\nLet's describe, what we see.\n.EW 2\n.HEADING 3 \"Enumeration\"\n.EW 0\n.SP 1v\n.PP\nYou can see:\n.PP\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nThe toplevel heading (Showcase).\n.LIST OFF\n\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nA sub level heading (Features).\n.LIST OFF\n\n.LIST\n.SHIFT_LIST 18p\n.PARA_INDENT 0\n.ITEM\n.PP\nA right chapter mark (S)\n.LIST OFF\n\n.PP\n"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c8515938eca - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5c8515938eca - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5c851594e98a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5c851594e98a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x5c851593da12 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5c851593da12 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5c8515916eff - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5c8515916eff - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5c8515930e59 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5c85158a288c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5c85158a288c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5c8515931012 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5c8515931012 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5c8515916fb8 - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5c851590e809 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5c8515917ccd - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5c851594f0dc - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5c851594eff3 - core[35159d6ffb30e017]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5c851589077e - core[35159d6ffb30e017]::panicking::assert_failed::<alloc[a8a7aba520129d98]::string::String, alloc[a8a7aba520129d98]::string::String>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5c8515891f16 - matopdf[57b08dce1b909f57]::golden_tests::assert_golden
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:444:9
[INFO] [stdout]   22:     0x5c8515892512 - matopdf[57b08dce1b909f57]::golden_tests::showcase
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:468:45
[INFO] [stdout]   23:     0x5c8515890ff7 - matopdf[57b08dce1b909f57]::golden_tests::showcase::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:468:26
[INFO] [stdout]   24:     0x5c8515895086 - <matopdf[57b08dce1b909f57]::golden_tests::showcase::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5c8515895b6b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5c8515895b6b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x5c85158a335b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5c85158a335b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x5c85158a335b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x5c851589dd34 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x5c851589dd34 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5c85158a5f62 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5c85158a5f62 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5c85158a5f62 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5c85159382bf - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   44:     0x5c85159382bf - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x7e340addaaa4 - <unknown>
[INFO] [stdout]   46:     0x7e340ae67a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::chapter_mark stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::chapter_mark' (158) panicked at src/bin/matopdf.rs:336:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: ".DOCTYPE DEFAULT\n.START\n.MN RIGHT\n.PT_SIZE +48\nc\n.MN OFF\n.COLOR lightgrey\n\\v'15c'\\h'0c'\\s[500]c\\s[0]\\v'-15c'\\h'-0c'\n.COLOR black"
[INFO] [stdout]  right: ".DOCTYPE DEFAULT\n.START\n.MN RIGHT\n.PT_SIZE +48\nc\n.MN OFF\n\\v'10c'\\h'3c'\\s[120]\\m[gray]c\\m[]\\s[0]\\v'-10c'\\h'-3c'"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c8515938eca - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c8515938eca - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5c8515938eca - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5c851594e98a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5c851594e98a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x5c851593da12 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5c851593da12 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5c8515916eff - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5c8515916eff - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5c8515930e59 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5c85158a288c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5c85158a288c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5c8515931012 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5c8515931012 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5c8515916fb8 - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5c851590e809 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5c8515917ccd - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5c851594f0dc - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5c851594ef93 - core[35159d6ffb30e017]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x5c85158907cf - core[35159d6ffb30e017]::panicking::assert_failed::<alloc[a8a7aba520129d98]::string::String, &str>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5c85158936db - matopdf[57b08dce1b909f57]::tests::chapter_mark
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:336:9
[INFO] [stdout]   22:     0x5c85158915b7 - matopdf[57b08dce1b909f57]::tests::chapter_mark::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/bin/matopdf.rs:335:22
[INFO] [stdout]   23:     0x5c8515895606 - <matopdf[57b08dce1b909f57]::tests::chapter_mark::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5c8515895b6b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5c8515895b6b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5c85158a335b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5c85158a335b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5c85158a335b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5c85158a335b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5c85158a335b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5c851589dd34 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5c851589dd34 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5c85158a5f62 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5c85158a5f62 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5c85158a5f62 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5c85158a5f62 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5c85158a5f62 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5c85159382bf - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x5c85159382bf - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x7e340addaaa4 - <unknown>
[INFO] [stdout]   45:     0x7e340ae67a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     golden_tests::refactoring_lose_context
[INFO] [stdout]     golden_tests::references
[INFO] [stdout]     golden_tests::showcase
[INFO] [stdout]     tests::chapter_mark
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 37 passed; 4 failed; 19 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ec015f3cf1ed1007cc847c978df88a947f0e61252698e9f6728d3401d417019f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ec015f3cf1ed1007cc847c978df88a947f0e61252698e9f6728d3401d417019f", kill_on_drop: false }`
[INFO] [stdout] ec015f3cf1ed1007cc847c978df88a947f0e61252698e9f6728d3401d417019f
