Dec 15 20:06:09.469 INFO testing cargo-dependencies-0.1.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 20:06:09.469 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 20:06:10.032 INFO [stdout] 1a3e7ded43d3306a22cc8151e4f5b736c66c32e733cd249e58f400435052258d
Dec 15 20:06:10.038 INFO running `"docker" "start" "-a" "1a3e7ded43d3306a22cc8151e4f5b736c66c32e733cd249e58f400435052258d"`
Dec 15 20:06:11.347 INFO [stderr] usermod: no changes
Dec 15 20:06:11.446 INFO [stderr]    Compiling cargo-dependencies v0.1.1 (/source)
Dec 15 20:06:13.929 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.54s
Dec 15 20:06:13.935 INFO [stderr] su: No module specific data is present
Dec 15 20:06:14.834 INFO running `"docker" "inspect" "1a3e7ded43d3306a22cc8151e4f5b736c66c32e733cd249e58f400435052258d"`
Dec 15 20:06:15.031 INFO running `"docker" "rm" "-f" "1a3e7ded43d3306a22cc8151e4f5b736c66c32e733cd249e58f400435052258d"`
Dec 15 20:06:15.268 INFO [stdout] 1a3e7ded43d3306a22cc8151e4f5b736c66c32e733cd249e58f400435052258d
Dec 15 20:06:15.273 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 20:06:15.679 INFO [stdout] 67b88cc268b1df8516578f13355e2e2f0e6c5e82be9f7a3b2f915a0b55ada206
Dec 15 20:06:15.685 INFO running `"docker" "start" "-a" "67b88cc268b1df8516578f13355e2e2f0e6c5e82be9f7a3b2f915a0b55ada206"`
Dec 15 20:06:16.248 INFO [stderr] usermod: no changes
Dec 15 20:06:16.276 INFO [stderr]    Compiling cargo-dependencies v0.1.1 (/source)
Dec 15 20:06:17.767 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.50s
Dec 15 20:06:17.770 INFO [stderr] su: No module specific data is present
Dec 15 20:06:18.208 INFO running `"docker" "inspect" "67b88cc268b1df8516578f13355e2e2f0e6c5e82be9f7a3b2f915a0b55ada206"`
Dec 15 20:06:18.321 INFO running `"docker" "rm" "-f" "67b88cc268b1df8516578f13355e2e2f0e6c5e82be9f7a3b2f915a0b55ada206"`
Dec 15 20:06:18.427 INFO [stdout] 67b88cc268b1df8516578f13355e2e2f0e6c5e82be9f7a3b2f915a0b55ada206
Dec 15 20:06:18.432 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 20:06:18.651 INFO [stdout] 1881310bab90ef9ac029a7e0cf67b9c848419e183572879e18924c401fcdcb7b
Dec 15 20:06:18.653 INFO running `"docker" "start" "-a" "1881310bab90ef9ac029a7e0cf67b9c848419e183572879e18924c401fcdcb7b"`
Dec 15 20:06:19.233 INFO [stderr] usermod: no changes
Dec 15 20:06:19.267 INFO [stdout] 
Dec 15 20:06:19.267 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 15 20:06:19.267 INFO [stdout] running 17 tests
Dec 15 20:06:19.267 INFO [stderr]      Running /target/debug/deps/cargo_dependencies-99c19c038dc643bf
Dec 15 20:06:19.267 INFO [stdout] test dependencies::tests::dependency_extraction_returns_dependency_names ... ok
Dec 15 20:06:19.268 INFO [stdout] test dependencies::tests::dependency_extraction_on_file_without_dependencies_returns_empty_vector ... ok
Dec 15 20:06:19.268 INFO [stdout] test dependencies::tests::dependency_extraction_returns_only_well_formed_dependencies ... ok
Dec 15 20:06:19.268 INFO [stdout] test package::tests::package_name_extraction_copes_with_unordered_sections ... ok
Dec 15 20:06:19.268 INFO [stdout] test dependencies::tests::dependency_extraction_on_file_with_a_different_package_returns_empty_vector ... ok
Dec 15 20:06:19.268 INFO [stdout] test dependencies::tests::dependency_extraction_on_empty_file_returns_empty_vector ... ok
Dec 15 20:06:19.268 INFO [stdout] test package::tests::package_name_extraction_fails_on_file_without_a_package_name ... ok
Dec 15 20:06:19.268 INFO [stdout] test package::tests::package_name_extraction_fails_on_unquoted_value ... ok
Dec 15 20:06:19.268 INFO [stdout] test package::tests::package_name_extraction_fails_on_empty_file ... ok
Dec 15 20:06:19.268 INFO [stdout] test dependencies::tests::dependency_extraction_ignores_malformed_dependencies_property ... ok
Dec 15 20:06:19.268 INFO [stdout] test package::tests::package_name_extraction_fails_on_different_section ... ok
Dec 15 20:06:19.269 INFO [stdout] test package::tests::package_name_extraction_is_successful ... ok
Dec 15 20:06:19.269 INFO [stdout] test parser::tests::parser_on_empty_file_is_empty ... ok
Dec 15 20:06:19.269 INFO [stdout] test parser::tests::parser_on_file_with_a_section_returns_section ... ok
Dec 15 20:06:19.269 INFO [stdout] test parser::tests::parser_on_file_with_two_sections_returns_both_individually ... ok
Dec 15 20:06:19.269 INFO [stdout] test parser::tests::parser_on_file_without_a_section_returns_file ... ok
Dec 15 20:06:19.270 INFO [stdout] test dependencies::tests::dependency_extraction_copes_with_out_of_order_properties ... ok
Dec 15 20:06:19.270 INFO [stdout] 
Dec 15 20:06:19.270 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:06:19.270 INFO [stdout] 
Dec 15 20:06:19.272 INFO [stderr] su: No module specific data is present
Dec 15 20:06:19.653 INFO running `"docker" "inspect" "1881310bab90ef9ac029a7e0cf67b9c848419e183572879e18924c401fcdcb7b"`
Dec 15 20:06:19.776 INFO running `"docker" "rm" "-f" "1881310bab90ef9ac029a7e0cf67b9c848419e183572879e18924c401fcdcb7b"`
Dec 15 20:06:19.876 INFO [stdout] 1881310bab90ef9ac029a7e0cf67b9c848419e183572879e18924c401fcdcb7b