Dec 12 22:05:01.605 INFO testing voop/finalize_latex_changes against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 12 22:05:01.605 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:05:02.272 INFO [stdout] d6591096b2f926ba51ffb4bc1d173c1c4a0d44f24e0dedddbb40b1c29736d064
Dec 12 22:05:02.276 INFO running `"docker" "start" "-a" "d6591096b2f926ba51ffb4bc1d173c1c4a0d44f24e0dedddbb40b1c29736d064"`
Dec 12 22:05:04.578 INFO [stderr] usermod: no changes
Dec 12 22:05:04.712 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 12 22:05:04.716 INFO [stderr]    Compiling yaml-rust v0.3.5
Dec 12 22:05:04.716 INFO [stderr]    Compiling syn v0.14.9
Dec 12 22:05:04.716 INFO [stderr]    Compiling backtrace v0.3.9
Dec 12 22:05:04.716 INFO [stderr]    Compiling colored v1.6.1
Dec 12 22:05:11.875 INFO [stderr]    Compiling clap v2.32.0
Dec 12 22:05:26.934 INFO [stderr]    Compiling synstructure v0.9.0
Dec 12 22:05:39.139 INFO [stderr]    Compiling failure v0.1.2
Dec 12 22:05:39.935 INFO [stderr]    Compiling finalize_latex_changes v0.1.0 (/source)
Dec 12 22:05:42.599 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 37.98s
Dec 12 22:05:42.607 INFO [stderr] su: No module specific data is present
Dec 12 22:05:43.991 INFO running `"docker" "inspect" "d6591096b2f926ba51ffb4bc1d173c1c4a0d44f24e0dedddbb40b1c29736d064"`
Dec 12 22:05:44.199 INFO running `"docker" "rm" "-f" "d6591096b2f926ba51ffb4bc1d173c1c4a0d44f24e0dedddbb40b1c29736d064"`
Dec 12 22:05:44.459 INFO [stdout] d6591096b2f926ba51ffb4bc1d173c1c4a0d44f24e0dedddbb40b1c29736d064
Dec 12 22:05:44.465 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:05:44.887 INFO [stdout] 127e03c82b338d0869aceb4d4a16dfb93c383b990090361336a8f9275f6af45c
Dec 12 22:05:44.889 INFO running `"docker" "start" "-a" "127e03c82b338d0869aceb4d4a16dfb93c383b990090361336a8f9275f6af45c"`
Dec 12 22:05:46.392 INFO [stderr] usermod: no changes
Dec 12 22:05:46.483 INFO [stderr]    Compiling finalize_latex_changes v0.1.0 (/source)
Dec 12 22:05:48.521 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.09s
Dec 12 22:05:48.523 INFO [stderr] su: No module specific data is present
Dec 12 22:05:49.271 INFO running `"docker" "inspect" "127e03c82b338d0869aceb4d4a16dfb93c383b990090361336a8f9275f6af45c"`
Dec 12 22:05:49.636 INFO running `"docker" "rm" "-f" "127e03c82b338d0869aceb4d4a16dfb93c383b990090361336a8f9275f6af45c"`
Dec 12 22:05:50.028 INFO [stdout] 127e03c82b338d0869aceb4d4a16dfb93c383b990090361336a8f9275f6af45c
Dec 12 22:05:50.033 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:05:50.504 INFO [stdout] d21733eb73c678c2e7c4cd91ea835b91f7f5fd7914d7052861e4b822796a091f
Dec 12 22:05:50.507 INFO running `"docker" "start" "-a" "d21733eb73c678c2e7c4cd91ea835b91f7f5fd7914d7052861e4b822796a091f"`
Dec 12 22:05:52.521 INFO [stderr] usermod: no changes
Dec 12 22:05:52.671 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 12 22:05:52.679 INFO [stderr]      Running /target/debug/deps/finalize_latex_changes-cc11927f3e23a8c5
Dec 12 22:05:52.687 INFO [stdout] 
Dec 12 22:05:52.687 INFO [stdout] running 8 tests
Dec 12 22:05:52.687 INFO [stdout] test tests::test_create_steps_for_command ... ok
Dec 12 22:05:52.687 INFO [stdout] test tests::test_defaults ... ok
Dec 12 22:05:52.687 INFO [stdout] test tests::test_added ... ok
Dec 12 22:05:52.687 INFO [stdout] test tests::test_count_open_brackets ... ok
Dec 12 22:05:52.699 INFO [stdout] test tests::test_replaced ... ok
Dec 12 22:05:52.699 INFO [stdout] test tests::test_multi_line ... ok
Dec 12 22:05:52.699 INFO [stdout] test tests::test_process_file_same_file ... ok
Dec 12 22:05:52.699 INFO [stdout] test tests::test_deleted ... ok
Dec 12 22:05:52.699 INFO [stdout] 
Dec 12 22:05:52.699 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:52.699 INFO [stdout] 
Dec 12 22:05:52.699 INFO [stdout] 
Dec 12 22:05:52.699 INFO [stdout] running 0 tests
Dec 12 22:05:52.699 INFO [stdout] 
Dec 12 22:05:52.699 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:52.699 INFO [stdout] 
Dec 12 22:05:52.699 INFO [stderr]      Running /target/debug/deps/finalize_latex_changes-079df0f292bda92f
Dec 12 22:05:52.699 INFO [stderr]    Doc-tests finalize_latex_changes
Dec 12 22:05:53.012 INFO [stdout] 
Dec 12 22:05:53.012 INFO [stdout] running 4 tests
Dec 12 22:05:54.691 INFO [stdout] test src/lib.rs - Filter::num_added (line 134) ... ok
Dec 12 22:05:54.773 INFO [stdout] test src/lib.rs - Filter::num_replaced (line 147) ... ok
Dec 12 22:05:54.789 INFO [stdout] test src/lib.rs - Filter::num_deleted (line 159) ... ok
Dec 12 22:05:54.959 INFO [stdout] test src/lib.rs - Filter::process (line 37) ... ok
Dec 12 22:05:54.959 INFO [stdout] 
Dec 12 22:05:54.959 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:05:54.959 INFO [stdout] 
Dec 12 22:05:54.983 INFO [stderr] su: No module specific data is present
Dec 12 22:05:55.779 INFO running `"docker" "inspect" "d21733eb73c678c2e7c4cd91ea835b91f7f5fd7914d7052861e4b822796a091f"`
Dec 12 22:05:56.146 INFO running `"docker" "rm" "-f" "d21733eb73c678c2e7c4cd91ea835b91f7f5fd7914d7052861e4b822796a091f"`
Dec 12 22:05:56.419 INFO [stdout] d21733eb73c678c2e7c4cd91ea835b91f7f5fd7914d7052861e4b822796a091f