[INFO] updating cached repository gadomski/riprocess
[INFO] running `"git" "fetch" "--all"`
[INFO] [stdout] Fetching origin
[INFO] [stderr] From git://github.com/gadomski/riprocess
[INFO] [stderr]  * branch            HEAD       -> FETCH_HEAD
[INFO] running `"git" "clone" "work/cache/sources/gh/gadomski/riprocess" "work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/gh/gadomski/riprocess"`
[INFO] [stderr] Cloning into 'work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/gh/gadomski/riprocess'...
[INFO] [stderr] done.
[INFO] running `"git" "clone" "work/cache/sources/gh/gadomski/riprocess" "work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess"`
[INFO] [stderr] Cloning into 'work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess'...
[INFO] [stderr] done.
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] f65896522c8766fbb911e8adc66383d3be1ca4f7
[INFO] sha for GitHub repo gadomski/riprocess: f65896522c8766fbb911e8adc66383d3be1ca4f7
[INFO] validating manifest of gadomski/riprocess on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of gadomski/riprocess on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing gadomski/riprocess
[INFO] finished frobbing gadomski/riprocess
[INFO] frobbed toml for gadomski/riprocess written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/gh/gadomski/riprocess/Cargo.toml
[INFO] started frobbing gadomski/riprocess
[INFO] finished frobbing gadomski/riprocess
[INFO] frobbed toml for gadomski/riprocess written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess/Cargo.toml
[INFO] crate gadomski/riprocess has a lockfile. skipping
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing gadomski/riprocess against try#7fea0229842e79db1159ea58ad9a653b288efee9 for pr-57967
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-6/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "build" "--frozen"`
[INFO] [stdout] 5471341e287c47d3cf7270829ff9e699c9ff4d8351fb2aaed9e63cd9816c7e02
[INFO] running `"docker" "start" "-a" "5471341e287c47d3cf7270829ff9e699c9ff4d8351fb2aaed9e63cd9816c7e02"`
[INFO] [stderr]    Compiling libc v0.2.30
[INFO] [stderr]    Compiling toml v0.4.5
[INFO] [stderr]    Compiling memchr v1.0.1
[INFO] [stderr]    Compiling aho-corasick v0.6.3
[INFO] [stderr]    Compiling regex v0.2.2
[INFO] [stderr]    Compiling docopt v0.8.1
[INFO] [stderr]    Compiling riprocess v0.1.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.50s
[INFO] running `"docker" "inspect" "5471341e287c47d3cf7270829ff9e699c9ff4d8351fb2aaed9e63cd9816c7e02"`
[INFO] running `"docker" "rm" "-f" "5471341e287c47d3cf7270829ff9e699c9ff4d8351fb2aaed9e63cd9816c7e02"`
[INFO] [stdout] 5471341e287c47d3cf7270829ff9e699c9ff4d8351fb2aaed9e63cd9816c7e02
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-6/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] d5d7e26fa42518005bb9f17a75e1bf15017bad9daa0b38244a7f8cbde97957a1
[INFO] running `"docker" "start" "-a" "d5d7e26fa42518005bb9f17a75e1bf15017bad9daa0b38244a7f8cbde97957a1"`
[INFO] [stderr]    Compiling riprocess v0.1.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.60s
[INFO] running `"docker" "inspect" "d5d7e26fa42518005bb9f17a75e1bf15017bad9daa0b38244a7f8cbde97957a1"`
[INFO] running `"docker" "rm" "-f" "d5d7e26fa42518005bb9f17a75e1bf15017bad9daa0b38244a7f8cbde97957a1"`
[INFO] [stdout] d5d7e26fa42518005bb9f17a75e1bf15017bad9daa0b38244a7f8cbde97957a1
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-6/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/gh/gadomski/riprocess:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen"`
[INFO] [stdout] 58dc1176df8de62989a4217d73584909948735407b3b9ab6aa1258257fd79efa
[INFO] running `"docker" "start" "-a" "58dc1176df8de62989a4217d73584909948735407b3b9ab6aa1258257fd79efa"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/riprocess-7a128b495dffed95
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test image::tests::end ... ok
[INFO] [stdout] test image::tests::all_images ... ok
[INFO] [stdout] test image::tests::no_images ... ok
[INFO] [stdout] test record::tests::count_mismatch ... ok
[INFO] [stdout] test image::tests::end_out_of_range ... ok
[INFO] [stdout] test record::tests::empty_timestamps ... ok
[INFO] [stdout] test record::tests::from_reference ... ok
[INFO] [stdout] test image::tests::start_out_of_range ... ok
[INFO] [stdout] test image::tests::start ... ok
[INFO] [stdout] test config::tests::no_images ... ok
[INFO] [stdout] test config::tests::image_count_mismatch ... ok
[INFO] [stdout] test timestamp::tests::start ... ok
[INFO] [stdout] test timestamp::tests::no_paths ... ok
[INFO] [stdout] test timestamp::tests::invalid_end ... ok
[INFO] [stdout] test timestamp::tests::invalid_start ... ok
[INFO] [stdout] test timestamp::tests::end ... ok
[INFO] [stdout] test timestamp::tests::all_paths ... ok
[INFO] [stdout] test config::tests::image_list ... ok
[INFO] [stdout] test config::tests::record_count_mismatch ... ok
[INFO] [stdout] test timestamp::tests::timestamps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests riprocess
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test src/record.rs - record::Config::adjust_timestamps (line 20) ... ok
[INFO] [stdout] test src/image.rs - image::Config::new (line 35) ... ok
[INFO] [stdout] test src/config.rs - config::Config::new (line 62) ... ok
[INFO] [stdout] test src/record.rs - record::Config::adjust_timestamps (line 39) ... ok
[INFO] [stdout] test src/image.rs - image::Config::paths (line 50) ... ok
[INFO] [stdout] test src/timestamp.rs - timestamp::Config::paths (line 34) ... ok
[INFO] [stdout] test src/config.rs - config::Config (line 10) ... ok
[INFO] [stdout] test src/config.rs - config::Config::from_path (line 44) ... ok
[INFO] [stdout] test src/config.rs - config::Config::image_list (line 77) ... ok
[INFO] [stdout] test src/timestamp.rs - timestamp::Config::timestamps (line 82) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "58dc1176df8de62989a4217d73584909948735407b3b9ab6aa1258257fd79efa"`
[INFO] running `"docker" "rm" "-f" "58dc1176df8de62989a4217d73584909948735407b3b9ab6aa1258257fd79efa"`
[INFO] [stdout] 58dc1176df8de62989a4217d73584909948735407b3b9ab6aa1258257fd79efa