Nov 16 21:15:37.666 INFO testing livioribeiro/cargo-readme against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 21:15:37.666 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:15:38.204 INFO [stdout] 6dc4a1b180c9fb39109cfd9665150084de534300b09e05d528f2b08f381ed608
Nov 16 21:15:38.207 INFO running `"docker" "start" "-a" "6dc4a1b180c9fb39109cfd9665150084de534300b09e05d528f2b08f381ed608"`
Nov 16 21:15:39.655 INFO [stderr] usermod: no changes
Nov 16 21:15:39.814 INFO [stderr]    Compiling cargo-readme v3.1.2 (/source)
Nov 16 21:15:48.868 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.18s
Nov 16 21:15:48.876 INFO [stderr] su: No module specific data is present
Nov 16 21:15:49.635 INFO running `"docker" "inspect" "6dc4a1b180c9fb39109cfd9665150084de534300b09e05d528f2b08f381ed608"`
Nov 16 21:15:49.917 INFO running `"docker" "rm" "-f" "6dc4a1b180c9fb39109cfd9665150084de534300b09e05d528f2b08f381ed608"`
Nov 16 21:15:50.281 INFO [stdout] 6dc4a1b180c9fb39109cfd9665150084de534300b09e05d528f2b08f381ed608
Nov 16 21:15:50.289 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:15:50.815 INFO [stdout] 64daaf85521a4b48489786935355921d33fa37a970bbf956e800d6e377c87192
Nov 16 21:15:50.821 INFO running `"docker" "start" "-a" "64daaf85521a4b48489786935355921d33fa37a970bbf956e800d6e377c87192"`
Nov 16 21:15:52.123 INFO [stderr] usermod: no changes
Nov 16 21:15:52.335 INFO [stderr]    Compiling colored v1.6.1
Nov 16 21:15:53.743 INFO [stderr]    Compiling assert_cli v0.6.3
Nov 16 21:15:56.757 INFO [stderr]    Compiling cargo-readme v3.1.2 (/source)
Nov 16 21:16:13.331 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.18s
Nov 16 21:16:13.347 INFO [stderr] su: No module specific data is present
Nov 16 21:16:14.263 INFO running `"docker" "inspect" "64daaf85521a4b48489786935355921d33fa37a970bbf956e800d6e377c87192"`
Nov 16 21:16:14.527 INFO running `"docker" "rm" "-f" "64daaf85521a4b48489786935355921d33fa37a970bbf956e800d6e377c87192"`
Nov 16 21:16:14.903 INFO [stdout] 64daaf85521a4b48489786935355921d33fa37a970bbf956e800d6e377c87192
Nov 16 21:16:14.909 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:16:15.459 INFO [stdout] 52976e55c58f7e6e0277c004319d9bf6171fcd7def171061aa057433369a8559
Nov 16 21:16:15.461 INFO running `"docker" "start" "-a" "52976e55c58f7e6e0277c004319d9bf6171fcd7def171061aa057433369a8559"`
Nov 16 21:16:16.467 INFO [stderr] usermod: no changes
Nov 16 21:16:16.699 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Nov 16 21:16:16.719 INFO [stderr]      Running /target/debug/deps/cargo_readme-e9c6c724b946cc71
Nov 16 21:16:16.720 INFO [stdout] 
Nov 16 21:16:16.720 INFO [stdout] running 42 tests
Nov 16 21:16:16.728 INFO [stdout] test readme::extract::tests::extract_docs_mix_styles_singleline ... ok
Nov 16 21:16:16.728 INFO [stdout] test readme::extract::tests::extract_docs_singleline_style ... ok
Nov 16 21:16:16.729 INFO [stdout] test readme::extract::tests::extract_docs_nested_level_2 ... ok
Nov 16 21:16:16.731 INFO [stdout] test readme::extract::tests::extract_docs_nested_level_1 ... ok
Nov 16 21:16:16.731 INFO [stdout] test readme::extract::tests::extract_docs_multiline_style ... ok
Nov 16 21:16:16.732 INFO [stdout] test readme::extract::tests::extract_docs_mix_styles_multiline ... ok
Nov 16 21:16:16.738 INFO [stdout] test readme::process::tests::alternate_delimiter_4_backticks ... ok
Nov 16 21:16:16.743 INFO [stdout] test readme::process::tests::alternate_delimiter_3_tildes ... ok
Nov 16 21:16:16.744 INFO [stdout] test readme::process::tests::alternate_delimiter_4_tildes ... ok
Nov 16 21:16:16.744 INFO [stdout] test readme::process::tests::alternate_delimiter_4_backticks_nested ... ok
Nov 16 21:16:16.755 INFO [stdout] test readme::process::tests::hide_line_in_rust_code_block ... ok
Nov 16 21:16:16.756 INFO [stdout] test readme::process::tests::transform_text_block ... ok
Nov 16 21:16:16.756 INFO [stdout] test readme::template::tests::append_license_with_empty_readme ... ok
Nov 16 21:16:16.760 INFO [stdout] test readme::template::tests::append_license_with_filled_readme ... ok
Nov 16 21:16:16.779 INFO [stdout] test readme::template::tests::prepend_badges_with_empty_readme_and_empty_badges ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::alternate_delimiter_mixed ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::do_not_indent_markdown_headings ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::transform_other_code_block_with_symbols ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::transform_rust_code_block ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::transform_rust_code_block_with_prefix ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::process::tests::indent_markdown_headings ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::prepend_badges_with_filled_readme_and_empty_badges ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::prepend_badges_with_filled_readme_and_non_empty_badges ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::prepend_title_with_empty_readme ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::prepend_title_with_filled_readme ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::render_full ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::render_license ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::render_minimal ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::render_nothing ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::render_title ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_full ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_minimal ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_badges ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_badge_tag_but_missing_badges_should_fail ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_license ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_title ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_license_tag_but_missing_license_should_fail ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_with_version ... ok
Nov 16 21:16:16.811 INFO [stdout] test readme::template::tests::template_without_readme_should_fail ... ok
Nov 16 21:16:16.815 INFO [stdout] test readme::template::tests::prepend_badges_with_empty_readme_and_non_empty_badges ... ok
Nov 16 21:16:16.815 INFO [stdout] test readme::process::tests::do_not_hide_line_in_code_block ... ok
Nov 16 21:16:16.823 INFO [stdout] test readme::template::tests::render_badges ... ok
Nov 16 21:16:16.823 INFO [stdout] 
Nov 16 21:16:16.823 INFO [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:16.823 INFO [stdout] 
Nov 16 21:16:16.835 INFO [stdout] 
Nov 16 21:16:16.835 INFO [stderr]      Running /target/debug/deps/cargo_readme-da35ddc38d9f9665
Nov 16 21:16:16.835 INFO [stdout] running 0 tests
Nov 16 21:16:16.835 INFO [stderr]      Running /target/debug/deps/alternate_input-5058afcef6c04fec
Nov 16 21:16:16.835 INFO [stdout] 
Nov 16 21:16:16.835 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:16.835 INFO [stdout] 
Nov 16 21:16:16.835 INFO [stdout] 
Nov 16 21:16:16.835 INFO [stdout] running 3 tests
Nov 16 21:16:17.191 INFO [stdout] test alternate_input_single_line ... ok
Nov 16 21:16:17.227 INFO [stdout] test alternate_input_empty_docs ... ok
Nov 16 21:16:17.242 INFO [stdout] test alternate_input_a_little_bit_longer ... ok
Nov 16 21:16:17.242 INFO [stdout] 
Nov 16 21:16:17.242 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:17.242 INFO [stdout] 
Nov 16 21:16:17.244 INFO [stderr]      Running /target/debug/deps/alternate_template-af8afb8b705b0977
Nov 16 21:16:17.251 INFO [stdout] 
Nov 16 21:16:17.251 INFO [stdout] running 1 test
Nov 16 21:16:17.407 INFO [stdout] test alternate_template ... ok
Nov 16 21:16:17.407 INFO [stdout] 
Nov 16 21:16:17.407 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:17.407 INFO [stdout] 
Nov 16 21:16:17.409 INFO [stderr]      Running /target/debug/deps/append_license-60ed0d5ba90eb3b5
Nov 16 21:16:17.427 INFO [stdout] 
Nov 16 21:16:17.427 INFO [stdout] running 2 tests
Nov 16 21:16:17.655 INFO [stdout] test append_license ... ok
Nov 16 21:16:17.663 INFO [stdout] test no_append_license ... ok
Nov 16 21:16:17.663 INFO [stdout] 
Nov 16 21:16:17.663 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:17.663 INFO [stdout] 
Nov 16 21:16:17.664 INFO [stderr]      Running /target/debug/deps/badges-9ca0b4160919f641
Nov 16 21:16:17.670 INFO [stdout] 
Nov 16 21:16:17.670 INFO [stdout] running 1 test
Nov 16 21:16:17.916 INFO [stdout] test badges ... ok
Nov 16 21:16:17.916 INFO [stdout] 
Nov 16 21:16:17.916 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:17.916 INFO [stdout] 
Nov 16 21:16:17.917 INFO [stderr]      Running /target/debug/deps/default_behavior-872cd8e8997ddc9a
Nov 16 21:16:17.927 INFO [stdout] 
Nov 16 21:16:17.927 INFO [stdout] running 1 test
Nov 16 21:16:18.184 INFO [stdout] test default_behavior ... ok
Nov 16 21:16:18.184 INFO [stdout] 
Nov 16 21:16:18.184 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:18.184 INFO [stdout] 
Nov 16 21:16:18.186 INFO [stderr]      Running /target/debug/deps/entrypoint_resolution-6cf0380f216e6632
Nov 16 21:16:18.199 INFO [stdout] 
Nov 16 21:16:18.199 INFO [stdout] running 4 tests
Nov 16 21:16:18.576 INFO [stdout] test entrypoint_resolution_lib ... ok
Nov 16 21:16:18.600 INFO [stdout] test entrypoint_resolution_cargo_bin ... ok
Nov 16 21:16:18.662 INFO [stdout] test entrypoint_resolution_main ... ok
Nov 16 21:16:18.696 INFO [stdout] test entrypoint_resolution_cargo_lib ... ok
Nov 16 21:16:18.696 INFO [stdout] 
Nov 16 21:16:18.697 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:18.697 INFO [stdout] 
Nov 16 21:16:18.697 INFO [stderr]      Running /target/debug/deps/multiline_doc-6358b345a528cfbd
Nov 16 21:16:18.700 INFO [stdout] 
Nov 16 21:16:18.700 INFO [stdout] running 1 test
Nov 16 21:16:18.959 INFO [stdout] test multiline_doc ... ok
Nov 16 21:16:18.960 INFO [stderr]      Running /target/debug/deps/multiple_bin_fail-8f009c202f4db4f7
Nov 16 21:16:18.960 INFO [stdout] 
Nov 16 21:16:18.960 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:18.961 INFO [stdout] 
Nov 16 21:16:18.961 INFO [stdout] 
Nov 16 21:16:18.962 INFO [stdout] running 1 test
Nov 16 21:16:19.143 INFO [stdout] test multiple_bin_fail ... ok
Nov 16 21:16:19.144 INFO [stdout] 
Nov 16 21:16:19.144 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:19.144 INFO [stdout] 
Nov 16 21:16:19.148 INFO [stderr]      Running /target/debug/deps/no_entrypoint_fail-8578d129ddc86923
Nov 16 21:16:19.157 INFO [stdout] 
Nov 16 21:16:19.157 INFO [stdout] running 1 test
Nov 16 21:16:19.275 INFO [stdout] test no_entrypoint_fail ... ok
Nov 16 21:16:19.275 INFO [stdout] 
Nov 16 21:16:19.276 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:19.276 INFO [stdout] 
Nov 16 21:16:19.277 INFO [stdout] 
Nov 16 21:16:19.277 INFO [stdout] running 1 test
Nov 16 21:16:19.277 INFO [stderr]      Running /target/debug/deps/no_template-2c266f1031702a70
Nov 16 21:16:19.419 INFO [stdout] test no_template ... ok
Nov 16 21:16:19.420 INFO [stdout] 
Nov 16 21:16:19.420 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:19.420 INFO [stdout] 
Nov 16 21:16:19.421 INFO [stderr]      Running /target/debug/deps/project_with_version-2143bb718a878699
Nov 16 21:16:19.422 INFO [stdout] 
Nov 16 21:16:19.422 INFO [stdout] running 1 test
Nov 16 21:16:19.552 INFO [stdout] test template_with_version ... ok
Nov 16 21:16:19.553 INFO [stdout] 
Nov 16 21:16:19.553 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:19.553 INFO [stdout] 
Nov 16 21:16:19.554 INFO [stderr]    Doc-tests cargo-readme
Nov 16 21:16:20.268 INFO [stdout] 
Nov 16 21:16:20.268 INFO [stdout] running 2 tests
Nov 16 21:16:20.268 INFO [stdout] test src/lib.rs -  (line 32) ... ignored
Nov 16 21:16:20.269 INFO [stdout] test src/lib.rs -  (line 77) ... ignored
Nov 16 21:16:20.269 INFO [stdout] 
Nov 16 21:16:20.269 INFO [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Nov 16 21:16:20.270 INFO [stdout] 
Nov 16 21:16:20.278 INFO [stderr] su: No module specific data is present
Nov 16 21:16:20.857 INFO running `"docker" "inspect" "52976e55c58f7e6e0277c004319d9bf6171fcd7def171061aa057433369a8559"`
Nov 16 21:16:21.035 INFO running `"docker" "rm" "-f" "52976e55c58f7e6e0277c004319d9bf6171fcd7def171061aa057433369a8559"`
Nov 16 21:16:21.347 INFO [stdout] 52976e55c58f7e6e0277c004319d9bf6171fcd7def171061aa057433369a8559