Dec 08 00:09:22.916 INFO testing aligner-0.1.6 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 08 00:09:22.916 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 08 00:09:23.181 INFO [stdout] bb168c5b2bfde99abaa170224c1f39ddeb2eb219ee387dc6181e30a17da18a6f
Dec 08 00:09:23.186 INFO running `"docker" "start" "-a" "bb168c5b2bfde99abaa170224c1f39ddeb2eb219ee387dc6181e30a17da18a6f"`
Dec 08 00:09:23.629 INFO [stderr] usermod: no changes
Dec 08 00:09:23.780 INFO [stderr]    Compiling image v0.13.0
Dec 08 00:09:26.645 INFO [stderr]    Compiling vobsub v0.2.3
Dec 08 00:09:30.794 INFO [stderr]    Compiling subparse v0.3.0
Dec 08 00:09:38.038 INFO [stderr]    Compiling aligner v0.1.6 (/source)
Dec 08 00:09:47.048 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.40s
Dec 08 00:09:47.056 INFO [stderr] su: No module specific data is present
Dec 08 00:09:47.920 INFO running `"docker" "inspect" "bb168c5b2bfde99abaa170224c1f39ddeb2eb219ee387dc6181e30a17da18a6f"`
Dec 08 00:09:48.305 INFO running `"docker" "rm" "-f" "bb168c5b2bfde99abaa170224c1f39ddeb2eb219ee387dc6181e30a17da18a6f"`
Dec 08 00:09:48.456 INFO [stdout] bb168c5b2bfde99abaa170224c1f39ddeb2eb219ee387dc6181e30a17da18a6f
Dec 08 00:09:48.462 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 08 00:09:48.814 INFO [stdout] a1eea4881832b26dad4cd7be989842872815dd6ffc5c47c1bd1922cf78297e73
Dec 08 00:09:48.820 INFO running `"docker" "start" "-a" "a1eea4881832b26dad4cd7be989842872815dd6ffc5c47c1bd1922cf78297e73"`
Dec 08 00:09:49.478 INFO [stderr] usermod: no changes
Dec 08 00:09:49.624 INFO [stderr]    Compiling aligner v0.1.6 (/source)
Dec 08 00:09:54.965 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.45s
Dec 08 00:09:54.973 INFO [stderr] su: No module specific data is present
Dec 08 00:09:55.354 INFO running `"docker" "inspect" "a1eea4881832b26dad4cd7be989842872815dd6ffc5c47c1bd1922cf78297e73"`
Dec 08 00:09:55.442 INFO running `"docker" "rm" "-f" "a1eea4881832b26dad4cd7be989842872815dd6ffc5c47c1bd1922cf78297e73"`
Dec 08 00:09:55.592 INFO [stdout] a1eea4881832b26dad4cd7be989842872815dd6ffc5c47c1bd1922cf78297e73
Dec 08 00:09:55.594 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 08 00:09:55.792 INFO [stdout] b9da0ccc340ad441e128ba08f4cbf253854acd1cf7bd0663fe5fb622059eb8b2
Dec 08 00:09:55.797 INFO running `"docker" "start" "-a" "b9da0ccc340ad441e128ba08f4cbf253854acd1cf7bd0663fe5fb622059eb8b2"`
Dec 08 00:09:57.185 INFO [stderr] usermod: no changes
Dec 08 00:09:57.404 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 08 00:09:57.420 INFO [stderr]      Running /target/debug/deps/aligner-d121c5e8adc2d34c
Dec 08 00:09:57.420 INFO [stdout] 
Dec 08 00:09:57.420 INFO [stdout] running 7 tests
Dec 08 00:09:57.500 INFO [stdout] test internal::delta_compression::tests::combine_add ... ok
Dec 08 00:09:57.595 INFO [stdout] test internal::aligner::tests::run_aligner ... ok
Dec 08 00:09:57.613 INFO [stdout] test internal::delta_compression::tests::truncate ... ok
Dec 08 00:09:57.624 INFO [stdout] test internal::timespan_ops::tests::test_prepare_time_spans ... ok
Dec 08 00:09:58.572 INFO [stdout] test internal::utils::tests::test_get_best_segments2 ... ok
Dec 08 00:09:58.592 INFO [stdout] test internal::utils::tests::test_get_best_segments3 ... ok
Dec 08 00:09:59.767 INFO [stdout] test internal::aligner::tests::get_compressed_overlapping_ratings ... ok
Dec 08 00:09:59.767 INFO [stdout] 
Dec 08 00:09:59.767 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 08 00:09:59.767 INFO [stdout] 
Dec 08 00:09:59.768 INFO [stderr]      Running /target/debug/deps/aligner-e1302fd3e572e2f4
Dec 08 00:09:59.770 INFO [stdout] 
Dec 08 00:09:59.770 INFO [stdout] running 0 tests
Dec 08 00:09:59.770 INFO [stdout] 
Dec 08 00:09:59.770 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 08 00:09:59.770 INFO [stdout] 
Dec 08 00:09:59.771 INFO [stderr]    Doc-tests aligner
Dec 08 00:10:00.804 INFO [stdout] 
Dec 08 00:10:00.804 INFO [stdout] running 6 tests
Dec 08 00:10:03.616 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimeSpan::new (line 230) ... ok
Dec 08 00:10:03.796 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimePoint (line 142) ... ok
Dec 08 00:10:03.904 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimeSpan::new (line 216) ... ok
Dec 08 00:10:03.959 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimeSpan::contains (line 306) ... ok
Dec 08 00:10:04.018 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimeSpan::fast_distance_to (line 315) ... ok
Dec 08 00:10:04.020 INFO [stdout] test src/internal/time_types.rs - internal::time_types::TimeSpan::new_safe (line 253) ... ok
Dec 08 00:10:04.020 INFO [stdout] 
Dec 08 00:10:04.020 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 08 00:10:04.020 INFO [stdout] 
Dec 08 00:10:04.042 INFO [stderr] su: No module specific data is present
Dec 08 00:10:04.308 INFO running `"docker" "inspect" "b9da0ccc340ad441e128ba08f4cbf253854acd1cf7bd0663fe5fb622059eb8b2"`
Dec 08 00:10:04.560 INFO running `"docker" "rm" "-f" "b9da0ccc340ad441e128ba08f4cbf253854acd1cf7bd0663fe5fb622059eb8b2"`
Dec 08 00:10:04.848 INFO [stdout] b9da0ccc340ad441e128ba08f4cbf253854acd1cf7bd0663fe5fb622059eb8b2