Dec 11 09:05:59.366 INFO checking souvik1997/fsbench against master#3a75e80557a103497cffbcab395a2f37061a77ea for pr-56677
Dec 11 09:05:59.366 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56677/worker-1/master#3a75e80557a103497cffbcab395a2f37061a77ea:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56677/master#3a75e80557a103497cffbcab395a2f37061a77ea:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +3a75e80557a103497cffbcab395a2f37061a77ea-alt check --frozen --all --all-targets" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 11 09:06:00.062 INFO [stdout] 9f5c8aedb76ddbc4274ccd54208e860afa95a39cf7ed2a5be361a38217a635ac
Dec 11 09:06:00.064 INFO running `"docker" "start" "-a" "9f5c8aedb76ddbc4274ccd54208e860afa95a39cf7ed2a5be361a38217a635ac"`
Dec 11 09:06:02.746 INFO [stderr] usermod: no changes
Dec 11 09:06:02.963 INFO [stderr]    Compiling num-traits v0.2.2
Dec 11 09:06:02.963 INFO [stderr]     Checking log v0.4.1
Dec 11 09:06:02.976 INFO [stderr]     Checking serde_json v1.0.11
Dec 11 09:06:02.981 INFO [stderr]     Checking num-bigint v0.1.43
Dec 11 09:06:02.996 INFO [stderr]     Checking nix v0.10.0
Dec 11 09:06:03.004 INFO [stderr]     Checking rayon-core v1.4.0
Dec 11 09:06:03.005 INFO [stderr]    Compiling serde_derive_internals v0.20.0
Dec 11 09:06:05.584 INFO [stderr]     Checking fern v0.5.2
Dec 11 09:06:07.733 INFO [stderr]     Checking rayon v1.0.0
Dec 11 09:06:14.170 INFO [stderr]    Compiling num-derive v0.2.0
Dec 11 09:06:15.285 INFO [stderr]     Checking num-rational v0.1.42
Dec 11 09:06:15.652 INFO [stderr]    Compiling serde_derive v1.0.29
Dec 11 09:06:18.840 INFO [stderr]     Checking num v0.1.42
Dec 11 09:06:19.636 INFO [stderr]     Checking chrono v0.4.0
Dec 11 09:06:31.245 INFO [stderr]     Checking fsbench v0.1.0 (/source)
Dec 11 09:06:33.028 INFO [stderr] warning: unused import: `std::process::Command`
Dec 11 09:06:33.028 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:173:13
Dec 11 09:06:33.028 INFO [stderr]     |
Dec 11 09:06:33.028 INFO [stderr] 173 |         use std::process::Command;
Dec 11 09:06:33.028 INFO [stderr]     |             ^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:33.028 INFO [stderr]     |
Dec 11 09:06:33.029 INFO [stderr]     = note: #[warn(unused_imports)] on by default
Dec 11 09:06:33.029 INFO [stderr] 
Dec 11 09:06:33.068 INFO [stderr] warning: unused import: `std::process::Command`
Dec 11 09:06:33.069 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:173:13
Dec 11 09:06:33.069 INFO [stderr]     |
Dec 11 09:06:33.069 INFO [stderr] 173 |         use std::process::Command;
Dec 11 09:06:33.072 INFO [stderr]     |             ^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:33.072 INFO [stderr]     |
Dec 11 09:06:33.072 INFO [stderr]     = note: #[warn(unused_imports)] on by default
Dec 11 09:06:33.072 INFO [stderr] 
Dec 11 09:06:34.047 INFO [stderr] warning: unused variable: `split_string`
Dec 11 09:06:34.047 INFO [stderr]   --> src/fsbench/operation.rs:28:9
Dec 11 09:06:34.047 INFO [stderr]    |
Dec 11 09:06:34.047 INFO [stderr] 28 |     let split_string = vec![String::new()];
Dec 11 09:06:34.047 INFO [stderr]    |         ^^^^^^^^^^^^ help: consider using `_split_string` instead
Dec 11 09:06:34.047 INFO [stderr]    |
Dec 11 09:06:34.047 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Dec 11 09:06:34.047 INFO [stderr] 
Dec 11 09:06:34.052 INFO [stderr] warning: variable `bytes` is assigned to, but never used
Dec 11 09:06:34.052 INFO [stderr]    --> src/fsbench/operation.rs:304:17
Dec 11 09:06:34.052 INFO [stderr]     |
Dec 11 09:06:34.052 INFO [stderr] 304 |         let mut bytes = 0;
Dec 11 09:06:34.052 INFO [stderr]     |                 ^^^^^
Dec 11 09:06:34.052 INFO [stderr]     |
Dec 11 09:06:34.052 INFO [stderr]     = note: consider using `_bytes` instead
Dec 11 09:06:34.052 INFO [stderr] 
Dec 11 09:06:34.296 INFO [stderr] warning: unused variable: `split_string`
Dec 11 09:06:34.296 INFO [stderr]   --> src/fsbench/operation.rs:28:9
Dec 11 09:06:34.296 INFO [stderr]    |
Dec 11 09:06:34.296 INFO [stderr] 28 |     let split_string = vec![String::new()];
Dec 11 09:06:34.296 INFO [stderr]    |         ^^^^^^^^^^^^ help: consider using `_split_string` instead
Dec 11 09:06:34.296 INFO [stderr]    |
Dec 11 09:06:34.296 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Dec 11 09:06:34.296 INFO [stderr] 
Dec 11 09:06:34.316 INFO [stderr] warning: variable `bytes` is assigned to, but never used
Dec 11 09:06:34.316 INFO [stderr]    --> src/fsbench/operation.rs:304:17
Dec 11 09:06:34.316 INFO [stderr]     |
Dec 11 09:06:34.316 INFO [stderr] 304 |         let mut bytes = 0;
Dec 11 09:06:34.316 INFO [stderr]     |                 ^^^^^
Dec 11 09:06:34.316 INFO [stderr]     |
Dec 11 09:06:34.316 INFO [stderr]     = note: consider using `_bytes` instead
Dec 11 09:06:34.316 INFO [stderr] 
Dec 11 09:06:34.508 INFO [stderr] warning: constant item is never used: `SECTOR_SIZE`
Dec 11 09:06:34.508 INFO [stderr]  --> src/fsbench/blktrace/trace.rs:9:1
Dec 11 09:06:34.508 INFO [stderr]   |
Dec 11 09:06:34.508 INFO [stderr] 9 | pub const SECTOR_SIZE: usize = 512;
Dec 11 09:06:34.508 INFO [stderr]   | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.508 INFO [stderr]   |
Dec 11 09:06:34.509 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: field is never used: `elapsed`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:147:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 147 |     elapsed: Duration,
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `total_duration`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:243:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 243 |     pub fn total_duration(&self) -> Duration {
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: struct is never constructed: `Read`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:160:1
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 160 | pub struct Read {
Dec 11 09:06:34.509 INFO [stderr]     | ^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `new`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:165:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 165 |     pub fn new() -> Read {
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `run`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:171:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 171 |     pub fn run(&mut self, fd: RawFd, buf: &mut [u8]) -> nix::Result<usize> {
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: struct is never constructed: `Write`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:192:1
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 192 | pub struct Write {
Dec 11 09:06:34.509 INFO [stderr]     | ^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `new`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:197:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 197 |     pub fn new() -> Write {
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `run`
Dec 11 09:06:34.509 INFO [stderr]    --> src/fsbench/operation.rs:203:5
Dec 11 09:06:34.509 INFO [stderr]     |
Dec 11 09:06:34.509 INFO [stderr] 203 |     pub fn run(&mut self, fd: RawFd, buf: &[u8]) -> nix::Result<usize> {
Dec 11 09:06:34.509 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.509 INFO [stderr] 
Dec 11 09:06:34.509 INFO [stderr] warning: method is never used: `ops_per_second`
Dec 11 09:06:34.509 INFO [stderr]   --> src/fsbench/statistics.rs:42:5
Dec 11 09:06:34.509 INFO [stderr]    |
Dec 11 09:06:34.509 INFO [stderr] 42 |     pub fn ops_per_second(&self) -> f64 {
Dec 11 09:06:34.509 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.516 INFO [stderr] 
Dec 11 09:06:34.516 INFO [stderr] warning: the feature `duration_extras` has been stable since 1.27.0 and no longer requires an attribute to enable
Dec 11 09:06:34.516 INFO [stderr]  --> src/main.rs:1:12
Dec 11 09:06:34.516 INFO [stderr]   |
Dec 11 09:06:34.516 INFO [stderr] 1 | #![feature(duration_extras)]
Dec 11 09:06:34.516 INFO [stderr]   |            ^^^^^^^^^^^^^^^
Dec 11 09:06:34.516 INFO [stderr]   |
Dec 11 09:06:34.516 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 11 09:06:34.516 INFO [stderr] 
Dec 11 09:06:34.824 INFO [stderr] warning: constant item is never used: `SECTOR_SIZE`
Dec 11 09:06:34.824 INFO [stderr]  --> src/fsbench/blktrace/trace.rs:9:1
Dec 11 09:06:34.824 INFO [stderr]   |
Dec 11 09:06:34.824 INFO [stderr] 9 | pub const SECTOR_SIZE: usize = 512;
Dec 11 09:06:34.824 INFO [stderr]   | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr]   |
Dec 11 09:06:34.825 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: field is never used: `elapsed`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:147:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 147 |     elapsed: Duration,
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `total_duration`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/blktrace/trace.rs:243:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 243 |     pub fn total_duration(&self) -> Duration {
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: struct is never constructed: `Read`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:160:1
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 160 | pub struct Read {
Dec 11 09:06:34.825 INFO [stderr]     | ^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `new`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:165:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 165 |     pub fn new() -> Read {
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `run`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:171:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 171 |     pub fn run(&mut self, fd: RawFd, buf: &mut [u8]) -> nix::Result<usize> {
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: struct is never constructed: `Write`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:192:1
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 192 | pub struct Write {
Dec 11 09:06:34.825 INFO [stderr]     | ^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `new`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:197:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 197 |     pub fn new() -> Write {
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `run`
Dec 11 09:06:34.825 INFO [stderr]    --> src/fsbench/operation.rs:203:5
Dec 11 09:06:34.825 INFO [stderr]     |
Dec 11 09:06:34.825 INFO [stderr] 203 |     pub fn run(&mut self, fd: RawFd, buf: &[u8]) -> nix::Result<usize> {
Dec 11 09:06:34.825 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: method is never used: `ops_per_second`
Dec 11 09:06:34.825 INFO [stderr]   --> src/fsbench/statistics.rs:42:5
Dec 11 09:06:34.825 INFO [stderr]    |
Dec 11 09:06:34.825 INFO [stderr] 42 |     pub fn ops_per_second(&self) -> f64 {
Dec 11 09:06:34.825 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.825 INFO [stderr] warning: the feature `duration_extras` has been stable since 1.27.0 and no longer requires an attribute to enable
Dec 11 09:06:34.825 INFO [stderr]  --> src/main.rs:1:12
Dec 11 09:06:34.825 INFO [stderr]   |
Dec 11 09:06:34.825 INFO [stderr] 1 | #![feature(duration_extras)]
Dec 11 09:06:34.825 INFO [stderr]   |            ^^^^^^^^^^^^^^^
Dec 11 09:06:34.825 INFO [stderr]   |
Dec 11 09:06:34.825 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 11 09:06:34.825 INFO [stderr] 
Dec 11 09:06:34.952 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 32.17s
Dec 11 09:06:34.972 INFO [stderr] su: No module specific data is present
Dec 11 09:06:36.160 INFO running `"docker" "inspect" "9f5c8aedb76ddbc4274ccd54208e860afa95a39cf7ed2a5be361a38217a635ac"`
Dec 11 09:06:36.450 INFO running `"docker" "rm" "-f" "9f5c8aedb76ddbc4274ccd54208e860afa95a39cf7ed2a5be361a38217a635ac"`
Dec 11 09:06:36.794 INFO [stdout] 9f5c8aedb76ddbc4274ccd54208e860afa95a39cf7ed2a5be361a38217a635ac