[INFO] cloning repository https://github.com/Rb982/cs264proj
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Rb982/cs264proj" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRb982%2Fcs264proj", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRb982%2Fcs264proj'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] ac95e824d234cb8f39eb855065f9a1c9ace610e1
[INFO] testing Rb982/cs264proj against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRb982%2Fcs264proj" "/workspace/builds/worker-2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:   3% (12/318)
Updating files:   4% (13/318)
Updating files:   5% (16/318)
Updating files:   6% (20/318)
Updating files:   7% (23/318)
Updating files:   8% (26/318)
Updating files:   9% (29/318)
Updating files:  10% (32/318)
Updating files:  11% (35/318)
Updating files:  12% (39/318)
Updating files:  13% (42/318)
Updating files:  14% (45/318)
Updating files:  15% (48/318)
Updating files:  16% (51/318)
Updating files:  17% (55/318)
Updating files:  18% (58/318)
Updating files:  19% (61/318)
Updating files:  20% (64/318)
Updating files:  21% (67/318)
Updating files:  22% (70/318)
Updating files:  23% (74/318)
Updating files:  24% (77/318)
Updating files:  25% (80/318)
Updating files:  26% (83/318)
Updating files:  27% (86/318)
Updating files:  28% (90/318)
Updating files:  29% (93/318)
Updating files:  30% (96/318)
Updating files:  31% (99/318)
Updating files:  32% (102/318)
Updating files:  33% (105/318)
Updating files:  34% (109/318)
Updating files:  35% (112/318)
Updating files:  36% (115/318)
Updating files:  37% (118/318)
Updating files:  38% (121/318)
Updating files:  39% (125/318)
Updating files:  40% (128/318)
Updating files:  41% (131/318)
Updating files:  42% (134/318)
Updating files:  43% (137/318)
Updating files:  44% (140/318)
Updating files:  45% (144/318)
Updating files:  46% (147/318)
Updating files:  47% (150/318)
Updating files:  48% (153/318)
Updating files:  49% (156/318)
Updating files:  50% (159/318)
Updating files:  51% (163/318)
Updating files:  52% (166/318)
Updating files:  53% (169/318)
Updating files:  54% (172/318)
Updating files:  55% (175/318)
Updating files:  56% (179/318)
Updating files:  57% (182/318)
Updating files:  58% (185/318)
Updating files:  59% (188/318)
Updating files:  60% (191/318)
Updating files:  61% (194/318)
Updating files:  62% (198/318)
Updating files:  63% (201/318)
Updating files:  64% (204/318)
Updating files:  65% (207/318)
Updating files:  66% (210/318)
Updating files:  67% (214/318)
Updating files:  68% (217/318)
Updating files:  69% (220/318)
Updating files:  70% (223/318)
Updating files:  71% (226/318)
Updating files:  72% (229/318)
Updating files:  73% (233/318)
Updating files:  74% (236/318)
Updating files:  75% (239/318)
Updating files:  76% (242/318)
Updating files:  77% (245/318)
Updating files:  78% (249/318)
Updating files:  79% (252/318)
Updating files:  79% (254/318)
Updating files:  80% (255/318)
Updating files:  81% (258/318)
Updating files:  82% (261/318)
Updating files:  83% (264/318)
Updating files:  84% (268/318)
Updating files:  85% (271/318)
Updating files:  86% (274/318)
Updating files:  87% (277/318)
Updating files:  88% (280/318)
Updating files:  89% (284/318)
Updating files:  90% (287/318)
Updating files:  91% (290/318)
Updating files:  92% (293/318)
Updating files:  93% (296/318)
Updating files:  94% (299/318)
Updating files:  95% (303/318)
Updating files:  96% (306/318)
Updating files:  97% (309/318)
Updating files:  98% (312/318)
Updating files:  99% (315/318)
Updating files: 100% (318/318)
Updating files: 100% (318/318), done.
[INFO] validating manifest of git repo https://github.com/Rb982/cs264proj on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/Rb982/cs264proj
[INFO] finished tweaking git repo https://github.com/Rb982/cs264proj
[INFO] tweaked toml for git repo https://github.com/Rb982/cs264proj written to /workspace/builds/worker-2/source/Cargo.toml
[INFO] crate git repo https://github.com/Rb982/cs264proj already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rusb v0.6.5
[INFO] [stderr]   Downloaded libusb1-sys v0.4.2
[INFO] [stderr]   Downloaded usb v0.2.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 85186036831b60a2a975e6774280b6232701960af621214af5174f7e660df777
[INFO] running `Command { std: "docker" "start" "-a" "85186036831b60a2a975e6774280b6232701960af621214af5174f7e660df777", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "85186036831b60a2a975e6774280b6232701960af621214af5174f7e660df777", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "85186036831b60a2a975e6774280b6232701960af621214af5174f7e660df777", kill_on_drop: false }`
[INFO] [stdout] 85186036831b60a2a975e6774280b6232701960af621214af5174f7e660df777
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6c7a9f9ddd830001eac9432ddadb9753595d86474997538e437a51dc7a28260b
[INFO] running `Command { std: "docker" "start" "-a" "6c7a9f9ddd830001eac9432ddadb9753595d86474997538e437a51dc7a28260b", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.81
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling rle-decode-fast v1.0.1
[INFO] [stderr]    Compiling libflate_lz77 v1.0.0
[INFO] [stderr]    Compiling adler32 v1.2.0
[INFO] [stderr]    Compiling pkg-config v0.3.19
[INFO] [stderr]    Compiling cc v1.0.66
[INFO] [stderr]    Compiling libc v0.1.12
[INFO] [stderr]    Compiling crc32fast v1.2.1
[INFO] [stderr]    Compiling usb v0.2.2
[INFO] [stderr]    Compiling libflate v1.0.3
[INFO] [stderr]    Compiling xattr v0.2.2
[INFO] [stderr]    Compiling filetime v0.2.13
[INFO] [stderr]    Compiling tar v0.4.30
[INFO] [stderr]    Compiling libusb1-sys v0.4.2
[INFO] [stderr]    Compiling rusb v0.6.5
[INFO] [stderr]    Compiling final_project_rust v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::slice`
[INFO] [stdout]  --> src/main.rs:3:5
[INFO] [stdout]   |
[INFO] [stdout] 3 | use std::slice;
[INFO] [stdout]   |     ^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `std::time::Duration`
[INFO] [stdout]  --> src/main.rs:4:5
[INFO] [stdout]   |
[INFO] [stdout] 4 | use std::time::Duration;
[INFO] [stdout]   |     ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `microphone`
[INFO] [stdout]  --> src/main.rs:7:9
[INFO] [stdout]   |
[INFO] [stdout] 7 |     let microphone = match ctx.find_by_vid_pid(22136, 4096){
[INFO] [stdout]   |         ^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_microphone`
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_variables)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 3 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.54s
[INFO] running `Command { std: "docker" "inspect" "6c7a9f9ddd830001eac9432ddadb9753595d86474997538e437a51dc7a28260b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c7a9f9ddd830001eac9432ddadb9753595d86474997538e437a51dc7a28260b", kill_on_drop: false }`
[INFO] [stdout] 6c7a9f9ddd830001eac9432ddadb9753595d86474997538e437a51dc7a28260b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b803a893869c95da69836318ad65cc74329116158439bd03d3cdc7547d314990
[INFO] running `Command { std: "docker" "start" "-a" "b803a893869c95da69836318ad65cc74329116158439bd03d3cdc7547d314990", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling final_project_rust v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::slice`
[INFO] [stdout]  --> src/main.rs:3:5
[INFO] [stdout]   |
[INFO] [stdout] 3 | use std::slice;
[INFO] [stdout]   |     ^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `std::time::Duration`
[INFO] [stdout]  --> src/main.rs:4:5
[INFO] [stdout]   |
[INFO] [stdout] 4 | use std::time::Duration;
[INFO] [stdout]   |     ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `microphone`
[INFO] [stdout]  --> src/main.rs:7:9
[INFO] [stdout]   |
[INFO] [stdout] 7 |     let microphone = match ctx.find_by_vid_pid(22136, 4096){
[INFO] [stdout]   |         ^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_microphone`
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_variables)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 3 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.01s
[INFO] running `Command { std: "docker" "inspect" "b803a893869c95da69836318ad65cc74329116158439bd03d3cdc7547d314990", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b803a893869c95da69836318ad65cc74329116158439bd03d3cdc7547d314990", kill_on_drop: false }`
[INFO] [stdout] b803a893869c95da69836318ad65cc74329116158439bd03d3cdc7547d314990
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4d27caae5cff8af7b8cfb9ed1cc69a4cc5db6739996f41045645a0181bb79765
[INFO] running `Command { std: "docker" "start" "-a" "4d27caae5cff8af7b8cfb9ed1cc69a4cc5db6739996f41045645a0181bb79765", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `std::slice`
[INFO] [stderr]  --> src/main.rs:3:5
[INFO] [stderr]   |
[INFO] [stderr] 3 | use std::slice;
[INFO] [stderr]   |     ^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::time::Duration`
[INFO] [stderr]  --> src/main.rs:4:5
[INFO] [stderr]   |
[INFO] [stderr] 4 | use std::time::Duration;
[INFO] [stderr]   |     ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `microphone`
[INFO] [stderr]  --> src/main.rs:7:9
[INFO] [stderr]   |
[INFO] [stderr] 7 |     let microphone = match ctx.find_by_vid_pid(22136, 4096){
[INFO] [stderr]   |         ^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_microphone`
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_variables)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `final_project_rust` (bin "final_project_rust" test) generated 3 warnings
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/final_project_rust-2101468fe38385f2)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4d27caae5cff8af7b8cfb9ed1cc69a4cc5db6739996f41045645a0181bb79765", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4d27caae5cff8af7b8cfb9ed1cc69a4cc5db6739996f41045645a0181bb79765", kill_on_drop: false }`
[INFO] [stdout] 4d27caae5cff8af7b8cfb9ed1cc69a4cc5db6739996f41045645a0181bb79765