[INFO] cloning repository https://github.com/popododo0720/kvmuse
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/popododo0720/kvmuse" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpopododo0720%2Fkvmuse", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpopododo0720%2Fkvmuse'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 5b5021721c7348ce7f8e21ee447bf094d2c30d09
[INFO] checking popododo0720/kvmuse against try#81ab7f2139295590561adbe6d5b0aaa2feff765f for pr-146470-3
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpopododo0720%2Fkvmuse" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:  75% (697/921)
Updating files:  76% (700/921)
Updating files:  77% (710/921)
Updating files:  78% (719/921)
Updating files:  79% (728/921)
Updating files:  80% (737/921)
Updating files:  81% (747/921)
Updating files:  82% (756/921)
Updating files:  83% (765/921)
Updating files:  84% (774/921)
Updating files:  85% (783/921)
Updating files:  86% (793/921)
Updating files:  87% (802/921)
Updating files:  88% (811/921)
Updating files:  89% (820/921)
Updating files:  90% (829/921)
Updating files:  91% (839/921)
Updating files:  92% (848/921)
Updating files:  93% (857/921)
Updating files:  94% (866/921)
Updating files:  95% (875/921)
Updating files:  96% (885/921)
Updating files:  97% (894/921)
Updating files:  98% (903/921)
Updating files:  99% (912/921)
Updating files: 100% (921/921)
Updating files: 100% (921/921), done.
[INFO] started tweaking git repo https://github.com/popododo0720/kvmuse
[INFO] finished tweaking git repo https://github.com/popododo0720/kvmuse
[INFO] tweaked toml for git repo https://github.com/popododo0720/kvmuse written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/popododo0720/kvmuse on toolchain 81ab7f2139295590561adbe6d5b0aaa2feff765f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/popododo0720/kvmuse already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "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
[ERROR] error running command: no output for 300 seconds
[INFO] checking popododo0720/kvmuse against try#81ab7f2139295590561adbe6d5b0aaa2feff765f for pr-146470-3
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpopododo0720%2Fkvmuse" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:  76% (709/921)
Updating files:  77% (710/921)
Updating files:  78% (719/921)
Updating files:  79% (728/921)
Updating files:  80% (737/921)
Updating files:  81% (747/921)
Updating files:  82% (756/921)
Updating files:  83% (765/921)
Updating files:  84% (774/921)
Updating files:  85% (783/921)
Updating files:  86% (793/921)
Updating files:  87% (802/921)
Updating files:  88% (811/921)
Updating files:  89% (820/921)
Updating files:  90% (829/921)
Updating files:  91% (839/921)
Updating files:  92% (848/921)
Updating files:  93% (857/921)
Updating files:  94% (866/921)
Updating files:  95% (875/921)
Updating files:  96% (885/921)
Updating files:  97% (894/921)
Updating files:  98% (903/921)
Updating files:  99% (912/921)
Updating files: 100% (921/921)
Updating files: 100% (921/921), done.
[INFO] started tweaking git repo https://github.com/popododo0720/kvmuse
[INFO] finished tweaking git repo https://github.com/popododo0720/kvmuse
[INFO] tweaked toml for git repo https://github.com/popododo0720/kvmuse written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/popododo0720/kvmuse on toolchain 81ab7f2139295590561adbe6d5b0aaa2feff765f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/popododo0720/kvmuse already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "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 io-uring v0.7.8
[INFO] [stderr]   Downloaded clap v4.5.41
[INFO] [stderr]   Downloaded redox_syscall v0.5.15
[INFO] [stderr]   Downloaded clap_builder v4.5.41
[INFO] [stderr]   Downloaded tokio v1.46.1
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c358cf4262161df4f4063d88e35b8acbbeab3b1b62592691f74e847128a3ed3f
[INFO] running `Command { std: "docker" "start" "-a" "c358cf4262161df4f4063d88e35b8acbbeab3b1b62592691f74e847128a3ed3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c358cf4262161df4f4063d88e35b8acbbeab3b1b62592691f74e847128a3ed3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c358cf4262161df4f4063d88e35b8acbbeab3b1b62592691f74e847128a3ed3f", kill_on_drop: false }`
[INFO] [stdout] c358cf4262161df4f4063d88e35b8acbbeab3b1b62592691f74e847128a3ed3f
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+81ab7f2139295590561adbe6d5b0aaa2feff765f" "check" "--frozen" "--all" "--all-targets" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5478dda01f9a01e9e1f2c0a4c980e26a6ad538f4a23495954a1ad3df916d3ea7
[INFO] running `Command { std: "docker" "start" "-a" "5478dda01f9a01e9e1f2c0a4c980e26a6ad538f4a23495954a1ad3df916d3ea7", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]     Checking anstyle-query v1.1.3
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde_json v1.0.141
[INFO] [stderr]    Compiling clap_derive v4.5.41
[INFO] [stderr]     Checking iana-time-zone v0.1.63
[INFO] [stderr]     Checking memchr v2.7.5
[INFO] [stderr]     Checking mio v1.0.4
[INFO] [stderr]     Checking signal-hook-registry v1.4.5
[INFO] [stderr]     Checking socket2 v0.5.10
[INFO] [stderr]     Checking parking_lot v0.12.4
[INFO] [stderr]     Checking anstream v0.6.19
[INFO] [stderr]     Checking clap_builder v4.5.41
[INFO] [stderr]     Checking tokio v1.46.1
[INFO] [stderr]     Checking clap v4.5.41
[INFO] [stderr]     Checking chrono v0.4.41
[INFO] [stderr]     Checking uuid v1.17.0
[INFO] [stderr]     Checking newhyper v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `uuid::Uuid`
[INFO] [stdout]   --> src/main.rs:12:5
[INFO] [stdout]    |
[INFO] [stdout] 12 | use uuid::Uuid;
[INFO] [stdout]    |     ^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `uuid::Uuid`
[INFO] [stdout]   --> src/main.rs:12:5
[INFO] [stdout]    |
[INFO] [stdout] 12 | use uuid::Uuid;
[INFO] [stdout]    |     ^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: constant `KVM_GET_VCPU_MMAP_SIZE` is never used
[INFO] [stdout]  --> src/kvm.rs:8:11
[INFO] [stdout]   |
[INFO] [stdout] 8 | pub const KVM_GET_VCPU_MMAP_SIZE: u64 = 0xae04;
[INFO] [stdout]   |           ^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: constant `KVM_RUN` is never used
[INFO] [stdout]   --> src/kvm.rs:11:11
[INFO] [stdout]    |
[INFO] [stdout] 11 | pub const KVM_RUN: u64 = 0xae80;
[INFO] [stdout]    |           ^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_val` is never used
[INFO] [stdout]   --> src/kvm.rs:32:8
[INFO] [stdout]    |
[INFO] [stdout] 32 | pub fn ioctl_with_val(fd: RawFd, request: u64, val: libc::c_ulong) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_ref` is never used
[INFO] [stdout]   --> src/kvm.rs:38:8
[INFO] [stdout]    |
[INFO] [stdout] 38 | pub fn ioctl_with_ref<T>(fd: RawFd, request: u64, arg: &T) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_mut` is never used
[INFO] [stdout]   --> src/kvm.rs:44:8
[INFO] [stdout]    |
[INFO] [stdout] 44 | pub fn ioctl_with_mut<T>(fd: RawFd, request: u64, arg: &mut T) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `fd` is never used
[INFO] [stdout]   --> src/kvm.rs:63:12
[INFO] [stdout]    |
[INFO] [stdout] 62 | impl KvmDevice {
[INFO] [stdout]    | -------------- method in this implementation
[INFO] [stdout] 63 |     pub fn fd(&self) -> RawFd {
[INFO] [stdout]    |            ^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `as_ptr` is never used
[INFO] [stdout]   --> src/vm.rs:37:8
[INFO] [stdout]    |
[INFO] [stdout] 17 | impl GuestMemory {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 37 |     fn as_ptr(&self) -> *mut u8 {
[INFO] [stdout]    |        ^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `id` is never read
[INFO] [stdout]   --> src/vm.rs:80:5
[INFO] [stdout]    |
[INFO] [stdout] 78 | struct Vcpu {
[INFO] [stdout]    |        ---- field in this struct
[INFO] [stdout] 79 |     fd: RawFd,
[INFO] [stdout] 80 |     id: u32,
[INFO] [stdout]    |     ^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: fields `kvm`, `memory`, and `vcpus` are never read
[INFO] [stdout]    --> src/vm.rs:104:5
[INFO] [stdout]     |
[INFO] [stdout] 103 | pub struct VirtualMachine {
[INFO] [stdout]     |            -------------- fields in this struct
[INFO] [stdout] 104 |     kvm: KvmDevice,
[INFO] [stdout]     |     ^^^
[INFO] [stdout] 105 |     vm_fd: RawFd,
[INFO] [stdout] 106 |     memory: GuestMemory,
[INFO] [stdout]     |     ^^^^^^
[INFO] [stdout] 107 |     vcpus: Vec<Vcpu>,
[INFO] [stdout]     |     ^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `info` is never used
[INFO] [stdout]    --> src/vm.rs:111:12
[INFO] [stdout]     |
[INFO] [stdout] 110 | impl VirtualMachine {
[INFO] [stdout]     | ------------------- method in this implementation
[INFO] [stdout] 111 |     pub fn info(&self) {
[INFO] [stdout]     |            ^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: constant `KVM_GET_VCPU_MMAP_SIZE` is never used
[INFO] [stdout]  --> src/kvm.rs:8:11
[INFO] [stdout]   |
[INFO] [stdout] 8 | pub const KVM_GET_VCPU_MMAP_SIZE: u64 = 0xae04;
[INFO] [stdout]   |           ^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: constant `KVM_RUN` is never used
[INFO] [stdout]   --> src/kvm.rs:11:11
[INFO] [stdout]    |
[INFO] [stdout] 11 | pub const KVM_RUN: u64 = 0xae80;
[INFO] [stdout]    |           ^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_val` is never used
[INFO] [stdout]   --> src/kvm.rs:32:8
[INFO] [stdout]    |
[INFO] [stdout] 32 | pub fn ioctl_with_val(fd: RawFd, request: u64, val: libc::c_ulong) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_ref` is never used
[INFO] [stdout]   --> src/kvm.rs:38:8
[INFO] [stdout]    |
[INFO] [stdout] 38 | pub fn ioctl_with_ref<T>(fd: RawFd, request: u64, arg: &T) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `ioctl_with_mut` is never used
[INFO] [stdout]   --> src/kvm.rs:44:8
[INFO] [stdout]    |
[INFO] [stdout] 44 | pub fn ioctl_with_mut<T>(fd: RawFd, request: u64, arg: &mut T) -> i32 {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `fd` is never used
[INFO] [stdout]   --> src/kvm.rs:63:12
[INFO] [stdout]    |
[INFO] [stdout] 62 | impl KvmDevice {
[INFO] [stdout]    | -------------- method in this implementation
[INFO] [stdout] 63 |     pub fn fd(&self) -> RawFd {
[INFO] [stdout]    |            ^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `as_ptr` is never used
[INFO] [stdout]   --> src/vm.rs:37:8
[INFO] [stdout]    |
[INFO] [stdout] 17 | impl GuestMemory {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 37 |     fn as_ptr(&self) -> *mut u8 {
[INFO] [stdout]    |        ^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `id` is never read
[INFO] [stdout]   --> src/vm.rs:80:5
[INFO] [stdout]    |
[INFO] [stdout] 78 | struct Vcpu {
[INFO] [stdout]    |        ---- field in this struct
[INFO] [stdout] 79 |     fd: RawFd,
[INFO] [stdout] 80 |     id: u32,
[INFO] [stdout]    |     ^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: fields `kvm`, `memory`, and `vcpus` are never read
[INFO] [stdout]    --> src/vm.rs:104:5
[INFO] [stdout]     |
[INFO] [stdout] 103 | pub struct VirtualMachine {
[INFO] [stdout]     |            -------------- fields in this struct
[INFO] [stdout] 104 |     kvm: KvmDevice,
[INFO] [stdout]     |     ^^^
[INFO] [stdout] 105 |     vm_fd: RawFd,
[INFO] [stdout] 106 |     memory: GuestMemory,
[INFO] [stdout]     |     ^^^^^^
[INFO] [stdout] 107 |     vcpus: Vec<Vcpu>,
[INFO] [stdout]     |     ^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `info` is never used
[INFO] [stdout]    --> src/vm.rs:111:12
[INFO] [stdout]     |
[INFO] [stdout] 110 | impl VirtualMachine {
[INFO] [stdout]     | ------------------- method in this implementation
[INFO] [stdout] 111 |     pub fn info(&self) {
[INFO] [stdout]     |            ^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.33s
[INFO] running `Command { std: "docker" "inspect" "5478dda01f9a01e9e1f2c0a4c980e26a6ad538f4a23495954a1ad3df916d3ea7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5478dda01f9a01e9e1f2c0a4c980e26a6ad538f4a23495954a1ad3df916d3ea7", kill_on_drop: false }`
[INFO] [stdout] 5478dda01f9a01e9e1f2c0a4c980e26a6ad538f4a23495954a1ad3df916d3ea7