[INFO] fetching crate ippusb 0.5.0...
[INFO] testing ippusb-0.5.0 against try#b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b for pr-145330-1
[INFO] extracting crate ippusb 0.5.0 into /workspace/builds/worker-5-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate ippusb 0.5.0 on toolchain b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "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" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 66 packages to latest compatible versions
[INFO] [stderr]       Adding hyper v0.14.32 (available: v1.7.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ba3623d2325925fb715fb1c5d4cac0324752f23a9cd2dae2f8605eef4faba3c3
[INFO] running `Command { std: "docker" "start" "-a" "ba3623d2325925fb715fb1c5d4cac0324752f23a9cd2dae2f8605eef4faba3c3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ba3623d2325925fb715fb1c5d4cac0324752f23a9cd2dae2f8605eef4faba3c3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ba3623d2325925fb715fb1c5d4cac0324752f23a9cd2dae2f8605eef4faba3c3", kill_on_drop: false }`
[INFO] [stdout] ba3623d2325925fb715fb1c5d4cac0324752f23a9cd2dae2f8605eef4faba3c3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fd24098d0c69834076a5aa9a3d0c05599c999971e2f749f323c16bdf5ec77d62
[INFO] running `Command { std: "docker" "start" "-a" "fd24098d0c69834076a5aa9a3d0c05599c999971e2f749f323c16bdf5ec77d62", kill_on_drop: false }`
[INFO] [stderr]    Compiling rusb v0.9.4
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling chunked_transfer v1.5.0
[INFO] [stderr]    Compiling libusb1-sys v0.7.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling http-body v0.4.6
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling tokio v1.47.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 19.48s
[INFO] running `Command { std: "docker" "inspect" "fd24098d0c69834076a5aa9a3d0c05599c999971e2f749f323c16bdf5ec77d62", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fd24098d0c69834076a5aa9a3d0c05599c999971e2f749f323c16bdf5ec77d62", kill_on_drop: false }`
[INFO] [stdout] fd24098d0c69834076a5aa9a3d0c05599c999971e2f749f323c16bdf5ec77d62
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 32cbaeca2f6df97b043c62554363509f4e7a74a99ffeae6745fac41e30d84304
[INFO] running `Command { std: "docker" "start" "-a" "32cbaeca2f6df97b043c62554363509f4e7a74a99ffeae6745fac41e30d84304", 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 4.00s
[INFO] running `Command { std: "docker" "inspect" "32cbaeca2f6df97b043c62554363509f4e7a74a99ffeae6745fac41e30d84304", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "32cbaeca2f6df97b043c62554363509f4e7a74a99ffeae6745fac41e30d84304", kill_on_drop: false }`
[INFO] [stdout] 32cbaeca2f6df97b043c62554363509f4e7a74a99ffeae6745fac41e30d84304
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8ad28f9b3f364d71502e95e39fc163fdb0e71c4ba01d8a4ce517e98c42fce3b5
[INFO] running `Command { std: "docker" "start" "-a" "8ad28f9b3f364d71502e95e39fc163fdb0e71c4ba01d8a4ce517e98c42fce3b5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ippusb-072ed99ca515014c)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test device_info::tests::compare_lowest_alternate_first ... ok
[INFO] [stdout] test device_info::tests::compare_lowest_interface_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::body_length_nonascii_header ... ok
[INFO] [stdout] test http::tests::body_length_invalid_number ... ok
[INFO] [stdout] test http::tests::copy_response_chunked ... 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_missing_header_end ... ok
[INFO] [stdout] test http::tests::e2e_header ... ok
[INFO] [stdout] test http::tests::rewrite_request_no_body ... ok
[INFO] [stdout] test http::tests::response_reader_static_response ... ok
[INFO] [stdout] test http::tests::response_reader_invalid_status_line ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_empty ... ok
[INFO] [stdout] test http::tests::response_reader_invalid_http_version ... ok
[INFO] [stdout] test http::tests::rewrite_request_chunked_body ... ok
[INFO] [stdout] test http::tests::rewrite_request_large_body ... ok
[INFO] [stdout] test io_adapters::tests::logging_reader ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_multiple ... ok
[INFO] [stdout] test io_adapters::tests::chunked_writer ... ok
[INFO] [stdout] test io_adapters::tests::complete_reader ... ok
[INFO] [stdout] test http::tests::test_read_until_delimiter ... ok
[INFO] [stdout] test http::tests::rewrite_request_small_body ... ok
[INFO] [stdout] test http::tests::copy_response_empty ... ok
[INFO] [stdout] test http::tests::copy_response_static ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_single ... ok
[INFO] [stdout] test http::tests::body_length_no_header ... ok
[INFO] [stderr]    Doc-tests ippusb
[INFO] [stdout] test http::tests::extract_content_type ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[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.21s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "8ad28f9b3f364d71502e95e39fc163fdb0e71c4ba01d8a4ce517e98c42fce3b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ad28f9b3f364d71502e95e39fc163fdb0e71c4ba01d8a4ce517e98c42fce3b5", kill_on_drop: false }`
[INFO] [stdout] 8ad28f9b3f364d71502e95e39fc163fdb0e71c4ba01d8a4ce517e98c42fce3b5