Dec 12 20:31:19.869 INFO testing yarrow/setop against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 12 20:31:19.869 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 12 20:31:20.527 INFO [stdout] 57d0adeaf65cab1a6add87994258a622466c45a2308f4ab41f6531e3a23e0e00
Dec 12 20:31:20.529 INFO running `"docker" "start" "-a" "57d0adeaf65cab1a6add87994258a622466c45a2308f4ab41f6531e3a23e0e00"`
Dec 12 20:31:22.642 INFO [stderr] usermod: no changes
Dec 12 20:31:22.827 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 12 20:31:22.827 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 12 20:31:22.827 INFO [stderr]    Compiling cfg-if v0.1.5
Dec 12 20:31:22.827 INFO [stderr]    Compiling rustc-demangle v0.1.9
Dec 12 20:31:22.827 INFO [stderr]    Compiling backtrace-sys v0.1.24
Dec 12 20:31:22.827 INFO [stderr]    Compiling term_size v0.3.1
Dec 12 20:31:23.231 INFO [stderr]    Compiling memchr v2.1.0
Dec 12 20:31:24.199 INFO [stderr]    Compiling textwrap v0.10.0
Dec 12 20:31:27.379 INFO [stderr]    Compiling clap v2.32.0
Dec 12 20:31:28.487 INFO [stderr]    Compiling backtrace v0.3.9
Dec 12 20:31:30.080 INFO [stderr]    Compiling quote v0.6.8
Dec 12 20:31:31.087 INFO [stderr]    Compiling syn v0.14.9
Dec 12 20:31:50.119 INFO [stderr]    Compiling synstructure v0.9.0
Dec 12 20:31:50.120 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 12 20:31:57.431 INFO [stderr]    Compiling structopt v0.2.10
Dec 12 20:31:58.422 INFO [stderr]    Compiling failure v0.1.2
Dec 12 20:31:59.185 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 12 20:32:02.598 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 39.91s
Dec 12 20:32:02.609 INFO [stderr] su: No module specific data is present
Dec 12 20:32:04.326 INFO running `"docker" "inspect" "57d0adeaf65cab1a6add87994258a622466c45a2308f4ab41f6531e3a23e0e00"`
Dec 12 20:32:04.597 INFO running `"docker" "rm" "-f" "57d0adeaf65cab1a6add87994258a622466c45a2308f4ab41f6531e3a23e0e00"`
Dec 12 20:32:04.967 INFO [stdout] 57d0adeaf65cab1a6add87994258a622466c45a2308f4ab41f6531e3a23e0e00
Dec 12 20:32:04.973 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 12 20:32:05.623 INFO [stdout] c12bcd602dcafd3210104a35f8f4073a242956c731f0c4fe23bbfac8b2af3b34
Dec 12 20:32:05.625 INFO running `"docker" "start" "-a" "c12bcd602dcafd3210104a35f8f4073a242956c731f0c4fe23bbfac8b2af3b34"`
Dec 12 20:32:07.559 INFO [stderr] usermod: no changes
Dec 12 20:32:07.810 INFO [stderr]    Compiling crossbeam-utils v0.5.0
Dec 12 20:32:07.810 INFO [stderr]    Compiling ryu v0.2.6
Dec 12 20:32:07.810 INFO [stderr]    Compiling num-traits v0.2.6
Dec 12 20:32:07.810 INFO [stderr]    Compiling serde v1.0.79
Dec 12 20:32:07.810 INFO [stderr]    Compiling same-file v1.0.3
Dec 12 20:32:07.819 INFO [stderr]    Compiling escargot v0.3.1
Dec 12 20:32:07.819 INFO [stderr]    Compiling itoa v0.4.3
Dec 12 20:32:07.819 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 12 20:32:10.311 INFO [stderr]    Compiling difference v2.0.0
Dec 12 20:32:10.323 INFO [stderr]    Compiling treeline v0.1.0
Dec 12 20:32:10.983 INFO [stderr]    Compiling rand v0.5.5
Dec 12 20:32:10.983 INFO [stderr]    Compiling log v0.4.5
Dec 12 20:32:10.992 INFO [stderr]    Compiling walkdir v2.2.5
Dec 12 20:32:11.147 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 12 20:32:11.396 INFO [stderr]    Compiling aho-corasick v0.6.8
Dec 12 20:32:11.543 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Dec 12 20:32:12.227 INFO [stderr]    Compiling syn v0.15.7
Dec 12 20:32:16.274 INFO [stderr]    Compiling regex v1.0.5
Dec 12 20:32:16.435 INFO [stderr]    Compiling predicates v1.0.0
Dec 12 20:32:24.141 INFO [stderr]    Compiling parking_lot_core v0.3.1
Dec 12 20:32:24.141 INFO [stderr]    Compiling tempfile v3.0.4
Dec 12 20:32:24.883 INFO [stderr]    Compiling float-cmp v0.4.0
Dec 12 20:32:28.086 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 12 20:32:36.619 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 12 20:32:37.699 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 12 20:32:40.224 INFO [stderr]    Compiling globset v0.4.2
Dec 12 20:32:40.224 INFO [stderr]    Compiling predicates v0.5.2
Dec 12 20:32:45.723 INFO [stderr]    Compiling ignore v0.4.4
Dec 12 20:32:53.467 INFO [stderr]    Compiling globwalk v0.3.1
Dec 12 20:32:54.351 INFO [stderr]    Compiling assert_fs v0.2.1
Dec 12 20:33:16.111 INFO [stderr]    Compiling serde_json v1.0.31
Dec 12 20:33:22.688 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 12 20:33:24.139 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 12 20:33:30.038 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 22s
Dec 12 20:33:30.079 INFO [stderr] su: No module specific data is present
Dec 12 20:33:31.998 INFO running `"docker" "inspect" "c12bcd602dcafd3210104a35f8f4073a242956c731f0c4fe23bbfac8b2af3b34"`
Dec 12 20:33:32.331 INFO running `"docker" "rm" "-f" "c12bcd602dcafd3210104a35f8f4073a242956c731f0c4fe23bbfac8b2af3b34"`
Dec 12 20:33:32.583 INFO [stdout] c12bcd602dcafd3210104a35f8f4073a242956c731f0c4fe23bbfac8b2af3b34
Dec 12 20:33:32.585 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 12 20:33:33.100 INFO [stdout] 07804c2cc029651afba041b0ab504dfdf1f50874a93360b6935cd2d8d2caef64
Dec 12 20:33:33.102 INFO running `"docker" "start" "-a" "07804c2cc029651afba041b0ab504dfdf1f50874a93360b6935cd2d8d2caef64"`
Dec 12 20:33:36.276 INFO [stderr] usermod: no changes
Dec 12 20:33:36.557 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Dec 12 20:33:36.572 INFO [stderr]      Running /target/debug/deps/setop-b08674ddea80fdff
Dec 12 20:33:36.575 INFO [stdout] 
Dec 12 20:33:36.575 INFO [stdout] running 2 tests
Dec 12 20:33:36.583 INFO [stdout] test test::given_a_single_argument_all_ops_but_multiple_return_its_lines_in_order_without_dups ... ok
Dec 12 20:33:36.607 INFO [stdout] test test::results_for_each_operation ... ok
Dec 12 20:33:36.607 INFO [stdout] 
Dec 12 20:33:36.607 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:33:36.607 INFO [stdout] 
Dec 12 20:33:36.608 INFO [stderr]      Running /target/debug/deps/setop-23a39e17935dd2d5
Dec 12 20:33:36.611 INFO [stdout] 
Dec 12 20:33:36.611 INFO [stdout] running 0 tests
Dec 12 20:33:36.611 INFO [stdout] 
Dec 12 20:33:36.612 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:33:36.612 INFO [stdout] 
Dec 12 20:33:36.620 INFO [stderr]      Running /target/debug/deps/features-e03674f4ae662189
Dec 12 20:33:36.629 INFO [stdout] 
Dec 12 20:33:36.629 INFO [stdout] running 8 tests
Dec 12 20:33:36.735 INFO [stdout] test output_is_subsequence_of_union_output_for_all_subcommands ... ok
Dec 12 20:33:36.735 INFO [stdout] test each_line_occurs_at_most_once_in_the_output_of_any_subcommand ... ok
Dec 12 20:33:36.763 INFO [stdout] test union_output_is_the_concatentated_input_lines_in_order_with_no_duplicates ... ok
Dec 12 20:34:36.635 INFO [stdout] test fail_on_missing_file ... test fail_on_missing_file has been running for over 60 seconds
Dec 12 20:34:36.671 INFO [stdout] test requires_subcommand ... test requires_subcommand has been running for over 60 seconds
Dec 12 20:34:36.691 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:36.743 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:36.743 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:42.347 INFO [stdout] test requires_subcommand ... ok
Dec 12 20:34:44.339 INFO [stdout] test fail_on_missing_file ... ok
Dec 12 20:34:44.446 INFO [stdout] test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands ... ok
Dec 12 20:34:44.515 INFO [stdout] test single_argument_just_prints_the_unique_lines_for_all_but_multiple ... ok
Dec 12 20:34:44.591 INFO [stdout] test subcommands_allow_empty_arg_list_and_produce_empty_output ... ok
Dec 12 20:34:44.591 INFO [stdout] 
Dec 12 20:34:44.592 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:34:44.592 INFO [stdout] 
Dec 12 20:34:44.593 INFO [stderr]    Doc-tests setop
Dec 12 20:34:44.884 INFO [stdout] 
Dec 12 20:34:44.884 INFO [stdout] running 1 test
Dec 12 20:34:45.112 INFO [stdout] test src/sio.rs - sio::ContentsIter (line 19) ... ok
Dec 12 20:34:45.112 INFO [stdout] 
Dec 12 20:34:45.112 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 20:34:45.112 INFO [stdout] 
Dec 12 20:34:45.127 INFO [stderr] su: No module specific data is present
Dec 12 20:34:46.689 INFO running `"docker" "inspect" "07804c2cc029651afba041b0ab504dfdf1f50874a93360b6935cd2d8d2caef64"`
Dec 12 20:34:47.055 INFO running `"docker" "rm" "-f" "07804c2cc029651afba041b0ab504dfdf1f50874a93360b6935cd2d8d2caef64"`
Dec 12 20:34:47.379 INFO [stdout] 07804c2cc029651afba041b0ab504dfdf1f50874a93360b6935cd2d8d2caef64