Dec 13 17:09:24.302 INFO testing iKevinY/ultra against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 17:09:24.303 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:09:24.827 INFO [stdout] 5552bc73350a87cd6b061452c4fb4d0b90540d3f54923cf351dd9acc65c7947f
Dec 13 17:09:24.832 INFO running `"docker" "start" "-a" "5552bc73350a87cd6b061452c4fb4d0b90540d3f54923cf351dd9acc65c7947f"`
Dec 13 17:09:26.171 INFO [stderr] usermod: no changes
Dec 13 17:09:26.287 INFO [stderr]    Compiling either v1.4.0
Dec 13 17:09:26.287 INFO [stderr]    Compiling rayon-core v1.3.0
Dec 13 17:09:26.287 INFO [stderr]    Compiling scopeguard v0.3.3
Dec 13 17:09:26.287 INFO [stderr]    Compiling num-traits v0.2.0
Dec 13 17:09:26.295 INFO [stderr]    Compiling bitflags v1.0.1
Dec 13 17:09:26.295 INFO [stderr]    Compiling vec_map v0.8.0
Dec 13 17:09:26.295 INFO [stderr]    Compiling ansi_term v0.10.2
Dec 13 17:09:26.295 INFO [stderr]    Compiling lazy_static v1.0.0
Dec 13 17:09:28.015 INFO [stderr]    Compiling rand v0.4.2
Dec 13 17:09:28.327 INFO [stderr]    Compiling num_cpus v1.8.0
Dec 13 17:09:28.611 INFO [stderr]    Compiling atty v0.2.6
Dec 13 17:09:29.487 INFO [stderr]    Compiling textwrap v0.9.0
Dec 13 17:09:29.539 INFO [stderr]    Compiling unreachable v0.1.1
Dec 13 17:09:29.569 INFO [stderr]    Compiling coco v0.1.1
Dec 13 17:09:31.267 INFO [stderr]    Compiling itertools v0.7.6
Dec 13 17:09:38.891 INFO [stderr]    Compiling num-traits v0.1.43
Dec 13 17:09:41.475 INFO [stderr]    Compiling ordered-float v0.5.0
Dec 13 17:09:42.272 INFO [stderr]    Compiling clap v2.29.4
Dec 13 17:09:43.167 INFO [stderr]    Compiling rand v0.3.22
Dec 13 17:09:49.830 INFO [stderr]    Compiling rayon v0.9.0
Dec 13 17:10:37.499 INFO [stderr]    Compiling ultra v0.5.0 (/source)
Dec 13 17:10:38.539 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Dec 13 17:10:38.539 INFO [stderr]  --> src/enigma.rs:1:5
Dec 13 17:10:38.539 INFO [stderr]   |
Dec 13 17:10:38.539 INFO [stderr] 1 | use std::ascii::AsciiExt;
Dec 13 17:10:38.539 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:38.539 INFO [stderr]   |
Dec 13 17:10:38.539 INFO [stderr]   = note: #[warn(deprecated)] on by default
Dec 13 17:10:38.539 INFO [stderr] 
Dec 13 17:10:38.639 INFO [stderr] warning: unused import: `std::ascii::AsciiExt`
Dec 13 17:10:38.640 INFO [stderr]  --> src/enigma.rs:1:5
Dec 13 17:10:38.640 INFO [stderr]   |
Dec 13 17:10:38.640 INFO [stderr] 1 | use std::ascii::AsciiExt;
Dec 13 17:10:38.640 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:38.640 INFO [stderr]   |
Dec 13 17:10:38.640 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 13 17:10:38.640 INFO [stderr] 
Dec 13 17:10:47.595 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Dec 13 17:10:47.595 INFO [stderr]  --> src/main.rs:6:5
Dec 13 17:10:47.595 INFO [stderr]   |
Dec 13 17:10:47.595 INFO [stderr] 6 | use std::ascii::AsciiExt;
Dec 13 17:10:47.595 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:47.595 INFO [stderr]   |
Dec 13 17:10:47.595 INFO [stderr]   = note: #[warn(deprecated)] on by default
Dec 13 17:10:47.595 INFO [stderr] 
Dec 13 17:10:47.639 INFO [stderr] warning: unused import: `std::ascii::AsciiExt`
Dec 13 17:10:47.639 INFO [stderr]  --> src/main.rs:6:5
Dec 13 17:10:47.639 INFO [stderr]   |
Dec 13 17:10:47.639 INFO [stderr] 6 | use std::ascii::AsciiExt;
Dec 13 17:10:47.639 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:47.639 INFO [stderr]   |
Dec 13 17:10:47.639 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 13 17:10:47.639 INFO [stderr] 
Dec 13 17:10:51.839 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 1m 25s
Dec 13 17:10:51.847 INFO [stderr] su: No module specific data is present
Dec 13 17:10:53.157 INFO running `"docker" "inspect" "5552bc73350a87cd6b061452c4fb4d0b90540d3f54923cf351dd9acc65c7947f"`
Dec 13 17:10:53.583 INFO running `"docker" "rm" "-f" "5552bc73350a87cd6b061452c4fb4d0b90540d3f54923cf351dd9acc65c7947f"`
Dec 13 17:10:53.859 INFO [stdout] 5552bc73350a87cd6b061452c4fb4d0b90540d3f54923cf351dd9acc65c7947f
Dec 13 17:10:53.865 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:10:54.455 INFO [stdout] 66056219dca585ff7ac2ad289546729c8f73541240177630d114e036167653cb
Dec 13 17:10:54.455 INFO running `"docker" "start" "-a" "66056219dca585ff7ac2ad289546729c8f73541240177630d114e036167653cb"`
Dec 13 17:10:55.895 INFO [stderr] usermod: no changes
Dec 13 17:10:56.019 INFO [stderr]    Compiling ultra v0.5.0 (/source)
Dec 13 17:10:57.943 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Dec 13 17:10:57.943 INFO [stderr]  --> src/enigma.rs:1:5
Dec 13 17:10:57.943 INFO [stderr]   |
Dec 13 17:10:57.944 INFO [stderr] 1 | use std::ascii::AsciiExt;
Dec 13 17:10:57.944 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:57.944 INFO [stderr]   |
Dec 13 17:10:57.944 INFO [stderr]   = note: #[warn(deprecated)] on by default
Dec 13 17:10:57.944 INFO [stderr] 
Dec 13 17:10:58.985 INFO [stderr] warning: unused import: `std::ascii::AsciiExt`
Dec 13 17:10:58.991 INFO [stderr]  --> src/enigma.rs:1:5
Dec 13 17:10:58.991 INFO [stderr]   |
Dec 13 17:10:58.991 INFO [stderr] 1 | use std::ascii::AsciiExt;
Dec 13 17:10:58.991 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 13 17:10:58.991 INFO [stderr]   |
Dec 13 17:10:58.991 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 13 17:10:58.991 INFO [stderr] 
Dec 13 17:11:04.374 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 8.45s
Dec 13 17:11:04.387 INFO [stderr] su: No module specific data is present
Dec 13 17:11:06.046 INFO running `"docker" "inspect" "66056219dca585ff7ac2ad289546729c8f73541240177630d114e036167653cb"`
Dec 13 17:11:06.371 INFO running `"docker" "rm" "-f" "66056219dca585ff7ac2ad289546729c8f73541240177630d114e036167653cb"`
Dec 13 17:11:06.743 INFO [stdout] 66056219dca585ff7ac2ad289546729c8f73541240177630d114e036167653cb
Dec 13 17:11:06.751 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:11:07.492 INFO [stdout] 94e67073106a25af1226f93ebf83353eef2ac126f5ebae073a3fbb981ed6b0e6
Dec 13 17:11:07.494 INFO running `"docker" "start" "-a" "94e67073106a25af1226f93ebf83353eef2ac126f5ebae073a3fbb981ed6b0e6"`
Dec 13 17:11:09.070 INFO [stderr] usermod: no changes
Dec 13 17:11:09.199 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 0.09s
Dec 13 17:11:09.215 INFO [stderr]      Running /target/debug/deps/ultra-c8697e1ce859020b
Dec 13 17:11:09.231 INFO [stdout] 
Dec 13 17:11:09.231 INFO [stdout] running 22 tests
Dec 13 17:11:09.231 INFO [stdout] test enigma::tests::repetition_period ... ok
Dec 13 17:11:09.231 INFO [stdout] test enigma::tests::ring_settings ... ok
Dec 13 17:11:09.231 INFO [stdout] test enigma::tests::key_settings ... ok
Dec 13 17:11:09.231 INFO [stdout] test enigma::tests::case_insensitive_encryption ... ok
Dec 13 17:11:09.231 INFO [stdout] test enigma::tests::symmetrical_behaviour ... ok
Dec 13 17:11:09.231 INFO [stdout] test plugboard::tests::multiple_connections ... ok
Dec 13 17:11:09.231 INFO [stdout] test plugboard::tests::no_connections ... ok
Dec 13 17:11:09.231 INFO [stdout] test plugboard::tests::single_connection ... ok
Dec 13 17:11:09.247 INFO [stdout] test reflector::tests::char_reflection ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::char_substitution ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::inverse_mapping ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::key_setting ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::matching_inverses ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::ring_setting ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::reset_rotor ... ok
Dec 13 17:11:09.247 INFO [stdout] test rotor::tests::step_inverse ... ok
Dec 13 17:11:09.255 INFO [stdout] test rotor::tests::step_rotor ... ok
Dec 13 17:11:09.343 INFO [stdout] test decrypt::tests::invalid_qgram_check ... ok
Dec 13 17:11:09.343 INFO [stdout] test rotor::tests::invalid_rotor ... ok
Dec 13 17:11:09.355 INFO [stdout] test enigma::tests::invalid_rotor_count ... ok
Dec 13 17:11:09.543 INFO [stdout] test decrypt::tests::qgram_estimates ... ok
Dec 13 17:11:09.543 INFO [stdout] test decrypt::tests::sensible_qgram_scores ... ok
Dec 13 17:11:09.543 INFO [stdout] 
Dec 13 17:11:09.543 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:11:09.543 INFO [stdout] 
Dec 13 17:11:09.563 INFO [stdout] 
Dec 13 17:11:09.563 INFO [stderr]      Running /target/debug/deps/decrypt-2530727045b9ce15
Dec 13 17:11:09.563 INFO [stdout] running 2 tests
Dec 13 17:11:46.462 INFO [stdout] test longer_decryption ... ok
Dec 13 17:11:48.088 INFO [stdout] test short_decryption ... ok
Dec 13 17:11:48.088 INFO [stdout] 
Dec 13 17:11:48.088 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:11:48.088 INFO [stdout] 
Dec 13 17:11:48.088 INFO [stdout] 
Dec 13 17:11:48.088 INFO [stdout] running 2 tests
Dec 13 17:11:48.088 INFO [stdout] test turnover_points ... ok
Dec 13 17:11:48.088 INFO [stdout] test expected_ciphertext ... ok
Dec 13 17:11:48.088 INFO [stdout] 
Dec 13 17:11:48.088 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:11:48.088 INFO [stdout] 
Dec 13 17:11:48.088 INFO [stderr]      Running /target/debug/deps/encrypt-87a05a5a8a135f65
Dec 13 17:11:48.088 INFO [stderr]    Doc-tests ultra
Dec 13 17:11:49.203 INFO [stdout] 
Dec 13 17:11:49.203 INFO [stdout] running 1 test
Dec 13 17:11:50.738 INFO [stdout] test src/enigma.rs - enigma::Enigma::new (line 29) ... ok
Dec 13 17:11:50.738 INFO [stdout] 
Dec 13 17:11:50.738 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 17:11:50.738 INFO [stdout] 
Dec 13 17:11:50.747 INFO [stderr] su: No module specific data is present
Dec 13 17:11:51.039 INFO running `"docker" "inspect" "94e67073106a25af1226f93ebf83353eef2ac126f5ebae073a3fbb981ed6b0e6"`
Dec 13 17:11:51.129 INFO running `"docker" "rm" "-f" "94e67073106a25af1226f93ebf83353eef2ac126f5ebae073a3fbb981ed6b0e6"`
Dec 13 17:11:51.210 INFO [stdout] 94e67073106a25af1226f93ebf83353eef2ac126f5ebae073a3fbb981ed6b0e6