Dec 15 22:09:38.252 INFO testing assert_cli-0.6.3 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 22:09:38.252 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 15 22:09:38.644 INFO [stdout] 4b59d868a6ee781d533787f4d3ba218afccd5d43ae40f5979ef2d7606e72a14d
Dec 15 22:09:38.649 INFO running `"docker" "start" "-a" "4b59d868a6ee781d533787f4d3ba218afccd5d43ae40f5979ef2d7606e72a14d"`
Dec 15 22:09:39.726 INFO [stderr] usermod: no changes
Dec 15 22:09:39.841 INFO [stderr]    Compiling assert_cli v0.6.3 (/source)
Dec 15 22:09:41.983 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:41.983 INFO [stderr]   --> src/errors.rs:80:33
Dec 15 22:09:41.983 INFO [stderr]    |
Dec 15 22:09:41.984 INFO [stderr] 80 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:41.984 INFO [stderr]    |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:41.984 INFO [stderr]    |
Dec 15 22:09:41.985 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 15 22:09:41.985 INFO [stderr] 
Dec 15 22:09:41.985 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:41.986 INFO [stderr]    --> src/errors.rs:125:33
Dec 15 22:09:41.986 INFO [stderr]     |
Dec 15 22:09:41.986 INFO [stderr] 125 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:41.986 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:41.986 INFO [stderr] 
Dec 15 22:09:41.990 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:41.990 INFO [stderr]    --> src/errors.rs:184:33
Dec 15 22:09:41.990 INFO [stderr]     |
Dec 15 22:09:41.991 INFO [stderr] 184 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:41.991 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:41.991 INFO [stderr] 
Dec 15 22:09:42.071 INFO [stderr] warning: use of deprecated item 'failure::Fail::causes': please use the 'iter_chain()' method instead
Dec 15 22:09:42.071 INFO [stderr]    --> src/assert.rs:441:44
Dec 15 22:09:42.071 INFO [stderr]     |
Dec 15 22:09:42.071 INFO [stderr] 441 |             panic!(Self::format_causes(err.causes()));
Dec 15 22:09:42.071 INFO [stderr]     |                                            ^^^^^^
Dec 15 22:09:42.071 INFO [stderr] 
Dec 15 22:09:42.183 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:42.183 INFO [stderr]    --> src/output.rs:539:33
Dec 15 22:09:42.183 INFO [stderr]     |
Dec 15 22:09:42.183 INFO [stderr] 539 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:42.183 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:42.183 INFO [stderr] 
Dec 15 22:09:45.528 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.76s
Dec 15 22:09:45.532 INFO [stderr] su: No module specific data is present
Dec 15 22:09:46.086 INFO running `"docker" "inspect" "4b59d868a6ee781d533787f4d3ba218afccd5d43ae40f5979ef2d7606e72a14d"`
Dec 15 22:09:46.269 INFO running `"docker" "rm" "-f" "4b59d868a6ee781d533787f4d3ba218afccd5d43ae40f5979ef2d7606e72a14d"`
Dec 15 22:09:46.478 INFO [stdout] 4b59d868a6ee781d533787f4d3ba218afccd5d43ae40f5979ef2d7606e72a14d
Dec 15 22:09:46.480 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 15 22:09:46.833 INFO [stdout] fd8fdce6427b753145e57764317c70cf00dcce37393733d47247518d6ecebaeb
Dec 15 22:09:46.836 INFO running `"docker" "start" "-a" "fd8fdce6427b753145e57764317c70cf00dcce37393733d47247518d6ecebaeb"`
Dec 15 22:09:47.364 INFO [stderr] usermod: no changes
Dec 15 22:09:47.441 INFO [stderr]    Compiling docmatic v0.1.2
Dec 15 22:09:49.144 INFO [stderr]    Compiling assert_cli v0.6.3 (/source)
Dec 15 22:09:54.091 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:54.091 INFO [stderr]   --> src/errors.rs:80:33
Dec 15 22:09:54.091 INFO [stderr]    |
Dec 15 22:09:54.091 INFO [stderr] 80 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:54.091 INFO [stderr]    |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:54.091 INFO [stderr]    |
Dec 15 22:09:54.091 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 15 22:09:54.091 INFO [stderr] 
Dec 15 22:09:54.098 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:54.099 INFO [stderr]    --> src/errors.rs:125:33
Dec 15 22:09:54.099 INFO [stderr]     |
Dec 15 22:09:54.099 INFO [stderr] 125 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:54.099 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:54.099 INFO [stderr] 
Dec 15 22:09:54.123 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:54.123 INFO [stderr]    --> src/errors.rs:184:33
Dec 15 22:09:54.123 INFO [stderr]     |
Dec 15 22:09:54.123 INFO [stderr] 184 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:54.123 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:54.123 INFO [stderr] 
Dec 15 22:09:54.199 INFO [stderr] warning: use of deprecated item 'failure::Fail::causes': please use the 'iter_chain()' method instead
Dec 15 22:09:54.199 INFO [stderr]    --> src/assert.rs:441:44
Dec 15 22:09:54.199 INFO [stderr]     |
Dec 15 22:09:54.199 INFO [stderr] 441 |             panic!(Self::format_causes(err.causes()));
Dec 15 22:09:54.199 INFO [stderr]     |                                            ^^^^^^
Dec 15 22:09:54.199 INFO [stderr] 
Dec 15 22:09:54.451 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 15 22:09:54.451 INFO [stderr]    --> src/output.rs:539:33
Dec 15 22:09:54.451 INFO [stderr]     |
Dec 15 22:09:54.451 INFO [stderr] 539 |         self.cause.as_ref().map(failure::Error::cause)
Dec 15 22:09:54.451 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 15 22:09:54.451 INFO [stderr] 
Dec 15 22:09:56.540 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.15s
Dec 15 22:09:56.545 INFO [stderr] su: No module specific data is present
Dec 15 22:09:56.872 INFO running `"docker" "inspect" "fd8fdce6427b753145e57764317c70cf00dcce37393733d47247518d6ecebaeb"`
Dec 15 22:09:56.942 INFO running `"docker" "rm" "-f" "fd8fdce6427b753145e57764317c70cf00dcce37393733d47247518d6ecebaeb"`
Dec 15 22:09:57.037 INFO [stdout] fd8fdce6427b753145e57764317c70cf00dcce37393733d47247518d6ecebaeb
Dec 15 22:09:57.039 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 15 22:09:57.268 INFO [stdout] 16a4f23b2f8963b66ada5ce8e921a341dce3477151afb7adbde5ba39095d8e61
Dec 15 22:09:57.275 INFO running `"docker" "start" "-a" "16a4f23b2f8963b66ada5ce8e921a341dce3477151afb7adbde5ba39095d8e61"`
Dec 15 22:09:58.480 INFO [stderr] usermod: no changes
Dec 15 22:09:58.590 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 15 22:09:58.602 INFO [stderr]      Running /target/debug/deps/assert_cli-b7db4886193dedf9
Dec 15 22:09:58.604 INFO [stdout] 
Dec 15 22:09:58.604 INFO [stdout] running 19 tests
Dec 15 22:09:58.605 INFO [stdout] test assert::test::main_binary_default_uses_active_profile ... ok
Dec 15 22:09:58.606 INFO [stdout] test assert::test::in_place_mod ... ok
Dec 15 22:09:58.609 INFO [stdout] test assert::test::take_ownership ... ok
Dec 15 22:09:58.610 INFO [stdout] test assert::test::cargo_binary_default_uses_active_profile ... ok
Dec 15 22:09:58.611 INFO [stdout] test assert::test::in_place_mod3 ... ok
Dec 15 22:09:58.612 INFO [stdout] test assert::test::in_place_mod2 ... ok
Dec 15 22:09:58.613 INFO [stdout] test assert::test::empty_env ... ok
Dec 15 22:09:58.613 INFO [stdout] test assert::test::take_slice ... ok
Dec 15 22:09:58.614 INFO [stdout] test diff::tests::added_first_line_diff ... ok
Dec 15 22:09:58.614 INFO [stdout] test diff::tests::basic_diff ... ok
Dec 15 22:09:58.615 INFO [stdout] test macros::test::flatten_escaped ... ok
Dec 15 22:09:58.617 INFO [stdout] test assert::test::take_slice_of_strings ... ok
Dec 15 22:09:58.617 INFO [stdout] test diff::tests::multiline_diff ... ok
Dec 15 22:09:58.618 INFO [stdout] test macros::test::flatten_unquoted ... ok
Dec 15 22:09:58.618 INFO [stdout] test macros::test::flatten_quoted ... ok
Dec 15 22:09:58.625 INFO [stdout] test assert::test::take_string_i32 ... ok
Dec 15 22:09:58.625 INFO [stdout] test output::test_find_subsequence ... ok
Dec 15 22:09:58.625 INFO [stdout] test assert::test::take_slice_of_strs ... ok
Dec 15 22:09:58.626 INFO [stdout] test assert::test::take_vec ... ok
Dec 15 22:09:58.626 INFO [stdout] 
Dec 15 22:09:58.626 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:09:58.626 INFO [stdout] 
Dec 15 22:09:58.626 INFO [stderr]      Running /target/debug/deps/assert_fixture-3867186707f8f423
Dec 15 22:09:58.630 INFO [stdout] 
Dec 15 22:09:58.630 INFO [stdout] running 0 tests
Dec 15 22:09:58.630 INFO [stdout] 
Dec 15 22:09:58.630 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:09:58.630 INFO [stdout] 
Dec 15 22:09:58.630 INFO [stderr]      Running /target/debug/deps/cargo-717320392cfb8d36
Dec 15 22:09:58.632 INFO [stdout] 
Dec 15 22:09:58.632 INFO [stdout] running 2 tests
Dec 15 22:09:58.819 INFO [stdout] test cargo_binary ... ok
Dec 15 22:09:58.820 INFO [stdout] test main_binary ... ok
Dec 15 22:09:58.820 INFO [stdout] 
Dec 15 22:09:58.821 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:09:58.821 INFO [stdout] 
Dec 15 22:09:58.822 INFO [stderr]      Running /target/debug/deps/docmatic-ed509f08d73b9936
Dec 15 22:09:58.832 INFO [stdout] 
Dec 15 22:09:58.832 INFO [stdout] running 1 test
Dec 15 22:09:58.887 INFO [stdout] 
Dec 15 22:09:58.887 INFO [stdout] running 0 tests
Dec 15 22:09:58.887 INFO [stdout] 
Dec 15 22:09:58.887 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:09:58.887 INFO [stdout] 
Dec 15 22:09:58.889 INFO [stdout] test test_readme ... ok
Dec 15 22:09:58.889 INFO [stdout] 
Dec 15 22:09:58.889 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:09:58.889 INFO [stdout] 
Dec 15 22:09:58.894 INFO [stderr]    Doc-tests assert_cli
Dec 15 22:10:01.775 INFO [stdout] 
Dec 15 22:10:01.775 INFO [stdout] running 32 tests
Dec 15 22:10:27.619 INFO [stdout] test src/assert.rs - assert::Assert::execute (line 351) ... ok
Dec 15 22:10:28.403 INFO [stdout] test src/assert.rs - assert::Assert::stderr (line 331) ... ok
Dec 15 22:10:28.419 INFO [stdout] test src/assert.rs - assert::Assert::fails_with (line 270) ... ok
Dec 15 22:10:29.158 INFO [stdout] test src/assert.rs - assert::Assert::and (line 215) ... ok
Dec 15 22:10:30.671 INFO [stdout] test src/assert.rs - assert::Assert::command (line 110) ... ok
Dec 15 22:10:31.199 INFO [stdout] test src/assert.rs - assert::Assert::ignore_status (line 292) ... ok
Dec 15 22:10:31.259 INFO [stdout] test src/assert.rs - assert::Assert::current_dir (line 162) ... ok
Dec 15 22:10:31.527 INFO [stdout] test src/assert.rs - assert::Assert::fails (line 252) ... ok
Dec 15 22:10:52.763 INFO [stdout] test src/assert.rs - assert::Assert::stdout (line 313) ... ok
Dec 15 22:10:54.582 INFO [stdout] test src/assert.rs - assert::Assert::with_args (line 127) ... ok
Dec 15 22:10:55.131 INFO [stdout] test src/assert.rs - assert::Assert::with_env (line 180) ... ok
Dec 15 22:10:56.323 INFO [stdout] test src/assert.rs - assert::Assert::unwrap (line 432) ... ok
Dec 15 22:10:56.455 INFO [stdout] test src/assert.rs - assert::Assert::succeeds (line 232) ... ok
Dec 15 22:10:57.314 INFO [stdout] test src/assert.rs - assert::Assert::stdin (line 145) ... ok
Dec 15 22:10:58.516 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::contains (line 467) ... ok
Dec 15 22:11:00.587 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::doesnt_contain (line 501) ... ok
Dec 15 22:11:16.007 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::is (line 484) ... ok
Dec 15 22:11:17.344 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::isnt (line 518) ... ok
Dec 15 22:11:20.047 INFO [stdout] test src/lib.rs -  (line 27) ... ok
Dec 15 22:11:21.584 INFO [stdout] test src/lib.rs -  (line 19) ... ok
Dec 15 22:11:22.767 INFO [stdout] test src/lib.rs -  (line 110) ... ok
Dec 15 22:11:24.853 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::satisfies (line 535) ... ok
Dec 15 22:11:27.521 INFO [stdout] test src/lib.rs -  (line 45) ... ok
Dec 15 22:11:28.087 INFO [stdout] test src/lib.rs -  (line 65) ... ok
Dec 15 22:11:41.819 INFO [stdout] test src/lib.rs -  (line 9) ... ok
Dec 15 22:11:43.667 INFO [stdout] test src/output.rs - output::Output::doesnt_contain (line 236) ... ok
Dec 15 22:11:43.687 INFO [stdout] test src/output.rs - output::Output::contains (line 196) ... ok
Dec 15 22:11:43.795 INFO [stdout] test src/lib.rs -  (line 87) ... ok
Dec 15 22:11:43.875 INFO [stdout] test src/macros.rs - assert_cmd (line 20) ... ok
Dec 15 22:11:45.299 INFO [stdout] test src/output.rs - output::Output::is (line 216) ... ok
Dec 15 22:11:45.671 INFO [stdout] test src/output.rs - output::Output::satisfies (line 276) ... ok
Dec 15 22:11:45.719 INFO [stdout] test src/output.rs - output::Output::isnt (line 256) ... ok
Dec 15 22:11:45.719 INFO [stdout] 
Dec 15 22:11:45.719 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:11:45.719 INFO [stdout] 
Dec 15 22:11:45.751 INFO [stderr] su: No module specific data is present
Dec 15 22:11:46.022 INFO running `"docker" "inspect" "16a4f23b2f8963b66ada5ce8e921a341dce3477151afb7adbde5ba39095d8e61"`
Dec 15 22:11:46.098 INFO running `"docker" "rm" "-f" "16a4f23b2f8963b66ada5ce8e921a341dce3477151afb7adbde5ba39095d8e61"`
Dec 15 22:11:46.237 INFO [stdout] 16a4f23b2f8963b66ada5ce8e921a341dce3477151afb7adbde5ba39095d8e61