Dec 07 23:32:09.909 INFO testing assert_cli-0.6.3 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 23:32:09.909 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 23:32:10.496 INFO [stdout] 35e965df5828309cfdbfcb4ec67a92d21898c4e8e38c2580c8938e16b5365210
Dec 07 23:32:10.498 INFO running `"docker" "start" "-a" "35e965df5828309cfdbfcb4ec67a92d21898c4e8e38c2580c8938e16b5365210"`
Dec 07 23:32:11.860 INFO [stderr] usermod: no changes
Dec 07 23:32:12.064 INFO [stderr]    Compiling assert_cli v0.6.3 (/source)
Dec 07 23:32:13.812 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:13.812 INFO [stderr]   --> src/errors.rs:80:33
Dec 07 23:32:13.812 INFO [stderr]    |
Dec 07 23:32:13.812 INFO [stderr] 80 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:13.812 INFO [stderr]    |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:13.812 INFO [stderr]    |
Dec 07 23:32:13.812 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 07 23:32:13.812 INFO [stderr] 
Dec 07 23:32:13.812 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:13.812 INFO [stderr]    --> src/errors.rs:125:33
Dec 07 23:32:13.812 INFO [stderr]     |
Dec 07 23:32:13.812 INFO [stderr] 125 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:13.812 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:13.812 INFO [stderr] 
Dec 07 23:32:13.820 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:13.820 INFO [stderr]    --> src/errors.rs:184:33
Dec 07 23:32:13.820 INFO [stderr]     |
Dec 07 23:32:13.820 INFO [stderr] 184 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:13.820 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:13.820 INFO [stderr] 
Dec 07 23:32:13.928 INFO [stderr] warning: use of deprecated item 'failure::Fail::causes': please use the 'iter_chain()' method instead
Dec 07 23:32:13.928 INFO [stderr]    --> src/assert.rs:441:44
Dec 07 23:32:13.928 INFO [stderr]     |
Dec 07 23:32:13.928 INFO [stderr] 441 |             panic!(Self::format_causes(err.causes()));
Dec 07 23:32:13.928 INFO [stderr]     |                                            ^^^^^^
Dec 07 23:32:13.928 INFO [stderr] 
Dec 07 23:32:14.064 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:14.064 INFO [stderr]    --> src/output.rs:539:33
Dec 07 23:32:14.064 INFO [stderr]     |
Dec 07 23:32:14.064 INFO [stderr] 539 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:14.064 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:14.064 INFO [stderr] 
Dec 07 23:32:17.272 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.38s
Dec 07 23:32:17.280 INFO [stderr] su: No module specific data is present
Dec 07 23:32:18.058 INFO running `"docker" "inspect" "35e965df5828309cfdbfcb4ec67a92d21898c4e8e38c2580c8938e16b5365210"`
Dec 07 23:32:18.234 INFO running `"docker" "rm" "-f" "35e965df5828309cfdbfcb4ec67a92d21898c4e8e38c2580c8938e16b5365210"`
Dec 07 23:32:18.568 INFO [stdout] 35e965df5828309cfdbfcb4ec67a92d21898c4e8e38c2580c8938e16b5365210
Dec 07 23:32:18.574 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 23:32:18.948 INFO [stdout] 92625b8d92a94e753aa1cbb45e169db4a8a149ca5e449c9f3f06d254ab2ae99c
Dec 07 23:32:18.950 INFO running `"docker" "start" "-a" "92625b8d92a94e753aa1cbb45e169db4a8a149ca5e449c9f3f06d254ab2ae99c"`
Dec 07 23:32:20.425 INFO [stderr] usermod: no changes
Dec 07 23:32:20.552 INFO [stderr]    Compiling docmatic v0.1.2
Dec 07 23:32:22.248 INFO [stderr]    Compiling assert_cli v0.6.3 (/source)
Dec 07 23:32:27.306 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:27.306 INFO [stderr]   --> src/errors.rs:80:33
Dec 07 23:32:27.306 INFO [stderr]    |
Dec 07 23:32:27.306 INFO [stderr] 80 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:27.307 INFO [stderr]    |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:27.307 INFO [stderr]    |
Dec 07 23:32:27.307 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 07 23:32:27.307 INFO [stderr] 
Dec 07 23:32:27.320 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:27.320 INFO [stderr]    --> src/errors.rs:125:33
Dec 07 23:32:27.321 INFO [stderr]     |
Dec 07 23:32:27.321 INFO [stderr] 125 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:27.321 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:27.321 INFO [stderr] 
Dec 07 23:32:27.344 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:27.344 INFO [stderr]    --> src/errors.rs:184:33
Dec 07 23:32:27.345 INFO [stderr]     |
Dec 07 23:32:27.345 INFO [stderr] 184 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:27.345 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:27.345 INFO [stderr] 
Dec 07 23:32:27.460 INFO [stderr] warning: use of deprecated item 'failure::Fail::causes': please use the 'iter_chain()' method instead
Dec 07 23:32:27.461 INFO [stderr]    --> src/assert.rs:441:44
Dec 07 23:32:27.461 INFO [stderr]     |
Dec 07 23:32:27.461 INFO [stderr] 441 |             panic!(Self::format_causes(err.causes()));
Dec 07 23:32:27.461 INFO [stderr]     |                                            ^^^^^^
Dec 07 23:32:27.461 INFO [stderr] 
Dec 07 23:32:27.785 INFO [stderr] warning: use of deprecated item 'failure::Error::cause': please use 'as_fail()' method instead
Dec 07 23:32:27.785 INFO [stderr]    --> src/output.rs:539:33
Dec 07 23:32:27.786 INFO [stderr]     |
Dec 07 23:32:27.786 INFO [stderr] 539 |         self.cause.as_ref().map(failure::Error::cause)
Dec 07 23:32:27.786 INFO [stderr]     |                                 ^^^^^^^^^^^^^^^^^^^^^
Dec 07 23:32:27.786 INFO [stderr] 
Dec 07 23:32:32.170 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.71s
Dec 07 23:32:32.183 INFO [stderr] su: No module specific data is present
Dec 07 23:32:32.978 INFO running `"docker" "inspect" "92625b8d92a94e753aa1cbb45e169db4a8a149ca5e449c9f3f06d254ab2ae99c"`
Dec 07 23:32:33.276 INFO running `"docker" "rm" "-f" "92625b8d92a94e753aa1cbb45e169db4a8a149ca5e449c9f3f06d254ab2ae99c"`
Dec 07 23:32:33.544 INFO [stdout] 92625b8d92a94e753aa1cbb45e169db4a8a149ca5e449c9f3f06d254ab2ae99c
Dec 07 23:32:33.552 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 23:32:33.980 INFO [stdout] 21aa10f08b4b4d30db0adfb6136e5564a501ec0df515b4480c8bd96b713bff05
Dec 07 23:32:33.982 INFO running `"docker" "start" "-a" "21aa10f08b4b4d30db0adfb6136e5564a501ec0df515b4480c8bd96b713bff05"`
Dec 07 23:32:36.483 INFO [stderr] usermod: no changes
Dec 07 23:32:36.647 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 07 23:32:36.663 INFO [stderr]      Running /target/debug/deps/assert_cli-fcc728ac76c64255
Dec 07 23:32:36.670 INFO [stdout] 
Dec 07 23:32:36.670 INFO [stdout] running 19 tests
Dec 07 23:32:36.671 INFO [stdout] test assert::test::main_binary_default_uses_active_profile ... ok
Dec 07 23:32:36.681 INFO [stdout] test assert::test::cargo_binary_default_uses_active_profile ... ok
Dec 07 23:32:36.681 INFO [stdout] test assert::test::in_place_mod ... ok
Dec 07 23:32:36.690 INFO [stdout] test assert::test::empty_env ... ok
Dec 07 23:32:36.690 INFO [stdout] test assert::test::take_ownership ... ok
Dec 07 23:32:36.691 INFO [stdout] test assert::test::take_string_i32 ... ok
Dec 07 23:32:36.691 INFO [stdout] test assert::test::in_place_mod3 ... ok
Dec 07 23:32:36.696 INFO [stdout] test assert::test::in_place_mod2 ... ok
Dec 07 23:32:36.697 INFO [stdout] test diff::tests::multiline_diff ... ok
Dec 07 23:32:36.698 INFO [stdout] test macros::test::flatten_escaped ... ok
Dec 07 23:32:36.698 INFO [stdout] test diff::tests::added_first_line_diff ... ok
Dec 07 23:32:36.699 INFO [stdout] test diff::tests::basic_diff ... ok
Dec 07 23:32:36.699 INFO [stdout] test assert::test::take_slice_of_strings ... ok
Dec 07 23:32:36.700 INFO [stdout] test macros::test::flatten_quoted ... ok
Dec 07 23:32:36.700 INFO [stdout] test macros::test::flatten_unquoted ... ok
Dec 07 23:32:36.700 INFO [stdout] test output::test_find_subsequence ... ok
Dec 07 23:32:36.712 INFO [stdout] test assert::test::take_slice ... ok
Dec 07 23:32:36.724 INFO [stdout] test assert::test::take_slice_of_strs ... ok
Dec 07 23:32:36.732 INFO [stdout] test assert::test::take_vec ... ok
Dec 07 23:32:36.732 INFO [stdout] 
Dec 07 23:32:36.732 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:32:36.732 INFO [stdout] 
Dec 07 23:32:36.733 INFO [stderr]      Running /target/debug/deps/assert_fixture-c939d236687c88e8
Dec 07 23:32:36.746 INFO [stdout] 
Dec 07 23:32:36.746 INFO [stdout] running 0 tests
Dec 07 23:32:36.746 INFO [stdout] 
Dec 07 23:32:36.746 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:32:36.746 INFO [stdout] 
Dec 07 23:32:36.746 INFO [stderr]      Running /target/debug/deps/cargo-0def469926ab3f14
Dec 07 23:32:36.758 INFO [stdout] 
Dec 07 23:32:36.758 INFO [stdout] running 2 tests
Dec 07 23:32:36.943 INFO [stdout] test cargo_binary ... ok
Dec 07 23:32:36.953 INFO [stdout] test main_binary ... ok
Dec 07 23:32:36.953 INFO [stdout] 
Dec 07 23:32:36.953 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:32:36.953 INFO [stdout] 
Dec 07 23:32:36.954 INFO [stderr]      Running /target/debug/deps/docmatic-00e4fd8cffafb216
Dec 07 23:32:36.957 INFO [stdout] 
Dec 07 23:32:36.958 INFO [stdout] running 1 test
Dec 07 23:32:37.029 INFO [stdout] 
Dec 07 23:32:37.029 INFO [stdout] running 0 tests
Dec 07 23:32:37.029 INFO [stdout] 
Dec 07 23:32:37.029 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:32:37.029 INFO [stdout] 
Dec 07 23:32:37.040 INFO [stdout] test test_readme ... ok
Dec 07 23:32:37.040 INFO [stdout] 
Dec 07 23:32:37.040 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:32:37.040 INFO [stdout] 
Dec 07 23:32:37.041 INFO [stderr]    Doc-tests assert_cli
Dec 07 23:32:39.103 INFO [stdout] 
Dec 07 23:32:39.103 INFO [stdout] running 32 tests
Dec 07 23:33:00.553 INFO [stdout] test src/assert.rs - assert::Assert::stderr (line 331) ... ok
Dec 07 23:33:01.015 INFO [stdout] test src/assert.rs - assert::Assert::fails (line 252) ... ok
Dec 07 23:33:01.893 INFO [stdout] test src/assert.rs - assert::Assert::ignore_status (line 292) ... ok
Dec 07 23:33:02.792 INFO [stdout] test src/assert.rs - assert::Assert::and (line 215) ... ok
Dec 07 23:33:03.213 INFO [stdout] test src/assert.rs - assert::Assert::execute (line 351) ... ok
Dec 07 23:33:03.232 INFO [stdout] test src/assert.rs - assert::Assert::command (line 110) ... ok
Dec 07 23:33:03.344 INFO [stdout] test src/assert.rs - assert::Assert::fails_with (line 270) ... ok
Dec 07 23:33:03.388 INFO [stdout] test src/assert.rs - assert::Assert::current_dir (line 162) ... ok
Dec 07 23:33:21.104 INFO [stdout] test src/assert.rs - assert::Assert::stdout (line 313) ... ok
Dec 07 23:33:22.384 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::doesnt_contain (line 501) ... ok
Dec 07 23:33:22.540 INFO [stdout] test src/assert.rs - assert::Assert::stdin (line 145) ... ok
Dec 07 23:33:22.928 INFO [stdout] test src/assert.rs - assert::Assert::unwrap (line 432) ... ok
Dec 07 23:33:23.136 INFO [stdout] test src/assert.rs - assert::Assert::succeeds (line 232) ... ok
Dec 07 23:33:23.136 INFO [stdout] test src/assert.rs - assert::Assert::with_env (line 180) ... ok
Dec 07 23:33:24.128 INFO [stdout] test src/assert.rs - assert::Assert::with_args (line 127) ... ok
Dec 07 23:33:24.862 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::contains (line 467) ... ok
Dec 07 23:33:36.888 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::isnt (line 518) ... ok
Dec 07 23:33:37.092 INFO [stdout] test src/lib.rs -  (line 19) ... ok
Dec 07 23:33:39.052 INFO [stdout] test src/lib.rs -  (line 27) ... ok
Dec 07 23:33:39.112 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::satisfies (line 535) ... ok
Dec 07 23:33:39.944 INFO [stdout] test src/assert.rs - assert::OutputAssertionBuilder::is (line 484) ... ok
Dec 07 23:33:41.448 INFO [stdout] test src/lib.rs -  (line 45) ... ok
Dec 07 23:33:42.060 INFO [stdout] test src/lib.rs -  (line 65) ... ok
Dec 07 23:33:43.972 INFO [stdout] test src/lib.rs -  (line 110) ... ok
Dec 07 23:33:51.560 INFO [stdout] test src/lib.rs -  (line 87) ... ok
Dec 07 23:33:52.220 INFO [stdout] test src/lib.rs -  (line 9) ... ok
Dec 07 23:33:54.964 INFO [stdout] test src/macros.rs - assert_cmd (line 20) ... ok
Dec 07 23:33:55.140 INFO [stdout] test src/output.rs - output::Output::doesnt_contain (line 236) ... ok
Dec 07 23:33:55.148 INFO [stdout] test src/output.rs - output::Output::is (line 216) ... ok
Dec 07 23:33:56.052 INFO [stdout] test src/output.rs - output::Output::contains (line 196) ... ok
Dec 07 23:33:57.236 INFO [stdout] test src/output.rs - output::Output::isnt (line 256) ... ok
Dec 07 23:33:57.534 INFO [stdout] test src/output.rs - output::Output::satisfies (line 276) ... ok
Dec 07 23:33:57.534 INFO [stdout] 
Dec 07 23:33:57.534 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 23:33:57.534 INFO [stdout] 
Dec 07 23:33:57.562 INFO [stderr] su: No module specific data is present
Dec 07 23:33:58.146 INFO running `"docker" "inspect" "21aa10f08b4b4d30db0adfb6136e5564a501ec0df515b4480c8bd96b713bff05"`
Dec 07 23:33:58.462 INFO running `"docker" "rm" "-f" "21aa10f08b4b4d30db0adfb6136e5564a501ec0df515b4480c8bd96b713bff05"`
Dec 07 23:33:58.796 INFO [stdout] 21aa10f08b4b4d30db0adfb6136e5564a501ec0df515b4480c8bd96b713bff05