Nov 17 02:25:16.274 INFO testing gadomski/riprocess against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 02:25:16.276 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 02:25:16.987 INFO [stdout] 46dc77f2211845fa19a056a805a7689c8d81b94a01fb86aff8b11d0e89d1ae37
Nov 17 02:25:16.987 INFO running `"docker" "start" "-a" "46dc77f2211845fa19a056a805a7689c8d81b94a01fb86aff8b11d0e89d1ae37"`
Nov 17 02:25:18.159 INFO [stderr] usermod: no changes
Nov 17 02:25:18.290 INFO [stderr]    Compiling libc v0.2.30
Nov 17 02:25:18.290 INFO [stderr]    Compiling serde v1.0.14
Nov 17 02:25:18.290 INFO [stderr]    Compiling serde_derive v1.0.14
Nov 17 02:25:21.431 INFO [stderr]    Compiling memchr v1.0.1
Nov 17 02:25:22.303 INFO [stderr]    Compiling aho-corasick v0.6.3
Nov 17 02:25:24.575 INFO [stderr]    Compiling regex v0.2.2
Nov 17 02:25:42.013 INFO [stderr]    Compiling docopt v0.8.1
Nov 17 02:25:42.014 INFO [stderr]    Compiling toml v0.4.5
Nov 17 02:25:50.779 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Nov 17 02:25:56.344 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 38.14s
Nov 17 02:25:56.366 INFO [stderr] su: No module specific data is present
Nov 17 02:25:57.218 INFO running `"docker" "inspect" "46dc77f2211845fa19a056a805a7689c8d81b94a01fb86aff8b11d0e89d1ae37"`
Nov 17 02:25:57.495 INFO running `"docker" "rm" "-f" "46dc77f2211845fa19a056a805a7689c8d81b94a01fb86aff8b11d0e89d1ae37"`
Nov 17 02:25:57.850 INFO [stdout] 46dc77f2211845fa19a056a805a7689c8d81b94a01fb86aff8b11d0e89d1ae37
Nov 17 02:25:57.858 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 02:25:58.319 INFO [stdout] 7fbf882edbf093606f9a7bf33118b4b09c50e8fce8603982a339839f9c396496
Nov 17 02:25:58.324 INFO running `"docker" "start" "-a" "7fbf882edbf093606f9a7bf33118b4b09c50e8fce8603982a339839f9c396496"`
Nov 17 02:25:59.535 INFO [stderr] usermod: no changes
Nov 17 02:25:59.631 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Nov 17 02:26:03.933 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.36s
Nov 17 02:26:03.939 INFO [stderr] su: No module specific data is present
Nov 17 02:26:05.141 INFO running `"docker" "inspect" "7fbf882edbf093606f9a7bf33118b4b09c50e8fce8603982a339839f9c396496"`
Nov 17 02:26:05.509 INFO running `"docker" "rm" "-f" "7fbf882edbf093606f9a7bf33118b4b09c50e8fce8603982a339839f9c396496"`
Nov 17 02:26:05.827 INFO [stdout] 7fbf882edbf093606f9a7bf33118b4b09c50e8fce8603982a339839f9c396496
Nov 17 02:26:05.837 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 02:26:06.359 INFO [stdout] 0c0ffcd26eea95fed84bf1f2311a03460bdbd14c4d5392144d55bc8fd82fb2e2
Nov 17 02:26:06.367 INFO running `"docker" "start" "-a" "0c0ffcd26eea95fed84bf1f2311a03460bdbd14c4d5392144d55bc8fd82fb2e2"`
Nov 17 02:26:07.419 INFO [stderr] usermod: no changes
Nov 17 02:26:07.559 INFO [stdout] 
Nov 17 02:26:07.559 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Nov 17 02:26:07.559 INFO [stdout] running 20 tests
Nov 17 02:26:07.559 INFO [stderr]      Running /target/debug/deps/riprocess-2ca0052beedbcd6b
Nov 17 02:26:07.584 INFO [stdout] test image::tests::all_images ... ok
Nov 17 02:26:07.587 INFO [stdout] test image::tests::end ... ok
Nov 17 02:26:07.587 INFO [stdout] test image::tests::end_out_of_range ... ok
Nov 17 02:26:07.587 INFO [stdout] test record::tests::count_mismatch ... ok
Nov 17 02:26:07.588 INFO [stdout] test image::tests::no_images ... ok
Nov 17 02:26:07.588 INFO [stdout] test image::tests::start_out_of_range ... ok
Nov 17 02:26:07.588 INFO [stdout] test image::tests::start ... ok
Nov 17 02:26:07.588 INFO [stdout] test record::tests::from_reference ... ok
Nov 17 02:26:07.595 INFO [stdout] test record::tests::empty_timestamps ... ok
Nov 17 02:26:07.623 INFO [stdout] test timestamp::tests::invalid_end ... ok
Nov 17 02:26:07.625 INFO [stdout] test timestamp::tests::no_paths ... ok
Nov 17 02:26:07.635 INFO [stdout] test timestamp::tests::start ... ok
Nov 17 02:26:07.640 INFO [stdout] test config::tests::image_count_mismatch ... ok
Nov 17 02:26:07.640 INFO [stdout] test timestamp::tests::end ... ok
Nov 17 02:26:07.640 INFO [stdout] test timestamp::tests::all_paths ... ok
Nov 17 02:26:07.645 INFO [stdout] test config::tests::no_images ... ok
Nov 17 02:26:07.645 INFO [stdout] test timestamp::tests::invalid_start ... ok
Nov 17 02:26:07.645 INFO [stdout] test config::tests::record_count_mismatch ... ok
Nov 17 02:26:07.659 INFO [stdout] test timestamp::tests::timestamps ... ok
Nov 17 02:26:07.659 INFO [stderr]    Doc-tests riprocess
Nov 17 02:26:07.659 INFO [stdout] test config::tests::image_list ... ok
Nov 17 02:26:07.659 INFO [stdout] 
Nov 17 02:26:07.659 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 02:26:07.659 INFO [stdout] 
Nov 17 02:26:08.631 INFO [stdout] 
Nov 17 02:26:08.631 INFO [stdout] running 10 tests
Nov 17 02:26:12.503 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 20) ... ok
Nov 17 02:26:21.576 INFO [stdout] test src/config.rs - config::Config::new (line 62) ... ok
Nov 17 02:26:22.144 INFO [stdout] test src/image.rs - image::Config::new (line 35) ... ok
Nov 17 02:26:22.570 INFO [stdout] test src/image.rs - image::Config::paths (line 50) ... ok
Nov 17 02:26:23.574 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 39) ... ok
Nov 17 02:26:25.535 INFO [stdout] test src/config.rs - config::Config::from_path (line 44) ... ok
Nov 17 02:26:26.211 INFO [stdout] test src/config.rs - config::Config::image_list (line 77) ... ok
Nov 17 02:26:26.591 INFO [stdout] test src/config.rs - config::Config (line 10) ... ok
Nov 17 02:26:26.807 INFO [stdout] test src/timestamp.rs - timestamp::Config::paths (line 34) ... ok
Nov 17 02:26:27.931 INFO [stdout] test src/timestamp.rs - timestamp::Config::timestamps (line 82) ... ok
Nov 17 02:26:27.931 INFO [stdout] 
Nov 17 02:26:27.931 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 02:26:27.931 INFO [stdout] 
Nov 17 02:26:27.958 INFO [stderr] su: No module specific data is present
Nov 17 02:26:28.821 INFO running `"docker" "inspect" "0c0ffcd26eea95fed84bf1f2311a03460bdbd14c4d5392144d55bc8fd82fb2e2"`
Nov 17 02:26:29.125 INFO running `"docker" "rm" "-f" "0c0ffcd26eea95fed84bf1f2311a03460bdbd14c4d5392144d55bc8fd82fb2e2"`
Nov 17 02:26:29.455 INFO [stdout] 0c0ffcd26eea95fed84bf1f2311a03460bdbd14c4d5392144d55bc8fd82fb2e2