Dec 13 17:16:11.738 INFO testing huxi/rusty_ulid against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 17:16:11.738 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 13 17:16:12.200 INFO [stdout] 71e06a506be165c49abfe7fa9f59f13e645f021f127a8d6333d2ad7e83578197
Dec 13 17:16:12.202 INFO running `"docker" "start" "-a" "71e06a506be165c49abfe7fa9f59f13e645f021f127a8d6333d2ad7e83578197"`
Dec 13 17:16:14.324 INFO [stderr] usermod: no changes
Dec 13 17:16:14.585 INFO [stderr]    Compiling chrono v0.4.6
Dec 13 17:16:17.802 INFO [stderr]    Compiling rusty_ulid v0.7.0 (/source)
Dec 13 17:16:20.600 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.24s
Dec 13 17:16:20.609 INFO [stderr] su: No module specific data is present
Dec 13 17:16:22.025 INFO running `"docker" "inspect" "71e06a506be165c49abfe7fa9f59f13e645f021f127a8d6333d2ad7e83578197"`
Dec 13 17:16:22.291 INFO running `"docker" "rm" "-f" "71e06a506be165c49abfe7fa9f59f13e645f021f127a8d6333d2ad7e83578197"`
Dec 13 17:16:22.699 INFO [stdout] 71e06a506be165c49abfe7fa9f59f13e645f021f127a8d6333d2ad7e83578197
Dec 13 17:16:22.705 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 13 17:16:23.225 INFO [stdout] 3e7370d210490b4792d276e709a8cd51b01ff404dbed5d47f00b61485b38b201
Dec 13 17:16:23.227 INFO running `"docker" "start" "-a" "3e7370d210490b4792d276e709a8cd51b01ff404dbed5d47f00b61485b38b201"`
Dec 13 17:16:24.806 INFO [stderr] usermod: no changes
Dec 13 17:16:25.151 INFO [stderr]    Compiling criterion-plot v0.2.5
Dec 13 17:16:25.155 INFO [stderr]    Compiling itertools-num v0.1.3
Dec 13 17:16:25.163 INFO [stderr]    Compiling csv-core v0.1.4
Dec 13 17:16:25.164 INFO [stderr]    Compiling criterion-stats v0.2.5
Dec 13 17:16:25.188 INFO [stderr]    Compiling simplelog v0.5.3
Dec 13 17:16:25.188 INFO [stderr]    Compiling handlebars v0.32.4
Dec 13 17:16:29.736 INFO [stderr]    Compiling csv v1.0.2
Dec 13 17:16:38.430 INFO [stderr]    Compiling criterion v0.2.5
Dec 13 17:16:46.635 INFO [stderr]    Compiling rusty_ulid v0.7.0 (/source)
Dec 13 17:16:48.481 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.64s
Dec 13 17:16:48.495 INFO [stderr] su: No module specific data is present
Dec 13 17:16:49.038 INFO running `"docker" "inspect" "3e7370d210490b4792d276e709a8cd51b01ff404dbed5d47f00b61485b38b201"`
Dec 13 17:16:49.247 INFO running `"docker" "rm" "-f" "3e7370d210490b4792d276e709a8cd51b01ff404dbed5d47f00b61485b38b201"`
Dec 13 17:16:49.503 INFO [stdout] 3e7370d210490b4792d276e709a8cd51b01ff404dbed5d47f00b61485b38b201
Dec 13 17:16:49.511 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 13 17:16:49.863 INFO [stdout] ae76331237d9e3d2a4cded954c3e04a2832601481abe1ca93797d097e1ef6add
Dec 13 17:16:49.871 INFO running `"docker" "start" "-a" "ae76331237d9e3d2a4cded954c3e04a2832601481abe1ca93797d097e1ef6add"`
Dec 13 17:16:50.731 INFO [stderr] usermod: no changes
Dec 13 17:16:50.872 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 13 17:16:50.893 INFO [stderr]      Running /target/debug/deps/rusty_ulid-2e94cf1310a9cc82
Dec 13 17:16:50.893 INFO [stdout] 
Dec 13 17:16:50.893 INFO [stdout] running 21 tests
Dec 13 17:16:50.893 INFO [stdout] test tests::eq_cmp_sanity_checks ... ok
Dec 13 17:16:50.893 INFO [stdout] test crockford::tests::parse_crockford_u64_tuple_test_cases ... ok
Dec 13 17:16:50.894 INFO [stdout] test crockford::tests::parse_crockford_u128_test_cases ... ok
Dec 13 17:16:50.894 INFO [stdout] test crockford::tests::description_returns_deprecation_message ... ok
Dec 13 17:16:50.894 INFO [stdout] test crockford::tests::decoding_error_display_trait ... ok
Dec 13 17:16:50.894 INFO [stdout] test crockford::tests::append_crockford_u128_test_cases ... ok
Dec 13 17:16:50.894 INFO [stdout] test tests::from_str_failure_too_long ... ok
Dec 13 17:16:50.894 INFO [stdout] test tests::from_str_failure_overflow ... ok
Dec 13 17:16:50.894 INFO [stdout] test tests::from_string_to_string_special_cases ... ok
Dec 13 17:16:50.894 INFO [stdout] test tests::from_str_failure_too_short ... ok
Dec 13 17:16:50.895 INFO [stdout] test tests::from_string_to_string ... ok
Dec 13 17:16:50.900 INFO [stdout] test tests::parse_quickstart ... ok
Dec 13 17:16:50.906 INFO [stdout] test tests::hash_sanity_checks ... ok
Dec 13 17:16:50.906 INFO [stdout] test tests::increment ... ok
Dec 13 17:16:50.906 INFO [stdout] test crockford::tests::decoding_error_causes ... ok
Dec 13 17:16:50.906 INFO [stdout] test tests::from_str_failure_invalid_unicode ... ok
Dec 13 17:16:50.906 INFO [stdout] test crockford::tests::append_crockford_u64_tuple_test_cases ... ok
Dec 13 17:16:50.915 INFO [stdout] test tests::fn_quickstart ... ok
Dec 13 17:16:50.915 INFO [stdout] test tests::test_from_timestamp_with_rng ... ok
Dec 13 17:16:50.915 INFO [stdout] test tests::quickstart ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::y10889_bug ... ok
Dec 13 17:16:50.999 INFO [stderr]      Running /target/debug/deps/rusty_ulid-a90e83cf4bbe2d7a
Dec 13 17:16:50.999 INFO [stdout] 
Dec 13 17:16:50.999 INFO [stderr]    Doc-tests rusty_ulid
Dec 13 17:16:50.999 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:16:50.999 INFO [stdout] 
Dec 13 17:16:50.999 INFO [stdout] 
Dec 13 17:16:50.999 INFO [stdout] running 10 tests
Dec 13 17:16:50.999 INFO [stdout] test tests::valid_verbose_values_return_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::valid_values_return_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::no_args_return_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::version_long_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::version_short_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::verbose_long_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::verbose_short_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::invalid_value_returns_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::help_short_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] test tests::help_long_returns_no_error ... ok
Dec 13 17:16:50.999 INFO [stdout] 
Dec 13 17:16:50.999 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:16:50.999 INFO [stdout] 
Dec 13 17:16:52.131 INFO [stdout] 
Dec 13 17:16:52.131 INFO [stdout] running 51 tests
Dec 13 17:16:55.551 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u64_tuple (line 344) ... ok
Dec 13 17:16:56.966 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u128 (line 154) ... ok
Dec 13 17:16:58.660 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u64_tuple (line 353) ... ok
Dec 13 17:16:59.711 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u64_tuple (line 335) ... ok
Dec 13 17:17:00.831 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u64_tuple (line 326) ... ok
Dec 13 17:17:01.340 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u128 (line 147) ... ok
Dec 13 17:17:01.531 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u128 (line 161) ... ok
Dec 13 17:17:01.619 INFO [stdout] test src/crockford.rs - crockford::append_crockford_u128 (line 168) ... ok
Dec 13 17:17:05.844 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 208) ... ok
Dec 13 17:17:05.871 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 237) ... ok
Dec 13 17:17:06.243 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 218) ... ok
Dec 13 17:17:06.327 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 255) ... ok
Dec 13 17:17:07.331 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 270) ... ok
Dec 13 17:17:07.667 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 409) ... ok
Dec 13 17:17:08.775 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u128 (line 245) ... ok
Dec 13 17:17:09.127 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 398) ... ok
Dec 13 17:17:12.202 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 436) ... ok
Dec 13 17:17:12.442 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 461) ... ok
Dec 13 17:17:13.023 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 428) ... ok
Dec 13 17:17:13.167 INFO [stdout] test src/lib.rs - (u64, u64)::from (line 734) ... ok
Dec 13 17:17:13.423 INFO [stdout] test src/crockford.rs - crockford::parse_crockford_u64_tuple (line 446) ... ok
Dec 13 17:17:15.241 INFO [stdout] test src/lib.rs - (u64, u64)::from (line 722) ... ok
Dec 13 17:17:15.739 INFO [stdout] test src/lib.rs - Ulid::from (line 576) ... ok
Dec 13 17:17:16.219 INFO [stdout] test src/lib.rs - Ulid::datetime (line 460) ... ok
Dec 13 17:17:19.984 INFO [stdout] test src/lib.rs - Ulid::from (line 703) ... ok
Dec 13 17:17:20.104 INFO [stdout] test src/lib.rs - Ulid::from (line 691) ... ok
Dec 13 17:17:20.571 INFO [stdout] test src/lib.rs - Ulid::from (line 753) ... ok
Dec 13 17:17:20.724 INFO [stdout] test src/lib.rs - Ulid::from (line 591) ... ok
Dec 13 17:17:21.927 INFO [stdout] test src/lib.rs - Ulid::from_timestamp_with_rng (line 296) ... ok
Dec 13 17:17:22.210 INFO [stdout] test src/lib.rs - Ulid::from (line 765) ... ok
Dec 13 17:17:24.123 INFO [stdout] test src/lib.rs - Ulid::generate (line 217) ... ok
Dec 13 17:17:24.335 INFO [stdout] test src/lib.rs - Ulid::increment (line 489) ... ok
Dec 13 17:17:25.555 INFO [stdout] test src/lib.rs - Ulid::increment (line 498) ... ok
Dec 13 17:17:26.637 INFO [stdout] test src/lib.rs - Ulid::next_monotonic (line 243) ... ok
Dec 13 17:17:26.924 INFO [stdout] test src/lib.rs - Ulid::increment (line 507) ... ok
Dec 13 17:17:27.099 INFO [stdout] test src/lib.rs - Ulid::next_monotonic_from_timestamp_with_rng (line 332) ... ok
Dec 13 17:17:27.495 INFO [stdout] test src/lib.rs - Ulid::next_monotonic_from_timestamp_with_rng (line 341) ... ok
Dec 13 17:17:27.547 INFO [stdout] test src/lib.rs - Ulid::next_monotonic_from_timestamp_with_rng (line 350) ... ok
Dec 13 17:17:28.926 INFO [stdout] test src/lib.rs - Ulid::next_strictly_monotonic_from_timestamp_with_rng (line 385) ... ok
Dec 13 17:17:29.519 INFO [stdout] test src/lib.rs - Ulid::next_strictly_monotonic (line 269) ... ok
Dec 13 17:17:29.615 INFO [stdout] test src/lib.rs - Ulid::next_strictly_monotonic_from_timestamp_with_rng (line 394) ... ok
Dec 13 17:17:29.975 INFO [stdout] test src/lib.rs - Ulid::next_strictly_monotonic_from_timestamp_with_rng (line 403) ... ok
Dec 13 17:17:30.380 INFO [stdout] test src/lib.rs - Ulid::timestamp (line 438) ... ok
Dec 13 17:17:30.951 INFO [stdout] test src/lib.rs - [u8; 16]::from (line 634) ... ok
Dec 13 17:17:31.109 INFO [stdout] test src/lib.rs - Ulid::to_string (line 533) ... ok
Dec 13 17:17:31.447 INFO [stdout] test src/lib.rs - Ulid::to_string (line 541) ... ok
Dec 13 17:17:32.013 INFO [stdout] test src/lib.rs - [u8; 16]::from (line 649) ... ok
Dec 13 17:17:32.125 INFO [stdout] test src/lib.rs - u128::from (line 785) ... ok
Dec 13 17:17:32.227 INFO [stdout] test src/lib.rs - generate_ulid_string (line 179) ... ok
Dec 13 17:17:32.334 INFO [stdout] test src/lib.rs - generate_ulid_bytes (line 195) ... ok
Dec 13 17:17:32.410 INFO [stdout] test src/lib.rs - u128::from (line 797) ... ok
Dec 13 17:17:32.410 INFO [stdout] 
Dec 13 17:17:32.410 INFO [stdout] test result: ok. 51 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:17:32.410 INFO [stdout] 
Dec 13 17:17:32.429 INFO [stderr] su: No module specific data is present
Dec 13 17:17:32.742 INFO running `"docker" "inspect" "ae76331237d9e3d2a4cded954c3e04a2832601481abe1ca93797d097e1ef6add"`
Dec 13 17:17:32.852 INFO running `"docker" "rm" "-f" "ae76331237d9e3d2a4cded954c3e04a2832601481abe1ca93797d097e1ef6add"`
Dec 13 17:17:32.983 INFO [stdout] ae76331237d9e3d2a4cded954c3e04a2832601481abe1ca93797d097e1ef6add