Dec 05 03:38:45.782 INFO testing tshlabs/staccato against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 05 03:38:45.782 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 03:38:46.226 INFO [stdout] b12459e2fc197790eefc20db898a42c5a24781df8633a031235b048ad1be6e20
Dec 05 03:38:46.232 INFO running `"docker" "start" "-a" "b12459e2fc197790eefc20db898a42c5a24781df8633a031235b048ad1be6e20"`
Dec 05 03:38:47.703 INFO [stderr] usermod: no changes
Dec 05 03:38:47.865 INFO [stderr]    Compiling atty v0.2.11
Dec 05 03:38:48.196 INFO [stderr]    Compiling clap v2.32.0
Dec 05 03:39:03.504 INFO [stderr]    Compiling staccato v0.1.9 (/source)
Dec 05 03:39:06.999 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.25s
Dec 05 03:39:07.009 INFO [stderr] su: No module specific data is present
Dec 05 03:39:07.904 INFO running `"docker" "inspect" "b12459e2fc197790eefc20db898a42c5a24781df8633a031235b048ad1be6e20"`
Dec 05 03:39:08.180 INFO running `"docker" "rm" "-f" "b12459e2fc197790eefc20db898a42c5a24781df8633a031235b048ad1be6e20"`
Dec 05 03:39:08.476 INFO [stdout] b12459e2fc197790eefc20db898a42c5a24781df8633a031235b048ad1be6e20
Dec 05 03:39:08.480 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 03:39:09.104 INFO [stdout] fd70dd730ff210aedeba4c1a4d32f040686c01f9d634e0a43c559780cb885b9c
Dec 05 03:39:09.106 INFO running `"docker" "start" "-a" "fd70dd730ff210aedeba4c1a4d32f040686c01f9d634e0a43c559780cb885b9c"`
Dec 05 03:39:09.689 INFO [stderr] usermod: no changes
Dec 05 03:39:09.744 INFO [stderr]    Compiling staccato v0.1.9 (/source)
Dec 05 03:39:12.541 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.83s
Dec 05 03:39:12.546 INFO [stderr] su: No module specific data is present
Dec 05 03:39:13.432 INFO running `"docker" "inspect" "fd70dd730ff210aedeba4c1a4d32f040686c01f9d634e0a43c559780cb885b9c"`
Dec 05 03:39:13.506 INFO running `"docker" "rm" "-f" "fd70dd730ff210aedeba4c1a4d32f040686c01f9d634e0a43c559780cb885b9c"`
Dec 05 03:39:13.569 INFO [stdout] fd70dd730ff210aedeba4c1a4d32f040686c01f9d634e0a43c559780cb885b9c
Dec 05 03:39:13.570 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 03:39:13.728 INFO [stdout] 0c6165d53d1cc399ae555a69740e99808fb338c4ed8a94051a74d1b0acb80995
Dec 05 03:39:13.730 INFO running `"docker" "start" "-a" "0c6165d53d1cc399ae555a69740e99808fb338c4ed8a94051a74d1b0acb80995"`
Dec 05 03:39:14.158 INFO [stderr] usermod: no changes
Dec 05 03:39:14.258 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 05 03:39:14.277 INFO [stderr]      Running /target/debug/deps/staccato-6e409125848193b5
Dec 05 03:39:14.278 INFO [stdout] 
Dec 05 03:39:14.278 INFO [stdout] running 29 tests
Dec 05 03:39:14.286 INFO [stdout] test tests::test_get_values_filter_invalids ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_get_values_ordered ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_get_values_unordered ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_key_value_sep_from_str ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_key_value_sep_display ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_key_value_sep_get_sep ... ok
Dec 05 03:39:14.286 INFO [stdout] test tests::test_get_values_trim_whitespace ... ok
Dec 05 03:39:14.287 INFO [stdout] test tests::test_statistics_50_values_stddev ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_50_values_upper ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_empty_values ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_50_values_sum ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_50_values_median ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_lower ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_mean ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_median ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_count ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_stddev ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_sum ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_full_values_upper ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_single_value_count ... ok
Dec 05 03:39:14.288 INFO [stdout] test tests::test_statistics_single_value_lower ... ok
Dec 05 03:39:14.290 INFO [stdout] test tests::test_statistics_50_values_mean ... ok
Dec 05 03:39:14.290 INFO [stdout] test tests::test_statistics_50_values_lower ... ok
Dec 05 03:39:14.290 INFO [stdout] test tests::test_statistics_50_values_count ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_statistics_single_value_median ... ok
Dec 05 03:39:14.304 INFO [stderr]      Running /target/debug/deps/st-198d705d47a62142
Dec 05 03:39:14.304 INFO [stdout] test tests::test_statistics_single_value_sum ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_statistics_single_value_upper ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_statistics_single_value_stddev ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_statistics_single_value_mean ... ok
Dec 05 03:39:14.304 INFO [stdout] 
Dec 05 03:39:14.304 INFO [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 03:39:14.304 INFO [stdout] 
Dec 05 03:39:14.304 INFO [stdout] 
Dec 05 03:39:14.304 INFO [stdout] running 10 tests
Dec 05 03:39:14.304 INFO [stdout] test tests::test_validate_percents_err_not_in_range ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_validate_percents_lower_bound ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_parse_percents_matches ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_validate_percents_ok ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_validate_percents_upper_bound ... ok
Dec 05 03:39:14.304 INFO [stdout] test tests::test_validate_percents_err_not_a_number ... ok
Dec 05 03:39:14.308 INFO [stdout] test tests::test_parse_percents_no_matches ... ok
Dec 05 03:39:14.309 INFO [stdout] test tests::test_parse_separator_matches_other ... ok
Dec 05 03:39:14.309 INFO [stdout] test tests::test_parse_separator_no_matches ... ok
Dec 05 03:39:14.309 INFO [stdout] test tests::test_parse_separator_matches_tab ... ok
Dec 05 03:39:14.309 INFO [stdout] 
Dec 05 03:39:14.309 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 03:39:14.309 INFO [stdout] 
Dec 05 03:39:14.309 INFO [stderr]    Doc-tests staccato
Dec 05 03:39:14.437 INFO [stdout] 
Dec 05 03:39:14.437 INFO [stdout] running 0 tests
Dec 05 03:39:14.437 INFO [stdout] 
Dec 05 03:39:14.437 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 03:39:14.437 INFO [stdout] 
Dec 05 03:39:14.443 INFO [stderr] su: No module specific data is present
Dec 05 03:39:14.812 INFO running `"docker" "inspect" "0c6165d53d1cc399ae555a69740e99808fb338c4ed8a94051a74d1b0acb80995"`
Dec 05 03:39:15.024 INFO running `"docker" "rm" "-f" "0c6165d53d1cc399ae555a69740e99808fb338c4ed8a94051a74d1b0acb80995"`
Dec 05 03:39:15.209 INFO [stdout] 0c6165d53d1cc399ae555a69740e99808fb338c4ed8a94051a74d1b0acb80995