[INFO] fetching crate ippusb 0.5.0...
[INFO] testing ippusb-0.5.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate ippusb 0.5.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate ippusb 0.5.0
[INFO] finished tweaking crates.io crate ippusb 0.5.0
[INFO] tweaked toml for crates.io crate ippusb 0.5.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate ippusb 0.5.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 54 packages to latest compatible versions
[INFO] [stderr]       Adding hyper v0.14.32 (available: v1.9.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded testing_logger v0.1.1
[INFO] [stderr]   Downloaded chunked_transfer v1.5.0
[INFO] [stderr]   Downloaded rusb v0.9.4
[INFO] [stderr]   Downloaded libusb1-sys v0.7.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 36561139e1f2435b5fb6c49a1ac473d0e65be4081a8246e03217683cc99ef553
[INFO] running `Command { std: "docker" "start" "-a" "36561139e1f2435b5fb6c49a1ac473d0e65be4081a8246e03217683cc99ef553", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36561139e1f2435b5fb6c49a1ac473d0e65be4081a8246e03217683cc99ef553", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36561139e1f2435b5fb6c49a1ac473d0e65be4081a8246e03217683cc99ef553", kill_on_drop: false }`
[INFO] [stdout] 36561139e1f2435b5fb6c49a1ac473d0e65be4081a8246e03217683cc99ef553
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 180630b290d87da67b93efbca1e8c23ef7a56a37e638bb3e85a7e16c651b10d1
[INFO] running `Command { std: "docker" "start" "-a" "180630b290d87da67b93efbca1e8c23ef7a56a37e638bb3e85a7e16c651b10d1", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling libusb1-sys v0.7.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling rusb v0.9.4
[INFO] [stderr]    Compiling chunked_transfer v1.5.0
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.51.1
[INFO] [stderr]    Compiling hyper v0.14.32
[INFO] [stderr]    Compiling ippusb v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.90s
[INFO] running `Command { std: "docker" "inspect" "180630b290d87da67b93efbca1e8c23ef7a56a37e638bb3e85a7e16c651b10d1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "180630b290d87da67b93efbca1e8c23ef7a56a37e638bb3e85a7e16c651b10d1", kill_on_drop: false }`
[INFO] [stdout] 180630b290d87da67b93efbca1e8c23ef7a56a37e638bb3e85a7e16c651b10d1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8e68a48000d27dc4b3631fd775781cd6ceb4149de6e90d9d728aaaa91f301ed3
[INFO] running `Command { std: "docker" "start" "-a" "8e68a48000d27dc4b3631fd775781cd6ceb4149de6e90d9d728aaaa91f301ed3", kill_on_drop: false }`
[INFO] [stderr]    Compiling testing_logger v0.1.1
[INFO] [stderr]    Compiling ippusb v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.37s
[INFO] running `Command { std: "docker" "inspect" "8e68a48000d27dc4b3631fd775781cd6ceb4149de6e90d9d728aaaa91f301ed3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8e68a48000d27dc4b3631fd775781cd6ceb4149de6e90d9d728aaaa91f301ed3", kill_on_drop: false }`
[INFO] [stdout] 8e68a48000d27dc4b3631fd775781cd6ceb4149de6e90d9d728aaaa91f301ed3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] dad6309828a3d2b65ad6365b0c7eaa0ea19fa97fcc426ff79647bff3d6a99beb
[INFO] running `Command { std: "docker" "start" "-a" "dad6309828a3d2b65ad6365b0c7eaa0ea19fa97fcc426ff79647bff3d6a99beb", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ippusb-42d3812db4b5de36)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test device_info::tests::compare_lowest_interface_first ... ok
[INFO] [stdout] test http::tests::body_length_invalid_number ... ok
[INFO] [stdout] test http::tests::body_length_no_header ... ok
[INFO] [stdout] test http::tests::body_length_nonascii_header ... ok
[INFO] [stdout] test device_info::tests::compare_lowest_alternate_first ... ok
[INFO] [stdout] test http::tests::body_length_nonzero_header ... ok
[INFO] [stdout] test http::tests::body_length_zero_header ... ok
[INFO] [stdout] test http::tests::body_support ... ok
[INFO] [stdout] test http::tests::copy_request_header ... ok
[INFO] [stdout] test http::tests::extract_content_type ... ok
[INFO] [stdout] test http::tests::response_reader_chunked_response ... ok
[INFO] [stdout] test http::tests::response_reader_empty_response ... ok
[INFO] [stdout] test http::tests::response_reader_invalid_http_version ... ok
[INFO] [stdout] test http::tests::response_reader_invalid_status_line ... ok
[INFO] [stdout] test http::tests::response_reader_missing_header_end ... ok
[INFO] [stdout] test http::tests::rewrite_request_chunked_body ... ok
[INFO] [stdout] test http::tests::rewrite_request_no_body ... ok
[INFO] [stdout] test http::tests::copy_response_empty ... ok
[INFO] [stdout] test http::tests::rewrite_request_large_body ... ok
[INFO] [stdout] test http::tests::test_read_until_delimiter ... ok
[INFO] [stdout] test io_adapters::tests::chunked_writer ... ok
[INFO] [stdout] test http::tests::rewrite_request_small_body ... ok
[INFO] [stdout] test io_adapters::tests::logging_reader ... ok
[INFO] [stdout] test http::tests::e2e_header ... ok
[INFO] [stdout] test http::tests::response_reader_static_response ... ok
[INFO] [stdout] test io_adapters::tests::complete_reader ... ok
[INFO] [stdout] test http::tests::copy_response_static ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_empty ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_single ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_multiple ... ok
[INFO] [stdout] test http::tests::copy_response_chunked ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stderr]    Doc-tests ippusb
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 35) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "dad6309828a3d2b65ad6365b0c7eaa0ea19fa97fcc426ff79647bff3d6a99beb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dad6309828a3d2b65ad6365b0c7eaa0ea19fa97fcc426ff79647bff3d6a99beb", kill_on_drop: false }`
[INFO] [stdout] dad6309828a3d2b65ad6365b0c7eaa0ea19fa97fcc426ff79647bff3d6a99beb