Dec 12 22:05:09.476 INFO testing voop/finalize_latex_changes against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 12 22:05:09.476 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 12 22:05:09.941 INFO [stdout] e47445d18836ea455aae5a13ecf0c3ca781f808e53dac26cb649a7d15fb9ab15
Dec 12 22:05:09.943 INFO running `"docker" "start" "-a" "e47445d18836ea455aae5a13ecf0c3ca781f808e53dac26cb649a7d15fb9ab15"`
Dec 12 22:05:11.998 INFO [stderr] usermod: no changes
Dec 12 22:05:12.210 INFO [stderr]    Compiling same-file v1.0.3
Dec 12 22:05:12.211 INFO [stderr]    Compiling clap v2.32.0
Dec 12 22:05:12.211 INFO [stderr]    Compiling syn v0.14.9
Dec 12 22:05:12.211 INFO [stderr]    Compiling backtrace v0.3.9
Dec 12 22:05:12.212 INFO [stderr]    Compiling colored v1.6.1
Dec 12 22:05:13.665 INFO [stderr]    Compiling walkdir v2.2.5
Dec 12 22:05:35.131 INFO [stderr]    Compiling synstructure v0.9.0
Dec 12 22:05:41.327 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 12 22:05:43.884 INFO [stderr]    Compiling failure v0.1.2
Dec 12 22:05:44.487 INFO [stderr]    Compiling finalize_latex_changes v0.1.0 (/source)
Dec 12 22:05:47.645 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.61s
Dec 12 22:05:47.659 INFO [stderr] su: No module specific data is present
Dec 12 22:05:48.601 INFO running `"docker" "inspect" "e47445d18836ea455aae5a13ecf0c3ca781f808e53dac26cb649a7d15fb9ab15"`
Dec 12 22:05:48.810 INFO running `"docker" "rm" "-f" "e47445d18836ea455aae5a13ecf0c3ca781f808e53dac26cb649a7d15fb9ab15"`
Dec 12 22:05:49.079 INFO [stdout] e47445d18836ea455aae5a13ecf0c3ca781f808e53dac26cb649a7d15fb9ab15
Dec 12 22:05:49.081 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 12 22:05:49.520 INFO [stdout] 183f2502431390875bf85fd9ec285f0aeea3d6f554aa41064c3448d9dddec224
Dec 12 22:05:49.527 INFO running `"docker" "start" "-a" "183f2502431390875bf85fd9ec285f0aeea3d6f554aa41064c3448d9dddec224"`
Dec 12 22:05:51.070 INFO [stderr] usermod: no changes
Dec 12 22:05:51.258 INFO [stderr]    Compiling finalize_latex_changes v0.1.0 (/source)
Dec 12 22:05:53.259 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.15s
Dec 12 22:05:53.259 INFO [stderr] su: No module specific data is present
Dec 12 22:05:53.911 INFO running `"docker" "inspect" "183f2502431390875bf85fd9ec285f0aeea3d6f554aa41064c3448d9dddec224"`
Dec 12 22:05:54.189 INFO running `"docker" "rm" "-f" "183f2502431390875bf85fd9ec285f0aeea3d6f554aa41064c3448d9dddec224"`
Dec 12 22:05:54.444 INFO [stdout] 183f2502431390875bf85fd9ec285f0aeea3d6f554aa41064c3448d9dddec224
Dec 12 22:05:54.448 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 12 22:05:54.912 INFO [stdout] 042cbd3dc84abdb425328bd050b4da8bf752e18a8e46990c0357f5b66a191e39
Dec 12 22:05:54.914 INFO running `"docker" "start" "-a" "042cbd3dc84abdb425328bd050b4da8bf752e18a8e46990c0357f5b66a191e39"`
Dec 12 22:05:56.193 INFO [stderr] usermod: no changes
Dec 12 22:05:56.327 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 12 22:05:56.331 INFO [stderr]      Running /target/debug/deps/finalize_latex_changes-70b9b799edae0ca9
Dec 12 22:05:56.343 INFO [stderr]      Running /target/debug/deps/finalize_latex_changes-e9cfc9af0481676f
Dec 12 22:05:56.343 INFO [stdout] 
Dec 12 22:05:56.343 INFO [stdout] running 8 tests
Dec 12 22:05:56.343 INFO [stdout] test tests::test_added ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_create_steps_for_command ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_defaults ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_count_open_brackets ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_process_file_same_file ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_replaced ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_multi_line ... ok
Dec 12 22:05:56.343 INFO [stdout] test tests::test_deleted ... ok
Dec 12 22:05:56.343 INFO [stdout] 
Dec 12 22:05:56.343 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:56.343 INFO [stdout] 
Dec 12 22:05:56.347 INFO [stdout] 
Dec 12 22:05:56.347 INFO [stderr]    Doc-tests finalize_latex_changes
Dec 12 22:05:56.347 INFO [stdout] running 0 tests
Dec 12 22:05:56.347 INFO [stdout] 
Dec 12 22:05:56.347 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:56.347 INFO [stdout] 
Dec 12 22:05:56.571 INFO [stdout] 
Dec 12 22:05:56.571 INFO [stdout] running 4 tests
Dec 12 22:05:57.827 INFO [stdout] test src/lib.rs - Filter::num_deleted (line 159) ... ok
Dec 12 22:05:58.281 INFO [stdout] test src/lib.rs - Filter::num_replaced (line 147) ... ok
Dec 12 22:05:58.447 INFO [stdout] test src/lib.rs - Filter::process (line 37) ... ok
Dec 12 22:05:58.543 INFO [stdout] test src/lib.rs - Filter::num_added (line 134) ... ok
Dec 12 22:05:58.543 INFO [stdout] 
Dec 12 22:05:58.543 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:58.543 INFO [stdout] 
Dec 12 22:05:58.567 INFO [stderr] su: No module specific data is present
Dec 12 22:05:59.313 INFO running `"docker" "inspect" "042cbd3dc84abdb425328bd050b4da8bf752e18a8e46990c0357f5b66a191e39"`
Dec 12 22:05:59.559 INFO running `"docker" "rm" "-f" "042cbd3dc84abdb425328bd050b4da8bf752e18a8e46990c0357f5b66a191e39"`
Dec 12 22:05:59.831 INFO [stdout] 042cbd3dc84abdb425328bd050b4da8bf752e18a8e46990c0357f5b66a191e39