Dec 05 01:01:18.359 INFO testing yarrow/setop against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 05 01:01:18.359 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:18.896 INFO [stdout] 28471d80b9f5e00d65f3f574658fff9d0e658e88bb2ed214c849499d2cb0abf1
Dec 05 01:01:18.899 INFO running `"docker" "start" "-a" "28471d80b9f5e00d65f3f574658fff9d0e658e88bb2ed214c849499d2cb0abf1"`
Dec 05 01:01:20.368 INFO [stderr] usermod: no changes
Dec 05 01:01:20.588 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 05 01:01:20.588 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 05 01:01:20.628 INFO [stderr]    Compiling term_size v0.3.1
Dec 05 01:01:20.664 INFO [stderr]    Compiling backtrace v0.3.9
Dec 05 01:01:21.124 INFO [stderr]    Compiling textwrap v0.10.0
Dec 05 01:01:23.452 INFO [stderr]    Compiling clap v2.32.0
Dec 05 01:01:26.524 INFO [stderr]    Compiling quote v0.6.8
Dec 05 01:01:27.559 INFO [stderr]    Compiling syn v0.14.9
Dec 05 01:01:46.612 INFO [stderr]    Compiling synstructure v0.9.0
Dec 05 01:01:46.612 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 05 01:01:51.096 INFO [stderr]    Compiling structopt v0.2.10
Dec 05 01:01:51.964 INFO [stderr]    Compiling failure v0.1.2
Dec 05 01:01:52.599 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 05 01:01:55.516 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.13s
Dec 05 01:01:55.525 INFO [stderr] su: No module specific data is present
Dec 05 01:01:56.126 INFO running `"docker" "inspect" "28471d80b9f5e00d65f3f574658fff9d0e658e88bb2ed214c849499d2cb0abf1"`
Dec 05 01:01:56.422 INFO running `"docker" "rm" "-f" "28471d80b9f5e00d65f3f574658fff9d0e658e88bb2ed214c849499d2cb0abf1"`
Dec 05 01:01:56.769 INFO [stdout] 28471d80b9f5e00d65f3f574658fff9d0e658e88bb2ed214c849499d2cb0abf1
Dec 05 01:01:56.775 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:01:57.224 INFO [stdout] b2b9a0748538340552b145e1afbb8af4e66a19e7bd7ff0f9d031dd962aaa7c55
Dec 05 01:01:57.232 INFO running `"docker" "start" "-a" "b2b9a0748538340552b145e1afbb8af4e66a19e7bd7ff0f9d031dd962aaa7c55"`
Dec 05 01:01:59.136 INFO [stderr] usermod: no changes
Dec 05 01:01:59.405 INFO [stderr]    Compiling serde v1.0.79
Dec 05 01:01:59.405 INFO [stderr]    Compiling same-file v1.0.3
Dec 05 01:01:59.407 INFO [stderr]    Compiling escargot v0.3.1
Dec 05 01:01:59.407 INFO [stderr]    Compiling treeline v0.1.0
Dec 05 01:01:59.407 INFO [stderr]    Compiling difference v2.0.0
Dec 05 01:01:59.407 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 05 01:01:59.409 INFO [stderr]    Compiling tempfile v3.0.4
Dec 05 01:01:59.410 INFO [stderr]    Compiling float-cmp v0.4.0
Dec 05 01:02:00.928 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Dec 05 01:02:03.393 INFO [stderr]    Compiling syn v0.15.7
Dec 05 01:02:03.907 INFO [stderr]    Compiling parking_lot_core v0.3.1
Dec 05 01:02:03.960 INFO [stderr]    Compiling walkdir v2.2.5
Dec 05 01:02:04.012 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 05 01:02:04.044 INFO [stderr]    Compiling predicates v1.0.0
Dec 05 01:02:04.044 INFO [stderr]    Compiling globset v0.4.2
Dec 05 01:02:04.984 INFO [stderr]    Compiling predicates v0.5.2
Dec 05 01:02:11.990 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 05 01:02:15.118 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 05 01:02:17.137 INFO [stderr]    Compiling ignore v0.4.4
Dec 05 01:02:27.010 INFO [stderr]    Compiling globwalk v0.3.1
Dec 05 01:02:27.119 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 05 01:02:27.728 INFO [stderr]    Compiling assert_fs v0.2.1
Dec 05 01:02:54.941 INFO [stderr]    Compiling serde_json v1.0.31
Dec 05 01:03:01.569 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 05 01:03:02.919 INFO [stderr]    Compiling setop v0.1.0 (/source)
Dec 05 01:03:09.054 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 09s
Dec 05 01:03:09.069 INFO [stderr] su: No module specific data is present
Dec 05 01:03:10.808 INFO running `"docker" "inspect" "b2b9a0748538340552b145e1afbb8af4e66a19e7bd7ff0f9d031dd962aaa7c55"`
Dec 05 01:03:11.192 INFO running `"docker" "rm" "-f" "b2b9a0748538340552b145e1afbb8af4e66a19e7bd7ff0f9d031dd962aaa7c55"`
Dec 05 01:03:11.528 INFO [stdout] b2b9a0748538340552b145e1afbb8af4e66a19e7bd7ff0f9d031dd962aaa7c55
Dec 05 01:03:11.530 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:12.050 INFO [stdout] 5ac68613629a49607804502f7fdab23900b4fa261961a97974910096c1d17199
Dec 05 01:03:12.052 INFO running `"docker" "start" "-a" "5ac68613629a49607804502f7fdab23900b4fa261961a97974910096c1d17199"`
Dec 05 01:03:14.355 INFO [stderr] usermod: no changes
Dec 05 01:03:14.702 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.32s
Dec 05 01:03:14.714 INFO [stderr]      Running /target/debug/deps/setop-500cbfc956f13d4e
Dec 05 01:03:14.717 INFO [stdout] 
Dec 05 01:03:14.717 INFO [stdout] running 2 tests
Dec 05 01:03:14.721 INFO [stdout] test test::results_for_each_operation ... ok
Dec 05 01:03:14.721 INFO [stdout] test test::given_a_single_argument_all_ops_but_multiple_return_its_lines_in_order_without_dups ... ok
Dec 05 01:03:14.721 INFO [stdout] 
Dec 05 01:03:14.721 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:03:14.721 INFO [stdout] 
Dec 05 01:03:14.724 INFO [stderr]      Running /target/debug/deps/setop-6a241689249b8ef2
Dec 05 01:03:14.730 INFO [stdout] 
Dec 05 01:03:14.730 INFO [stdout] running 0 tests
Dec 05 01:03:14.730 INFO [stdout] 
Dec 05 01:03:14.730 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:03:14.730 INFO [stdout] 
Dec 05 01:03:14.730 INFO [stderr]      Running /target/debug/deps/features-643d1d2c22db3a72
Dec 05 01:03:14.733 INFO [stdout] 
Dec 05 01:03:14.733 INFO [stdout] running 8 tests
Dec 05 01:03:14.733 INFO [stdout] test union_output_is_the_concatentated_input_lines_in_order_with_no_duplicates ... ok
Dec 05 01:03:14.735 INFO [stdout] test output_is_subsequence_of_union_output_for_all_subcommands ... ok
Dec 05 01:03:14.744 INFO [stdout] test each_line_occurs_at_most_once_in_the_output_of_any_subcommand ... ok
Dec 05 01:04:14.733 INFO [stdout] test fail_on_missing_file ... test fail_on_missing_file has been running for over 60 seconds
Dec 05 01:04:14.733 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:14.734 INFO [stdout] test requires_subcommand ... test requires_subcommand has been running for over 60 seconds
Dec 05 01:04:14.734 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:14.734 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:19.961 INFO [stdout] test requires_subcommand ... ok
Dec 05 01:04:21.685 INFO [stdout] test single_argument_just_prints_the_unique_lines_for_all_but_multiple ... ok
Dec 05 01:04:21.792 INFO [stdout] test subcommands_allow_empty_arg_list_and_produce_empty_output ... ok
Dec 05 01:04:21.843 INFO [stdout] test setop_subcommand_x_y_z_matches_expected_output_for_all_subcommands ... ok
Dec 05 01:04:21.947 INFO [stdout] test fail_on_missing_file ... ok
Dec 05 01:04:21.947 INFO [stdout] 
Dec 05 01:04:21.947 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:04:21.947 INFO [stdout] 
Dec 05 01:04:21.949 INFO [stderr]    Doc-tests setop
Dec 05 01:04:22.483 INFO [stdout] 
Dec 05 01:04:22.483 INFO [stdout] running 1 test
Dec 05 01:04:22.905 INFO [stdout] test src/sio.rs - sio::ContentsIter (line 19) ... ok
Dec 05 01:04:22.905 INFO [stdout] 
Dec 05 01:04:22.905 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 01:04:22.905 INFO [stdout] 
Dec 05 01:04:22.924 INFO [stderr] su: No module specific data is present
Dec 05 01:04:24.341 INFO running `"docker" "inspect" "5ac68613629a49607804502f7fdab23900b4fa261961a97974910096c1d17199"`
Dec 05 01:04:24.716 INFO running `"docker" "rm" "-f" "5ac68613629a49607804502f7fdab23900b4fa261961a97974910096c1d17199"`
Dec 05 01:04:25.204 INFO [stdout] 5ac68613629a49607804502f7fdab23900b4fa261961a97974910096c1d17199