Dec 13 18:48:58.244 INFO testing gadomski/riprocess against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 18:48:58.245 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:48:58.715 INFO [stdout] 070ef9cdca20163c5d351d09051f6d0117e4a42603de339c65d6d2d8add33b8f
Dec 13 18:48:58.723 INFO running `"docker" "start" "-a" "070ef9cdca20163c5d351d09051f6d0117e4a42603de339c65d6d2d8add33b8f"`
Dec 13 18:48:59.701 INFO [stderr] usermod: no changes
Dec 13 18:48:59.844 INFO [stderr]    Compiling serde v1.0.14
Dec 13 18:48:59.851 INFO [stderr]    Compiling serde_derive v1.0.14
Dec 13 18:49:13.539 INFO [stderr]    Compiling toml v0.4.5
Dec 13 18:49:13.539 INFO [stderr]    Compiling docopt v0.8.1
Dec 13 18:49:22.630 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Dec 13 18:49:28.961 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.26s
Dec 13 18:49:28.966 INFO [stderr] su: No module specific data is present
Dec 13 18:49:29.487 INFO running `"docker" "inspect" "070ef9cdca20163c5d351d09051f6d0117e4a42603de339c65d6d2d8add33b8f"`
Dec 13 18:49:29.721 INFO running `"docker" "rm" "-f" "070ef9cdca20163c5d351d09051f6d0117e4a42603de339c65d6d2d8add33b8f"`
Dec 13 18:49:30.108 INFO [stdout] 070ef9cdca20163c5d351d09051f6d0117e4a42603de339c65d6d2d8add33b8f
Dec 13 18:49:30.112 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:49:30.496 INFO [stdout] 80166d1a2060dc25c5683c3a5b182d526071e287aab75d05e00d8652c97d4101
Dec 13 18:49:30.500 INFO running `"docker" "start" "-a" "80166d1a2060dc25c5683c3a5b182d526071e287aab75d05e00d8652c97d4101"`
Dec 13 18:49:31.934 INFO [stderr] usermod: no changes
Dec 13 18:49:32.067 INFO [stderr]    Compiling riprocess v0.1.0 (/source)
Dec 13 18:49:37.159 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.20s
Dec 13 18:49:37.165 INFO [stderr] su: No module specific data is present
Dec 13 18:49:38.071 INFO running `"docker" "inspect" "80166d1a2060dc25c5683c3a5b182d526071e287aab75d05e00d8652c97d4101"`
Dec 13 18:49:38.415 INFO running `"docker" "rm" "-f" "80166d1a2060dc25c5683c3a5b182d526071e287aab75d05e00d8652c97d4101"`
Dec 13 18:49:38.577 INFO [stdout] 80166d1a2060dc25c5683c3a5b182d526071e287aab75d05e00d8652c97d4101
Dec 13 18:49:38.581 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:49:39.065 INFO [stdout] e07da6187e50fb3c8e099c752c58ea4398aa8fb871e48ec0c6fab6b0d1f69fbb
Dec 13 18:49:39.066 INFO running `"docker" "start" "-a" "e07da6187e50fb3c8e099c752c58ea4398aa8fb871e48ec0c6fab6b0d1f69fbb"`
Dec 13 18:49:39.883 INFO [stderr] usermod: no changes
Dec 13 18:49:39.971 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Dec 13 18:49:39.983 INFO [stderr]      Running /target/debug/deps/riprocess-474f4c631c977968
Dec 13 18:49:39.983 INFO [stdout] 
Dec 13 18:49:39.983 INFO [stdout] running 20 tests
Dec 13 18:49:39.999 INFO [stdout] test image::tests::all_images ... ok
Dec 13 18:49:39.999 INFO [stdout] test image::tests::end ... ok
Dec 13 18:49:40.000 INFO [stdout] test image::tests::end_out_of_range ... ok
Dec 13 18:49:40.000 INFO [stdout] test image::tests::no_images ... ok
Dec 13 18:49:40.000 INFO [stdout] test image::tests::start ... ok
Dec 13 18:49:40.003 INFO [stdout] test record::tests::count_mismatch ... ok
Dec 13 18:49:40.008 INFO [stdout] test record::tests::empty_timestamps ... ok
Dec 13 18:49:40.011 INFO [stdout] test record::tests::from_reference ... ok
Dec 13 18:49:40.022 INFO [stdout] test config::tests::image_list ... ok
Dec 13 18:49:40.022 INFO [stdout] test timestamp::tests::all_paths ... ok
Dec 13 18:49:40.025 INFO [stdout] test timestamp::tests::invalid_start ... ok
Dec 13 18:49:40.025 INFO [stdout] test config::tests::image_count_mismatch ... ok
Dec 13 18:49:40.025 INFO [stdout] test image::tests::start_out_of_range ... ok
Dec 13 18:49:40.035 INFO [stdout] test timestamp::tests::start ... ok
Dec 13 18:49:40.047 INFO [stdout] test config::tests::no_images ... ok
Dec 13 18:49:40.047 INFO [stdout] test config::tests::record_count_mismatch ... ok
Dec 13 18:49:40.047 INFO [stdout] test timestamp::tests::no_paths ... ok
Dec 13 18:49:40.047 INFO [stdout] test timestamp::tests::timestamps ... ok
Dec 13 18:49:40.047 INFO [stdout] test timestamp::tests::end ... ok
Dec 13 18:49:40.048 INFO [stdout] test timestamp::tests::invalid_end ... ok
Dec 13 18:49:40.048 INFO [stderr]    Doc-tests riprocess
Dec 13 18:49:40.048 INFO [stdout] 
Dec 13 18:49:40.048 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 18:49:40.048 INFO [stdout] 
Dec 13 18:49:41.413 INFO [stdout] 
Dec 13 18:49:41.413 INFO [stdout] running 10 tests
Dec 13 18:49:45.019 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 20) ... ok
Dec 13 18:49:52.975 INFO [stdout] test src/image.rs - image::Config::new (line 35) ... ok
Dec 13 18:49:54.275 INFO [stdout] test src/image.rs - image::Config::paths (line 50) ... ok
Dec 13 18:49:57.079 INFO [stdout] test src/config.rs - config::Config::new (line 62) ... ok
Dec 13 18:49:57.731 INFO [stdout] test src/record.rs - record::Config::adjust_timestamps (line 39) ... ok
Dec 13 18:49:58.607 INFO [stdout] test src/timestamp.rs - timestamp::Config::paths (line 34) ... ok
Dec 13 18:49:59.084 INFO [stdout] test src/config.rs - config::Config (line 10) ... ok
Dec 13 18:50:00.031 INFO [stdout] test src/config.rs - config::Config::image_list (line 77) ... ok
Dec 13 18:50:00.283 INFO [stdout] test src/config.rs - config::Config::from_path (line 44) ... ok
Dec 13 18:50:01.371 INFO [stdout] test src/timestamp.rs - timestamp::Config::timestamps (line 82) ... ok
Dec 13 18:50:01.371 INFO [stdout] 
Dec 13 18:50:01.371 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 18:50:01.371 INFO [stdout] 
Dec 13 18:50:01.407 INFO [stderr] su: No module specific data is present
Dec 13 18:50:02.501 INFO running `"docker" "inspect" "e07da6187e50fb3c8e099c752c58ea4398aa8fb871e48ec0c6fab6b0d1f69fbb"`
Dec 13 18:50:02.765 INFO running `"docker" "rm" "-f" "e07da6187e50fb3c8e099c752c58ea4398aa8fb871e48ec0c6fab6b0d1f69fbb"`
Dec 13 18:50:03.189 INFO [stdout] e07da6187e50fb3c8e099c752c58ea4398aa8fb871e48ec0c6fab6b0d1f69fbb