Dec 05 23:03:19.543 INFO testing gadomski/riprocess against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 23:03:19.543 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 05 23:03:20.018 INFO [stdout] 1a181fd8be78663bcc832a9e612d0d5981b59ab89f8d8b6d822ea3f62c5e89da
Dec 05 23:03:20.022 INFO running `"docker" "start" "-a" "1a181fd8be78663bcc832a9e612d0d5981b59ab89f8d8b6d822ea3f62c5e89da"`
Dec 05 23:03:21.566 INFO [stderr] usermod: no changes
Dec 05 23:03:21.716 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Dec 05 23:03:29.344 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.74s
Dec 05 23:03:29.349 INFO [stderr] su: No module specific data is present
Dec 05 23:03:30.206 INFO running `"docker" "inspect" "1a181fd8be78663bcc832a9e612d0d5981b59ab89f8d8b6d822ea3f62c5e89da"`
Dec 05 23:03:30.482 INFO running `"docker" "rm" "-f" "1a181fd8be78663bcc832a9e612d0d5981b59ab89f8d8b6d822ea3f62c5e89da"`
Dec 05 23:03:30.795 INFO [stdout] 1a181fd8be78663bcc832a9e612d0d5981b59ab89f8d8b6d822ea3f62c5e89da
Dec 05 23:03:30.800 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 05 23:03:31.303 INFO [stdout] 627c6410b83d782b60b9a172283949375563dae40a6a0ef121d794c6948dc3c9
Dec 05 23:03:31.305 INFO running `"docker" "start" "-a" "627c6410b83d782b60b9a172283949375563dae40a6a0ef121d794c6948dc3c9"`
Dec 05 23:03:33.705 INFO [stderr] usermod: no changes
Dec 05 23:03:33.841 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Dec 05 23:03:37.904 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.17s
Dec 05 23:03:37.909 INFO [stderr] su: No module specific data is present
Dec 05 23:03:38.791 INFO running `"docker" "inspect" "627c6410b83d782b60b9a172283949375563dae40a6a0ef121d794c6948dc3c9"`
Dec 05 23:03:39.082 INFO running `"docker" "rm" "-f" "627c6410b83d782b60b9a172283949375563dae40a6a0ef121d794c6948dc3c9"`
Dec 05 23:03:39.427 INFO [stdout] 627c6410b83d782b60b9a172283949375563dae40a6a0ef121d794c6948dc3c9
Dec 05 23:03:39.434 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 05 23:03:39.960 INFO [stdout] fbd101a9dc7e7bdfef2e11f3d5152a5b6c8c3885e7959dc90667828a3f0d0b85
Dec 05 23:03:39.962 INFO running `"docker" "start" "-a" "fbd101a9dc7e7bdfef2e11f3d5152a5b6c8c3885e7959dc90667828a3f0d0b85"`
Dec 05 23:03:41.764 INFO [stderr] usermod: no changes
Dec 05 23:03:41.900 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 05 23:03:41.912 INFO [stderr]      Running /target/debug/deps/riprocess-b35004a7d72957f3
Dec 05 23:03:41.916 INFO [stdout] 
Dec 05 23:03:41.916 INFO [stdout] running 20 tests
Dec 05 23:03:41.924 INFO [stdout] test image::tests::end_out_of_range ... ok
Dec 05 23:03:41.924 INFO [stdout] test image::tests::no_images ... ok
Dec 05 23:03:41.925 INFO [stdout] test image::tests::end ... ok
Dec 05 23:03:41.925 INFO [stdout] test image::tests::all_images ... ok
Dec 05 23:03:41.929 INFO [stdout] test record::tests::count_mismatch ... ok
Dec 05 23:03:41.930 INFO [stdout] test record::tests::empty_timestamps ... ok
Dec 05 23:03:41.936 INFO [stdout] test record::tests::from_reference ... ok
Dec 05 23:03:41.961 INFO [stdout] test config::tests::record_count_mismatch ... ok
Dec 05 23:03:41.969 INFO [stdout] test config::tests::no_images ... ok
Dec 05 23:03:41.974 INFO [stdout] test image::tests::start ... ok
Dec 05 23:03:41.974 INFO [stdout] test image::tests::start_out_of_range ... ok
Dec 05 23:03:41.983 INFO [stdout] test timestamp::tests::end ... ok
Dec 05 23:03:41.983 INFO [stdout] test timestamp::tests::no_paths ... ok
Dec 05 23:03:41.983 INFO [stdout] test timestamp::tests::start ... ok
Dec 05 23:03:41.983 INFO [stdout] test config::tests::image_count_mismatch ... ok
Dec 05 23:03:41.986 INFO [stdout] test config::tests::image_list ... ok
Dec 05 23:03:41.988 INFO [stdout] test timestamp::tests::all_paths ... ok
Dec 05 23:03:41.991 INFO [stdout] test timestamp::tests::timestamps ... ok
Dec 05 23:03:41.993 INFO [stdout] test timestamp::tests::invalid_start ... ok
Dec 05 23:03:41.993 INFO [stdout] test timestamp::tests::invalid_end ... ok
Dec 05 23:03:41.993 INFO [stdout] 
Dec 05 23:03:41.993 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 23:03:41.993 INFO [stdout] 
Dec 05 23:03:42.003 INFO [stderr]    Doc-tests riprocess
Dec 05 23:03:43.120 INFO [stdout] 
Dec 05 23:03:43.120 INFO [stdout] running 10 tests
Dec 05 23:03:46.297 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 20) ... ok
Dec 05 23:03:50.948 INFO [stdout] test src/image.rs - image::Config::new (line 35) ... ok
Dec 05 23:03:53.734 INFO [stdout] test src/config.rs - config::Config::new (line 62) ... ok
Dec 05 23:03:54.445 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 39) ... ok
Dec 05 23:03:55.736 INFO [stdout] test src/image.rs - image::Config::paths (line 50) ... ok
Dec 05 23:03:56.253 INFO [stdout] test src/timestamp.rs - timestamp::Config::paths (line 34) ... ok
Dec 05 23:03:56.272 INFO [stdout] test src/config.rs - config::Config (line 10) ... ok
Dec 05 23:03:56.736 INFO [stdout] test src/config.rs - config::Config::from_path (line 44) ... ok
Dec 05 23:03:56.884 INFO [stdout] test src/config.rs - config::Config::image_list (line 77) ... ok
Dec 05 23:03:57.730 INFO [stdout] test src/timestamp.rs - timestamp::Config::timestamps (line 82) ... ok
Dec 05 23:03:57.730 INFO [stdout] 
Dec 05 23:03:57.730 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 23:03:57.730 INFO [stdout] 
Dec 05 23:03:57.757 INFO [stderr] su: No module specific data is present
Dec 05 23:03:58.254 INFO running `"docker" "inspect" "fbd101a9dc7e7bdfef2e11f3d5152a5b6c8c3885e7959dc90667828a3f0d0b85"`
Dec 05 23:03:58.460 INFO running `"docker" "rm" "-f" "fbd101a9dc7e7bdfef2e11f3d5152a5b6c8c3885e7959dc90667828a3f0d0b85"`
Dec 05 23:03:58.681 INFO [stdout] fbd101a9dc7e7bdfef2e11f3d5152a5b6c8c3885e7959dc90667828a3f0d0b85