Nov 16 21:15:39.216 INFO testing livioribeiro/cargo-readme against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 21:15:39.218 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:15:39.749 INFO [stdout] 411951687c512345226fc1e33f15a397f1369b06f3d7e1e7aa07a67c6ee54b9a
Nov 16 21:15:39.752 INFO running `"docker" "start" "-a" "411951687c512345226fc1e33f15a397f1369b06f3d7e1e7aa07a67c6ee54b9a"`
Nov 16 21:15:41.307 INFO [stderr] usermod: no changes
Nov 16 21:15:41.479 INFO [stderr]    Compiling cargo-readme v3.1.2 (/source)
Nov 16 21:15:50.904 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.57s
Nov 16 21:15:50.913 INFO [stderr] su: No module specific data is present
Nov 16 21:15:51.781 INFO running `"docker" "inspect" "411951687c512345226fc1e33f15a397f1369b06f3d7e1e7aa07a67c6ee54b9a"`
Nov 16 21:15:52.028 INFO running `"docker" "rm" "-f" "411951687c512345226fc1e33f15a397f1369b06f3d7e1e7aa07a67c6ee54b9a"`
Nov 16 21:15:52.315 INFO [stdout] 411951687c512345226fc1e33f15a397f1369b06f3d7e1e7aa07a67c6ee54b9a
Nov 16 21:15:52.323 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:15:52.809 INFO [stdout] 8e5f94a7487c4d9209427649e82de4df3038351ba1d097e8e88be35d4d6010d6
Nov 16 21:15:52.812 INFO running `"docker" "start" "-a" "8e5f94a7487c4d9209427649e82de4df3038351ba1d097e8e88be35d4d6010d6"`
Nov 16 21:15:55.181 INFO [stderr] usermod: no changes
Nov 16 21:15:55.430 INFO [stderr]    Compiling serde_json v1.0.27
Nov 16 21:15:55.463 INFO [stderr]    Compiling failure v0.1.2
Nov 16 21:16:00.351 INFO [stderr]    Compiling assert_cli v0.6.3
Nov 16 21:16:03.103 INFO [stderr]    Compiling cargo-readme v3.1.2 (/source)
Nov 16 21:16:17.028 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.81s
Nov 16 21:16:17.043 INFO [stderr] su: No module specific data is present
Nov 16 21:16:18.203 INFO running `"docker" "inspect" "8e5f94a7487c4d9209427649e82de4df3038351ba1d097e8e88be35d4d6010d6"`
Nov 16 21:16:18.535 INFO running `"docker" "rm" "-f" "8e5f94a7487c4d9209427649e82de4df3038351ba1d097e8e88be35d4d6010d6"`
Nov 16 21:16:18.859 INFO [stdout] 8e5f94a7487c4d9209427649e82de4df3038351ba1d097e8e88be35d4d6010d6
Nov 16 21:16:18.869 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 21:16:19.460 INFO [stdout] fbafa716da1a758ee6f0cf29a2ef5e18a4c1676bcbca11658c52bffcd95a4eb0
Nov 16 21:16:19.463 INFO running `"docker" "start" "-a" "fbafa716da1a758ee6f0cf29a2ef5e18a4c1676bcbca11658c52bffcd95a4eb0"`
Nov 16 21:16:20.502 INFO [stderr] usermod: no changes
Nov 16 21:16:20.631 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Nov 16 21:16:20.652 INFO [stderr]      Running /target/debug/deps/cargo_readme-f43dd0ee3488fee8
Nov 16 21:16:20.663 INFO [stdout] 
Nov 16 21:16:20.663 INFO [stdout] running 42 tests
Nov 16 21:16:20.663 INFO [stdout] test readme::extract::tests::extract_docs_mix_styles_multiline ... ok
Nov 16 21:16:20.663 INFO [stdout] test readme::extract::tests::extract_docs_mix_styles_singleline ... ok
Nov 16 21:16:20.663 INFO [stdout] test readme::extract::tests::extract_docs_multiline_style ... ok
Nov 16 21:16:20.663 INFO [stdout] test readme::extract::tests::extract_docs_nested_level_1 ... ok
Nov 16 21:16:20.667 INFO [stdout] test readme::extract::tests::extract_docs_nested_level_2 ... ok
Nov 16 21:16:20.667 INFO [stdout] test readme::extract::tests::extract_docs_singleline_style ... ok
Nov 16 21:16:20.667 INFO [stdout] test readme::process::tests::alternate_delimiter_4_tildes ... ok
Nov 16 21:16:20.667 INFO [stdout] test readme::process::tests::alternate_delimiter_4_backticks_nested ... ok
Nov 16 21:16:20.671 INFO [stdout] test readme::process::tests::alternate_delimiter_3_tildes ... ok
Nov 16 21:16:20.671 INFO [stdout] test readme::process::tests::hide_line_in_rust_code_block ... ok
Nov 16 21:16:20.671 INFO [stdout] test readme::process::tests::alternate_delimiter_4_backticks ... ok
Nov 16 21:16:20.671 INFO [stdout] test readme::process::tests::transform_text_block ... ok
Nov 16 21:16:20.671 INFO [stdout] test readme::template::tests::append_license_with_empty_readme ... ok
Nov 16 21:16:20.679 INFO [stdout] test readme::template::tests::append_license_with_filled_readme ... ok
Nov 16 21:16:20.679 INFO [stdout] test readme::template::tests::prepend_badges_with_empty_readme_and_empty_badges ... ok
Nov 16 21:16:20.684 INFO [stdout] test readme::template::tests::prepend_badges_with_empty_readme_and_non_empty_badges ... ok
Nov 16 21:16:20.688 INFO [stdout] test readme::process::tests::transform_rust_code_block ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::prepend_badges_with_filled_readme_and_empty_badges ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::process::tests::do_not_hide_line_in_code_block ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::process::tests::alternate_delimiter_mixed ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::render_badges ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::process::tests::transform_other_code_block_with_symbols ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::prepend_title_with_filled_readme ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::render_full ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::render_license ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::prepend_badges_with_filled_readme_and_non_empty_badges ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::prepend_title_with_empty_readme ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::render_title ... ok
Nov 16 21:16:20.689 INFO [stdout] test readme::template::tests::render_minimal ... ok
Nov 16 21:16:20.690 INFO [stdout] test readme::template::tests::render_nothing ... ok
Nov 16 21:16:20.690 INFO [stdout] test readme::process::tests::indent_markdown_headings ... ok
Nov 16 21:16:20.690 INFO [stdout] test readme::template::tests::template_with_license_tag_but_missing_license_should_fail ... ok
Nov 16 21:16:20.690 INFO [stdout] test readme::template::tests::template_with_badges ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_minimal ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_with_badge_tag_but_missing_badges_should_fail ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_with_license ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_full ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::process::tests::transform_rust_code_block_with_prefix ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::process::tests::do_not_indent_markdown_headings ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_without_readme_should_fail ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_with_title ... ok
Nov 16 21:16:20.692 INFO [stdout] test readme::template::tests::template_with_version ... ok
Nov 16 21:16:20.692 INFO [stdout] 
Nov 16 21:16:20.692 INFO [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:20.693 INFO [stdout] 
Nov 16 21:16:20.700 INFO [stderr]      Running /target/debug/deps/cargo_readme-f5edbd0a6070129d
Nov 16 21:16:20.703 INFO [stderr]      Running /target/debug/deps/alternate_input-9d7fbdf45e4ee163
Nov 16 21:16:20.703 INFO [stdout] 
Nov 16 21:16:20.703 INFO [stdout] running 0 tests
Nov 16 21:16:20.703 INFO [stdout] 
Nov 16 21:16:20.703 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:20.704 INFO [stdout] 
Nov 16 21:16:20.704 INFO [stdout] 
Nov 16 21:16:20.704 INFO [stdout] running 3 tests
Nov 16 21:16:20.891 INFO [stdout] test alternate_input_a_little_bit_longer ... ok
Nov 16 21:16:20.911 INFO [stdout] test alternate_input_empty_docs ... ok
Nov 16 21:16:20.981 INFO [stdout] test alternate_input_single_line ... ok
Nov 16 21:16:20.981 INFO [stderr]      Running /target/debug/deps/alternate_template-0c943393f36a213e
Nov 16 21:16:20.982 INFO [stdout] 
Nov 16 21:16:20.982 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:20.982 INFO [stdout] 
Nov 16 21:16:20.987 INFO [stdout] 
Nov 16 21:16:20.987 INFO [stdout] running 1 test
Nov 16 21:16:21.151 INFO [stdout] test alternate_template ... ok
Nov 16 21:16:21.151 INFO [stderr]      Running /target/debug/deps/append_license-85ed7ebaf1982fcc
Nov 16 21:16:21.151 INFO [stdout] 
Nov 16 21:16:21.151 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.151 INFO [stdout] 
Nov 16 21:16:21.151 INFO [stdout] 
Nov 16 21:16:21.151 INFO [stdout] running 2 tests
Nov 16 21:16:21.364 INFO [stdout] test no_append_license ... ok
Nov 16 21:16:21.395 INFO [stderr]      Running /target/debug/deps/badges-cb76377c01f0c2e1
Nov 16 21:16:21.395 INFO [stdout] test append_license ... ok
Nov 16 21:16:21.395 INFO [stdout] 
Nov 16 21:16:21.395 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.395 INFO [stdout] 
Nov 16 21:16:21.395 INFO [stdout] 
Nov 16 21:16:21.395 INFO [stdout] running 1 test
Nov 16 21:16:21.509 INFO [stdout] test badges ... ok
Nov 16 21:16:21.510 INFO [stdout] 
Nov 16 21:16:21.510 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.510 INFO [stdout] 
Nov 16 21:16:21.511 INFO [stderr]      Running /target/debug/deps/default_behavior-c317a8100bdec024
Nov 16 21:16:21.512 INFO [stdout] 
Nov 16 21:16:21.512 INFO [stdout] running 1 test
Nov 16 21:16:21.633 INFO [stdout] test default_behavior ... ok
Nov 16 21:16:21.633 INFO [stderr]      Running /target/debug/deps/entrypoint_resolution-63c3c176d58f6a68
Nov 16 21:16:21.633 INFO [stdout] 
Nov 16 21:16:21.633 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.633 INFO [stdout] 
Nov 16 21:16:21.636 INFO [stdout] 
Nov 16 21:16:21.636 INFO [stdout] running 4 tests
Nov 16 21:16:21.757 INFO [stdout] test entrypoint_resolution_cargo_bin ... ok
Nov 16 21:16:21.803 INFO [stdout] test entrypoint_resolution_main ... ok
Nov 16 21:16:21.827 INFO [stdout] test entrypoint_resolution_lib ... ok
Nov 16 21:16:21.855 INFO [stdout] test entrypoint_resolution_cargo_lib ... ok
Nov 16 21:16:21.855 INFO [stdout] 
Nov 16 21:16:21.855 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.855 INFO [stdout] 
Nov 16 21:16:21.856 INFO [stderr]      Running /target/debug/deps/multiline_doc-52e42c2838590d03
Nov 16 21:16:21.858 INFO [stdout] 
Nov 16 21:16:21.858 INFO [stdout] running 1 test
Nov 16 21:16:21.966 INFO [stdout] test multiline_doc ... ok
Nov 16 21:16:21.966 INFO [stdout] 
Nov 16 21:16:21.966 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:21.966 INFO [stdout] 
Nov 16 21:16:21.969 INFO [stdout] 
Nov 16 21:16:21.970 INFO [stderr]      Running /target/debug/deps/multiple_bin_fail-0cfff10035cdadfc
Nov 16 21:16:21.970 INFO [stdout] running 1 test
Nov 16 21:16:22.062 INFO [stdout] test multiple_bin_fail ... ok
Nov 16 21:16:22.063 INFO [stdout] 
Nov 16 21:16:22.063 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:22.063 INFO [stdout] 
Nov 16 21:16:22.065 INFO [stderr]      Running /target/debug/deps/no_entrypoint_fail-e0c960c660a03d3d
Nov 16 21:16:22.067 INFO [stdout] 
Nov 16 21:16:22.068 INFO [stdout] running 1 test
Nov 16 21:16:22.151 INFO [stdout] test no_entrypoint_fail ... ok
Nov 16 21:16:22.151 INFO [stdout] 
Nov 16 21:16:22.151 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:22.151 INFO [stdout] 
Nov 16 21:16:22.151 INFO [stderr]      Running /target/debug/deps/no_template-814cab6686912b63
Nov 16 21:16:22.153 INFO [stdout] 
Nov 16 21:16:22.153 INFO [stdout] running 1 test
Nov 16 21:16:22.255 INFO [stdout] test no_template ... ok
Nov 16 21:16:22.255 INFO [stdout] 
Nov 16 21:16:22.255 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:22.255 INFO [stdout] 
Nov 16 21:16:22.256 INFO [stderr]      Running /target/debug/deps/project_with_version-53d2dcb1a9aab2c6
Nov 16 21:16:22.258 INFO [stdout] 
Nov 16 21:16:22.258 INFO [stdout] running 1 test
Nov 16 21:16:22.338 INFO [stdout] test template_with_version ... ok
Nov 16 21:16:22.338 INFO [stdout] 
Nov 16 21:16:22.338 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:16:22.338 INFO [stdout] 
Nov 16 21:16:22.339 INFO [stderr]    Doc-tests cargo-readme
Nov 16 21:16:23.007 INFO [stdout] 
Nov 16 21:16:23.007 INFO [stdout] running 2 tests
Nov 16 21:16:23.008 INFO [stdout] test src/lib.rs -  (line 32) ... ignored
Nov 16 21:16:23.008 INFO [stdout] test src/lib.rs -  (line 77) ... ignored
Nov 16 21:16:23.008 INFO [stdout] 
Nov 16 21:16:23.008 INFO [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Nov 16 21:16:23.008 INFO [stdout] 
Nov 16 21:16:23.017 INFO [stderr] su: No module specific data is present
Nov 16 21:16:23.288 INFO running `"docker" "inspect" "fbafa716da1a758ee6f0cf29a2ef5e18a4c1676bcbca11658c52bffcd95a4eb0"`
Nov 16 21:16:23.400 INFO running `"docker" "rm" "-f" "fbafa716da1a758ee6f0cf29a2ef5e18a4c1676bcbca11658c52bffcd95a4eb0"`
Nov 16 21:16:23.510 INFO [stdout] fbafa716da1a758ee6f0cf29a2ef5e18a4c1676bcbca11658c52bffcd95a4eb0