[INFO] fetching crate ippusb 0.5.0...
[INFO] testing ippusb-0.5.0 against master#350d0ef0ec0493e6d21cfb265cb8211a0e74d766 for pr-145330-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 350d0ef0ec0493e6d21cfb265cb8211a0e74d766
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "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" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "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" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "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] [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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] eb18926cdda8ad3f72e0ade17612ebc9e3c600b5217454127f3ec3bea612495a
[INFO] running `Command { std: "docker" "start" "-a" "eb18926cdda8ad3f72e0ade17612ebc9e3c600b5217454127f3ec3bea612495a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "eb18926cdda8ad3f72e0ade17612ebc9e3c600b5217454127f3ec3bea612495a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eb18926cdda8ad3f72e0ade17612ebc9e3c600b5217454127f3ec3bea612495a", kill_on_drop: false }`
[INFO] [stdout] eb18926cdda8ad3f72e0ade17612ebc9e3c600b5217454127f3ec3bea612495a
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0ae722d600bfd24777e5247e3ae32d937e1b31999b4164504d2bbca05444df65
[INFO] running `Command { std: "docker" "start" "-a" "0ae722d600bfd24777e5247e3ae32d937e1b31999b4164504d2bbca05444df65", kill_on_drop: false }`
[INFO] [stderr]    Compiling libusb1-sys v0.7.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling rusb v0.9.4
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling chunked_transfer v1.5.0
[INFO] [stderr]    Compiling http-body v0.4.6
[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 18.15s
[INFO] running `Command { std: "docker" "inspect" "0ae722d600bfd24777e5247e3ae32d937e1b31999b4164504d2bbca05444df65", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0ae722d600bfd24777e5247e3ae32d937e1b31999b4164504d2bbca05444df65", kill_on_drop: false }`
[INFO] [stdout] 0ae722d600bfd24777e5247e3ae32d937e1b31999b4164504d2bbca05444df65
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ef85ba186ed7929f2b5b9c73b32dba9448d3cb5e06ff0ad935711494531d7c59
[INFO] running `Command { std: "docker" "start" "-a" "ef85ba186ed7929f2b5b9c73b32dba9448d3cb5e06ff0ad935711494531d7c59", 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 2.86s
[INFO] running `Command { std: "docker" "inspect" "ef85ba186ed7929f2b5b9c73b32dba9448d3cb5e06ff0ad935711494531d7c59", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef85ba186ed7929f2b5b9c73b32dba9448d3cb5e06ff0ad935711494531d7c59", kill_on_drop: false }`
[INFO] [stdout] ef85ba186ed7929f2b5b9c73b32dba9448d3cb5e06ff0ad935711494531d7c59
[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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c473bfc8e4205be7bd5af2e3b009fa716d3e08424a1d19780777c2a13b551a3f
[INFO] running `Command { std: "docker" "start" "-a" "c473bfc8e4205be7bd5af2e3b009fa716d3e08424a1d19780777c2a13b551a3f", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ippusb-072ed99ca515014c)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test http::tests::body_length_invalid_number ... ok
[INFO] [stdout] test http::tests::body_length_nonzero_header ... ok
[INFO] [stdout] test http::tests::body_length_no_header ... ok
[INFO] [stdout] test http::tests::body_length_nonascii_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::response_reader_chunked_response ... ok
[INFO] [stdout] test device_info::tests::compare_lowest_interface_first ... 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::response_reader_static_response ... ok
[INFO] [stdout] test http::tests::rewrite_request_chunked_body ... ok
[INFO] [stdout] test http::tests::rewrite_request_large_body ... ok
[INFO] [stdout] test http::tests::rewrite_request_no_body ... ok
[INFO] [stdout] test http::tests::rewrite_request_small_body ... ok
[INFO] [stdout] test http::tests::copy_request_header ... ok
[INFO] [stdout] test http::tests::extract_content_type ... ok
[INFO] [stdout] test http::tests::copy_response_static ... ok
[INFO] [stdout] test http::tests::e2e_header ... ok
[INFO] [stdout] test http::tests::response_reader_empty_response ... ok
[INFO] [stdout] test http::tests::copy_response_empty ... ok
[INFO] [stdout] test http::tests::test_read_until_delimiter ... ok
[INFO] [stdout] test io_adapters::tests::chunked_writer ... ok
[INFO] [stdout] test io_adapters::tests::complete_reader ... ok
[INFO] [stdout] test io_adapters::tests::logging_reader ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_empty ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_multiple ... ok
[INFO] [stdout] test io_adapters::tests::logging_writer_single ... ok
[INFO] [stdout] test http::tests::copy_response_chunked ... ok
[INFO] [stderr]    Doc-tests ippusb
[INFO] [stdout] test device_info::tests::compare_lowest_alternate_first ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[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.16s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "c473bfc8e4205be7bd5af2e3b009fa716d3e08424a1d19780777c2a13b551a3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c473bfc8e4205be7bd5af2e3b009fa716d3e08424a1d19780777c2a13b551a3f", kill_on_drop: false }`
[INFO] [stdout] c473bfc8e4205be7bd5af2e3b009fa716d3e08424a1d19780777c2a13b551a3f