Dec 05 01:01:24.993 INFO testing yarrow/setop against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 01:01:24.993 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05 01:01:25.440 INFO [stdout] 1ee0d275b2d7ac9d1f50ad935351aab1655c733d6edcb825cc868ec5201830ac
Dec 05 01:01:25.442 INFO running `"docker" "start" "-a" "1ee0d275b2d7ac9d1f50ad935351aab1655c733d6edcb825cc868ec5201830ac"`
Dec 05 01:01:27.180 INFO [stderr] usermod: no changes
Dec 05 01:01:27.392 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 05 01:01:27.404 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 05 01:01:27.404 INFO [stderr]    Compiling indexmap v1.0.1
Dec 05 01:01:27.408 INFO [stderr]    Compiling term_size v0.3.1
Dec 05 01:01:27.408 INFO [stderr]    Compiling memchr v2.1.0
Dec 05 01:01:27.408 INFO [stderr]    Compiling backtrace v0.3.9
Dec 05 01:01:28.350 INFO [stderr]    Compiling textwrap v0.10.0
Dec 05 01:01:31.592 INFO [stderr]    Compiling clap v2.32.0
Dec 05 01:01:34.017 INFO [stderr]    Compiling quote v0.6.8
Dec 05 01:01:35.246 INFO [stderr]    Compiling syn v0.14.9
Dec 05 01:01:50.784 INFO [stderr]    Compiling synstructure v0.9.0
Dec 05 01:01:50.784 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 05 01:01:55.252 INFO [stderr]    Compiling structopt v0.2.10
Dec 05 01:01:56.429 INFO [stderr]    Compiling failure v0.1.2
Dec 05 01:01:57.188 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 05 01:02:00.710 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 33.50s
Dec 05 01:02:00.724 INFO [stderr] su: No module specific data is present
Dec 05 01:02:02.082 INFO running `"docker" "inspect" "1ee0d275b2d7ac9d1f50ad935351aab1655c733d6edcb825cc868ec5201830ac"`
Dec 05 01:02:02.454 INFO running `"docker" "rm" "-f" "1ee0d275b2d7ac9d1f50ad935351aab1655c733d6edcb825cc868ec5201830ac"`
Dec 05 01:02:02.794 INFO [stdout] 1ee0d275b2d7ac9d1f50ad935351aab1655c733d6edcb825cc868ec5201830ac
Dec 05 01:02:02.800 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05 01:02:03.288 INFO [stdout] 81909a37b200965b3fd23705b4ff63227aa8a9cb880147d6593c54a5cbee7f64
Dec 05 01:02:03.290 INFO running `"docker" "start" "-a" "81909a37b200965b3fd23705b4ff63227aa8a9cb880147d6593c54a5cbee7f64"`
Dec 05 01:02:05.724 INFO [stderr] usermod: no changes
Dec 05 01:02:06.072 INFO [stderr]    Compiling serde v1.0.79
Dec 05 01:02:06.074 INFO [stderr]    Compiling same-file v1.0.3
Dec 05 01:02:06.081 INFO [stderr]    Compiling escargot v0.3.1
Dec 05 01:02:06.109 INFO [stderr]    Compiling difference v2.0.0
Dec 05 01:02:06.110 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 05 01:02:06.111 INFO [stderr]    Compiling treeline v0.1.0
Dec 05 01:02:06.118 INFO [stderr]    Compiling rand v0.5.5
Dec 05 01:02:06.125 INFO [stderr]    Compiling float-cmp v0.4.0
Dec 05 01:02:07.369 INFO [stderr]    Compiling aho-corasick v0.6.8
Dec 05 01:02:09.963 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Dec 05 01:02:10.034 INFO [stderr]    Compiling syn v0.15.7
Dec 05 01:02:10.046 INFO [stderr]    Compiling walkdir v2.2.5
Dec 05 01:02:10.069 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 05 01:02:10.094 INFO [stderr]    Compiling predicates v1.0.0
Dec 05 01:02:12.145 INFO [stderr]    Compiling regex v1.0.5
Dec 05 01:02:19.317 INFO [stderr]    Compiling parking_lot_core v0.3.1
Dec 05 01:02:19.317 INFO [stderr]    Compiling tempfile v3.0.4
Dec 05 01:02:23.018 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 05 01:02:31.964 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 05 01:02:35.278 INFO [stderr]    Compiling globset v0.4.2
Dec 05 01:02:35.888 INFO [stderr]    Compiling predicates v0.5.2
Dec 05 01:02:37.632 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 05 01:02:41.116 INFO [stderr]    Compiling ignore v0.4.4
Dec 05 01:02:48.526 INFO [stderr]    Compiling globwalk v0.3.1
Dec 05 01:02:49.234 INFO [stderr]    Compiling assert_fs v0.2.1
Dec 05 01:03:09.792 INFO [stderr]    Compiling serde_json v1.0.31
Dec 05 01:03:15.865 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 05 01:03:17.219 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 05 01:03:23.443 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 17s
Dec 05 01:03:23.455 INFO [stderr] su: No module specific data is present
Dec 05 01:03:25.225 INFO running `"docker" "inspect" "81909a37b200965b3fd23705b4ff63227aa8a9cb880147d6593c54a5cbee7f64"`
Dec 05 01:03:25.554 INFO running `"docker" "rm" "-f" "81909a37b200965b3fd23705b4ff63227aa8a9cb880147d6593c54a5cbee7f64"`
Dec 05 01:03:25.918 INFO [stdout] 81909a37b200965b3fd23705b4ff63227aa8a9cb880147d6593c54a5cbee7f64
Dec 05 01:03:25.922 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05 01:03:26.437 INFO [stdout] f7f52828afe92f3460898f8591446d1aa4a7b623e92ab1bcaf70c4e1e8238d09
Dec 05 01:03:26.439 INFO running `"docker" "start" "-a" "f7f52828afe92f3460898f8591446d1aa4a7b623e92ab1bcaf70c4e1e8238d09"`
Dec 05 01:03:28.604 INFO [stderr] usermod: no changes
Dec 05 01:03:28.816 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Dec 05 01:03:28.828 INFO [stderr]      Running /target/debug/deps/setop-0ab20b61099ac255
Dec 05 01:03:28.836 INFO [stdout] 
Dec 05 01:03:28.836 INFO [stdout] running 2 tests
Dec 05 01:03:28.837 INFO [stdout] test test::results_for_each_operation ... ok
Dec 05 01:03:28.837 INFO [stdout] test test::given_a_single_argument_all_ops_but_multiple_return_its_lines_in_order_without_dups ... ok
Dec 05 01:03:28.837 INFO [stdout] 
Dec 05 01:03:28.837 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:03:28.837 INFO [stdout] 
Dec 05 01:03:28.837 INFO [stderr]      Running /target/debug/deps/setop-bb3bc352eaec1d02
Dec 05 01:03:28.840 INFO [stdout] 
Dec 05 01:03:28.840 INFO [stdout] running 0 tests
Dec 05 01:03:28.840 INFO [stdout] 
Dec 05 01:03:28.840 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:03:28.840 INFO [stdout] 
Dec 05 01:03:28.841 INFO [stderr]      Running /target/debug/deps/features-a2ce8dbc124ff70c
Dec 05 01:03:28.844 INFO [stdout] 
Dec 05 01:03:28.844 INFO [stdout] running 8 tests
Dec 05 01:03:28.844 INFO [stdout] test union_output_is_the_concatentated_input_lines_in_order_with_no_duplicates ... ok
Dec 05 01:03:28.848 INFO [stdout] test output_is_subsequence_of_union_output_for_all_subcommands ... ok
Dec 05 01:03:28.848 INFO [stdout] test each_line_occurs_at_most_once_in_the_output_of_any_subcommand ... ok
Dec 05 01:04:28.852 INFO [stdout] test fail_on_missing_file ... test fail_on_missing_file has been running for over 60 seconds
Dec 05 01:04:28.852 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 05 01:04:28.852 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 05 01:04:28.852 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 05 01:04:28.852 INFO [stdout] test requires_subcommand ... test requires_subcommand has been running for over 60 seconds
Dec 05 01:04:32.844 INFO [stdout] test requires_subcommand ... ok
Dec 05 01:04:34.668 INFO [stdout] test single_argument_just_prints_the_unique_lines_for_all_but_multiple ... ok
Dec 05 01:04:34.716 INFO [stdout] test subcommands_allow_empty_arg_list_and_produce_empty_output ... ok
Dec 05 01:04:34.873 INFO [stdout] test fail_on_missing_file ... ok
Dec 05 01:04:34.904 INFO [stdout] test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands ... ok
Dec 05 01:04:34.904 INFO [stdout] 
Dec 05 01:04:34.905 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:04:34.905 INFO [stdout] 
Dec 05 01:04:34.906 INFO [stderr]    Doc-tests setop
Dec 05 01:04:35.338 INFO [stdout] 
Dec 05 01:04:35.338 INFO [stdout] running 1 test
Dec 05 01:04:35.701 INFO [stdout] test src/sio.rs - sio::ContentsIter (line 19) ... ok
Dec 05 01:04:35.701 INFO [stdout] 
Dec 05 01:04:35.701 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:04:35.701 INFO [stdout] 
Dec 05 01:04:35.724 INFO [stderr] su: No module specific data is present
Dec 05 01:04:37.192 INFO running `"docker" "inspect" "f7f52828afe92f3460898f8591446d1aa4a7b623e92ab1bcaf70c4e1e8238d09"`
Dec 05 01:04:37.567 INFO running `"docker" "rm" "-f" "f7f52828afe92f3460898f8591446d1aa4a7b623e92ab1bcaf70c4e1e8238d09"`
Dec 05 01:04:37.932 INFO [stdout] f7f52828afe92f3460898f8591446d1aa4a7b623e92ab1bcaf70c4e1e8238d09