Dec 12 20:31:15.972 INFO testing yarrow/setop against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 12 20:31:15.973 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12 20:31:16.465 INFO [stdout] f0c467d5743e35e95919b32cfd688d06e8ec65a1d2fe217239865fca4b645a46
Dec 12 20:31:16.469 INFO running `"docker" "start" "-a" "f0c467d5743e35e95919b32cfd688d06e8ec65a1d2fe217239865fca4b645a46"`
Dec 12 20:31:18.610 INFO [stderr] usermod: no changes
Dec 12 20:31:18.803 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 12 20:31:18.811 INFO [stderr]    Compiling term_size v0.3.1
Dec 12 20:31:19.207 INFO [stderr]    Compiling textwrap v0.10.0
Dec 12 20:31:20.944 INFO [stderr]    Compiling clap v2.32.0
Dec 12 20:31:23.544 INFO [stderr]    Compiling quote v0.6.8
Dec 12 20:31:24.730 INFO [stderr]    Compiling syn v0.14.9
Dec 12 20:31:41.660 INFO [stderr]    Compiling synstructure v0.9.0
Dec 12 20:31:41.660 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 12 20:31:45.071 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 12 20:31:48.491 INFO [stderr]    Compiling structopt v0.2.10
Dec 12 20:31:48.867 INFO [stderr]    Compiling failure v0.1.2
Dec 12 20:31:49.879 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 12 20:31:53.759 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.13s
Dec 12 20:31:53.767 INFO [stderr] su: No module specific data is present
Dec 12 20:31:55.098 INFO running `"docker" "inspect" "f0c467d5743e35e95919b32cfd688d06e8ec65a1d2fe217239865fca4b645a46"`
Dec 12 20:31:55.391 INFO running `"docker" "rm" "-f" "f0c467d5743e35e95919b32cfd688d06e8ec65a1d2fe217239865fca4b645a46"`
Dec 12 20:31:55.819 INFO [stdout] f0c467d5743e35e95919b32cfd688d06e8ec65a1d2fe217239865fca4b645a46
Dec 12 20:31:55.821 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12 20:31:56.465 INFO [stdout] ca9ee8f84457be70bcff89882873dc8cac46377263db24c49dfd4327c1f0f58e
Dec 12 20:31:56.467 INFO running `"docker" "start" "-a" "ca9ee8f84457be70bcff89882873dc8cac46377263db24c49dfd4327c1f0f58e"`
Dec 12 20:31:58.740 INFO [stderr] usermod: no changes
Dec 12 20:31:59.059 INFO [stderr]    Compiling rand_core v0.2.1
Dec 12 20:31:59.063 INFO [stderr]    Compiling serde v1.0.79
Dec 12 20:31:59.067 INFO [stderr]    Compiling num-traits v0.2.6
Dec 12 20:31:59.108 INFO [stderr]    Compiling escargot v0.3.1
Dec 12 20:31:59.110 INFO [stderr]    Compiling treeline v0.1.0
Dec 12 20:31:59.111 INFO [stderr]    Compiling remove_dir_all v0.5.1
Dec 12 20:31:59.183 INFO [stderr]    Compiling difference v2.0.0
Dec 12 20:31:59.183 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 12 20:31:59.888 INFO [stderr]    Compiling itertools v0.7.8
Dec 12 20:32:03.291 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 12 20:32:03.319 INFO [stderr]    Compiling rand v0.5.5
Dec 12 20:32:03.333 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Dec 12 20:32:03.343 INFO [stderr]    Compiling syn v0.15.7
Dec 12 20:32:03.403 INFO [stderr]    Compiling globset v0.4.2
Dec 12 20:32:03.531 INFO [stderr]    Compiling predicates v1.0.0
Dec 12 20:32:11.180 INFO [stderr]    Compiling float-cmp v0.4.0
Dec 12 20:32:12.604 INFO [stderr]    Compiling predicates v0.5.2
Dec 12 20:32:14.507 INFO [stderr]    Compiling parking_lot_core v0.3.1
Dec 12 20:32:14.507 INFO [stderr]    Compiling tempfile v3.0.4
Dec 12 20:32:17.071 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 12 20:32:18.755 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 12 20:32:22.377 INFO [stderr]    Compiling ignore v0.4.4
Dec 12 20:32:25.611 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 12 20:32:31.403 INFO [stderr]    Compiling globwalk v0.3.1
Dec 12 20:32:32.371 INFO [stderr]    Compiling assert_fs v0.2.1
Dec 12 20:32:56.289 INFO [stderr]    Compiling serde_json v1.0.31
Dec 12 20:33:02.721 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 12 20:33:04.149 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 12 20:33:10.384 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 11s
Dec 12 20:33:10.399 INFO [stderr] su: No module specific data is present
Dec 12 20:33:12.791 INFO running `"docker" "inspect" "ca9ee8f84457be70bcff89882873dc8cac46377263db24c49dfd4327c1f0f58e"`
Dec 12 20:33:13.207 INFO running `"docker" "rm" "-f" "ca9ee8f84457be70bcff89882873dc8cac46377263db24c49dfd4327c1f0f58e"`
Dec 12 20:33:13.623 INFO [stdout] ca9ee8f84457be70bcff89882873dc8cac46377263db24c49dfd4327c1f0f58e
Dec 12 20:33:13.628 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12 20:33:14.138 INFO [stdout] 59beecf23719951ced194847bfef70515d8e1350b5d472b888e07041355f5442
Dec 12 20:33:14.140 INFO running `"docker" "start" "-a" "59beecf23719951ced194847bfef70515d8e1350b5d472b888e07041355f5442"`
Dec 12 20:33:16.403 INFO [stderr] usermod: no changes
Dec 12 20:33:16.655 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Dec 12 20:33:16.689 INFO [stderr]      Running /target/debug/deps/setop-060bbaafec9a8eab
Dec 12 20:33:16.705 INFO [stdout] 
Dec 12 20:33:16.705 INFO [stdout] running 2 tests
Dec 12 20:33:16.705 INFO [stdout] test test::results_for_each_operation ... ok
Dec 12 20:33:16.705 INFO [stdout] test test::given_a_single_argument_all_ops_but_multiple_return_its_lines_in_order_without_dups ... ok
Dec 12 20:33:16.705 INFO [stdout] 
Dec 12 20:33:16.705 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:33:16.705 INFO [stdout] 
Dec 12 20:33:16.711 INFO [stderr]      Running /target/debug/deps/setop-42159d2890ccad0c
Dec 12 20:33:16.724 INFO [stdout] 
Dec 12 20:33:16.724 INFO [stdout] running 0 tests
Dec 12 20:33:16.724 INFO [stdout] 
Dec 12 20:33:16.724 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:33:16.725 INFO [stdout] 
Dec 12 20:33:16.725 INFO [stderr]      Running /target/debug/deps/features-7b71330c3fa282fa
Dec 12 20:33:16.727 INFO [stdout] 
Dec 12 20:33:16.727 INFO [stdout] running 8 tests
Dec 12 20:33:16.727 INFO [stdout] test each_line_occurs_at_most_once_in_the_output_of_any_subcommand ... ok
Dec 12 20:33:16.771 INFO [stdout] test union_output_is_the_concatentated_input_lines_in_order_with_no_duplicates ... ok
Dec 12 20:33:16.775 INFO [stdout] test output_is_subsequence_of_union_output_for_all_subcommands ... ok
Dec 12 20:34:16.731 INFO [stdout] test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands ... test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands has been running for over 60 seconds
Dec 12 20:34:16.731 INFO [stdout] test single_argument_just_prints_the_unique_lines_for_all_but_multiple ... test single_argument_just_prints_the_unique_lines_for_all_but_multiple has been running for over 60 seconds
Dec 12 20:34:16.731 INFO [stdout] test requires_subcommand ... test requires_subcommand has been running for over 60 seconds
Dec 12 20:34:16.731 INFO [stdout] test subcommands_allow_empty_arg_list_and_produce_empty_output ... test subcommands_allow_empty_arg_list_and_produce_empty_output has been running for over 60 seconds
Dec 12 20:34:16.731 INFO [stdout] test fail_on_missing_file ... test fail_on_missing_file has been running for over 60 seconds
Dec 12 20:34:23.898 INFO [stdout] test requires_subcommand ... ok
Dec 12 20:34:25.795 INFO [stdout] test single_argument_just_prints_the_unique_lines_for_all_but_multiple ... ok
Dec 12 20:34:25.923 INFO [stdout] test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands ... ok
Dec 12 20:34:25.999 INFO [stdout] test fail_on_missing_file ... ok
Dec 12 20:34:26.031 INFO [stdout] test subcommands_allow_empty_arg_list_and_produce_empty_output ... ok
Dec 12 20:34:26.031 INFO [stdout] 
Dec 12 20:34:26.031 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:34:26.031 INFO [stdout] 
Dec 12 20:34:26.031 INFO [stderr]    Doc-tests setop
Dec 12 20:34:26.561 INFO [stdout] 
Dec 12 20:34:26.561 INFO [stdout] running 1 test
Dec 12 20:34:26.954 INFO [stdout] test src/sio.rs - sio::ContentsIter (line 19) ... ok
Dec 12 20:34:26.954 INFO [stdout] 
Dec 12 20:34:26.954 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:34:26.954 INFO [stdout] 
Dec 12 20:34:27.003 INFO [stderr] su: No module specific data is present
Dec 12 20:34:29.578 INFO running `"docker" "inspect" "59beecf23719951ced194847bfef70515d8e1350b5d472b888e07041355f5442"`
Dec 12 20:34:29.915 INFO running `"docker" "rm" "-f" "59beecf23719951ced194847bfef70515d8e1350b5d472b888e07041355f5442"`
Dec 12 20:34:30.231 INFO [stdout] 59beecf23719951ced194847bfef70515d8e1350b5d472b888e07041355f5442