Dec 15 13:04:47.172 INFO testing gong-1.3.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 13:04:47.172 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15 13:04:47.665 INFO [stdout] d84642bc07c3d732451e8af836e5e37e78226fcdfb24347c7dc16cf08f74c82e
Dec 15 13:04:47.667 INFO running `"docker" "start" "-a" "d84642bc07c3d732451e8af836e5e37e78226fcdfb24347c7dc16cf08f74c82e"`
Dec 15 13:04:48.717 INFO [stderr] usermod: no changes
Dec 15 13:04:48.754 INFO [stderr]    Compiling gong v1.3.1 (/source)
Dec 15 13:04:50.597 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.85s
Dec 15 13:04:50.599 INFO [stderr] su: No module specific data is present
Dec 15 13:04:51.244 INFO running `"docker" "inspect" "d84642bc07c3d732451e8af836e5e37e78226fcdfb24347c7dc16cf08f74c82e"`
Dec 15 13:04:51.589 INFO running `"docker" "rm" "-f" "d84642bc07c3d732451e8af836e5e37e78226fcdfb24347c7dc16cf08f74c82e"`
Dec 15 13:04:51.958 INFO [stdout] d84642bc07c3d732451e8af836e5e37e78226fcdfb24347c7dc16cf08f74c82e
Dec 15 13:04:51.965 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15 13:04:52.431 INFO [stdout] b59eb6287cad1b5779c2d769da66a58ae6df3cbff665a9e7a631fb63aec67cc8
Dec 15 13:04:52.435 INFO running `"docker" "start" "-a" "b59eb6287cad1b5779c2d769da66a58ae6df3cbff665a9e7a631fb63aec67cc8"`
Dec 15 13:04:53.410 INFO [stderr] usermod: no changes
Dec 15 13:04:53.520 INFO [stderr]    Compiling gong v1.3.1 (/source)
Dec 15 13:04:58.779 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.31s
Dec 15 13:04:58.779 INFO [stderr] su: No module specific data is present
Dec 15 13:04:59.277 INFO running `"docker" "inspect" "b59eb6287cad1b5779c2d769da66a58ae6df3cbff665a9e7a631fb63aec67cc8"`
Dec 15 13:04:59.565 INFO running `"docker" "rm" "-f" "b59eb6287cad1b5779c2d769da66a58ae6df3cbff665a9e7a631fb63aec67cc8"`
Dec 15 13:04:59.845 INFO [stdout] b59eb6287cad1b5779c2d769da66a58ae6df3cbff665a9e7a631fb63aec67cc8
Dec 15 13:04:59.845 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15 13:05:00.343 INFO [stdout] 68320a5c12918947d292d3738cfb2b46ace07967ea0847d95193d7276a1cd9ed
Dec 15 13:05:00.343 INFO running `"docker" "start" "-a" "68320a5c12918947d292d3738cfb2b46ace07967ea0847d95193d7276a1cd9ed"`
Dec 15 13:05:01.687 INFO [stderr] usermod: no changes
Dec 15 13:05:01.737 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 15 13:05:01.739 INFO [stderr]      Running /target/debug/deps/gong-efe49b2231f558c6
Dec 15 13:05:01.739 INFO [stdout] 
Dec 15 13:05:01.739 INFO [stdout] running 3 tests
Dec 15 13:05:01.839 INFO [stdout] test options::tests::create_long_no_name ... ok
Dec 15 13:05:01.839 INFO [stdout] test options::tests::create_long_with_equals ... ok
Dec 15 13:05:01.850 INFO [stdout] test options::tests::create_short_dash ... ok
Dec 15 13:05:01.850 INFO [stderr]      Running /target/debug/deps/macros-3f0816c91128c911
Dec 15 13:05:01.850 INFO [stdout] 
Dec 15 13:05:01.850 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:01.850 INFO [stdout] 
Dec 15 13:05:01.850 INFO [stdout] 
Dec 15 13:05:01.850 INFO [stdout] running 4 tests
Dec 15 13:05:01.855 INFO [stdout] test available_options::cmp_method_built ... ok
Dec 15 13:05:01.855 INFO [stdout] test available_options::cmp_hand_built ... ok
Dec 15 13:05:01.855 INFO [stdout] test available_options::cmp_non_fixed ... ok
Dec 15 13:05:01.859 INFO [stdout] test available_options::modes ... ok
Dec 15 13:05:01.859 INFO [stdout] 
Dec 15 13:05:01.859 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:01.859 INFO [stdout] 
Dec 15 13:05:01.863 INFO [stdout] 
Dec 15 13:05:01.863 INFO [stderr]      Running /target/debug/deps/options-4eb2d563248f0d7e
Dec 15 13:05:01.863 INFO [stdout] running 18 tests
Dec 15 13:05:01.863 INFO [stdout] test basic ... ok
Dec 15 13:05:01.875 INFO [stdout] test long_equals::bypassed_processing ... ok
Dec 15 13:05:01.875 INFO [stdout] test long_equals::add_long_existing ... ok
Dec 15 13:05:01.883 INFO [stdout] test long_no_name::add_long_existing ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_equals::add_long_data ... ok
Dec 15 13:05:01.935 INFO [stdout] test set_types ... ok
Dec 15 13:05:01.935 INFO [stdout] test duplicates::short ... ok
Dec 15 13:05:01.935 INFO [stdout] test duplicates::long ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_no_name::add_long ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_equals::invalid_set ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_equals::add_long ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_no_name::invalid_set ... ok
Dec 15 13:05:01.935 INFO [stdout] test long_no_name::add_long_data ... ok
Dec 15 13:05:01.935 INFO [stdout] test short_dash::invalid_set ... ok
Dec 15 13:05:01.936 INFO [stdout] test short_dash::bypassed_processing ... ok
Dec 15 13:05:01.936 INFO [stdout] test short_dash::add_short ... ok
Dec 15 13:05:01.951 INFO [stdout] test short_dash::add_short_data ... ok
Dec 15 13:05:01.951 INFO [stderr]      Running /target/debug/deps/processing-1cb2512c4d29b8eb
Dec 15 13:05:01.951 INFO [stdout] test short_dash::add_short_existing ... ok
Dec 15 13:05:01.951 INFO [stdout] 
Dec 15 13:05:01.951 INFO [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:01.951 INFO [stdout] 
Dec 15 13:05:01.951 INFO [stdout] 
Dec 15 13:05:01.951 INFO [stdout] running 31 tests
Dec 15 13:05:01.951 INFO [stdout] test abbreviations::ambiguous ... ok
Dec 15 13:05:01.951 INFO [stdout] test abbreviations::disabled ... ok
Dec 15 13:05:01.951 INFO [stdout] test abbreviations::exact_override ... ok
Dec 15 13:05:01.951 INFO [stdout] test alt_mode::basic ... ok
Dec 15 13:05:01.951 INFO [stdout] test alt_mode::data_looking_like_early_term ... ok
Dec 15 13:05:01.956 INFO [stdout] test alt_mode::data_basic ... ok
Dec 15 13:05:01.956 INFO [stdout] test arg_list_owned_set ... ok
Dec 15 13:05:01.956 INFO [stdout] test abbreviations::unambiguous ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::containing_equals ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::arg_placement_long ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::arg_placement_short_calc ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::arg_placement_short_next ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::arg_placement_short_same ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::looking_like_early_term ... ok
Dec 15 13:05:01.956 INFO [stdout] test data::looking_like_options ... ok
Dec 15 13:05:01.961 INFO [stdout] test basic ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::missing_long ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::missing_short ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::multibyte_long ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::misc ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::repetition ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::multibyte_short ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::multibyte_utf8combi_short ... ok
Dec 15 13:05:01.961 INFO [stdout] test data::same_arg_empty ... ok
Dec 15 13:05:01.961 INFO [stdout] test utf8::test4 ... ok
Dec 15 13:05:01.961 INFO [stdout] test utf8::test1 ... ok
Dec 15 13:05:01.961 INFO [stdout] test repetition ... ok
Dec 15 13:05:01.963 INFO [stdout] test long_no_name ... ok
Dec 15 13:05:01.963 INFO [stdout] test early_term ... ok
Dec 15 13:05:01.970 INFO [stdout] test utf8::test2 ... ok
Dec 15 13:05:01.970 INFO [stdout] test utf8::test3 ... ok
Dec 15 13:05:01.970 INFO [stdout] 
Dec 15 13:05:01.970 INFO [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:01.970 INFO [stdout] 
Dec 15 13:05:01.975 INFO [stderr]      Running /target/debug/deps/testenv-4bc77bf150789004
Dec 15 13:05:01.991 INFO [stdout] 
Dec 15 13:05:01.991 INFO [stdout] running 4 tests
Dec 15 13:05:01.991 INFO [stdout] test check_constants ... ok
Dec 15 13:05:01.991 INFO [stdout] test base_set::is_valid_altmode ... ok
Dec 15 13:05:01.991 INFO [stdout] test base_set::is_valid ... ok
Dec 15 13:05:02.043 INFO [stdout] test base_set::can_break ... ok
Dec 15 13:05:02.043 INFO [stdout] 
Dec 15 13:05:02.043 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:02.043 INFO [stdout] 
Dec 15 13:05:02.045 INFO [stderr]    Doc-tests gong
Dec 15 13:05:03.167 INFO [stdout] 
Dec 15 13:05:03.167 INFO [stdout] running 8 tests
Dec 15 13:05:07.737 INFO [stdout] test src/docs/usage.rs - docs::usage (line 19) ... ok
Dec 15 13:05:07.747 INFO [stdout] test src/docs/usage.rs - docs::usage (line 59) ... ok
Dec 15 13:05:07.804 INFO [stdout] test src/docs/usage.rs - docs::usage (line 142) ... ok
Dec 15 13:05:08.227 INFO [stdout] test src/macros.rs - gong_option_set (line 24) ... ok
Dec 15 13:05:08.363 INFO [stdout] test src/docs/usage.rs - docs::usage (line 42) ... ok
Dec 15 13:05:08.635 INFO [stdout] test src/macros.rs - gong_option_set_fixed (line 62) ... ok
Dec 15 13:05:09.299 INFO [stdout] test src/docs/usage.rs - docs::usage (line 120) ... ok
Dec 15 13:05:09.387 INFO [stdout] test src/docs/usage.rs - docs::usage (line 129) ... ok
Dec 15 13:05:09.387 INFO [stdout] 
Dec 15 13:05:09.387 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 13:05:09.387 INFO [stdout] 
Dec 15 13:05:09.417 INFO [stderr] su: No module specific data is present
Dec 15 13:05:10.135 INFO running `"docker" "inspect" "68320a5c12918947d292d3738cfb2b46ace07967ea0847d95193d7276a1cd9ed"`
Dec 15 13:05:10.435 INFO running `"docker" "rm" "-f" "68320a5c12918947d292d3738cfb2b46ace07967ea0847d95193d7276a1cd9ed"`
Dec 15 13:05:10.723 INFO [stdout] 68320a5c12918947d292d3738cfb2b46ace07967ea0847d95193d7276a1cd9ed