[INFO] fetching crate howlong 0.1.7... [INFO] testing howlong-0.1.7 against try#6c5b2f071bf9fe3a4b05942f31d42d076171aa61 for pr-115315-1 [INFO] extracting crate howlong 0.1.7 into /workspace/builds/worker-6-tc2/source [INFO] validating manifest of crates.io crate howlong 0.1.7 on toolchain 6c5b2f071bf9fe3a4b05942f31d42d076171aa61 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate howlong 0.1.7 [INFO] finished tweaking crates.io crate howlong 0.1.7 [INFO] tweaked toml for crates.io crate howlong 0.1.7 written to /workspace/builds/worker-6-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "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-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:f5e78b491441a5b9644e276a59809eedfadf4ee1de96655588122b3a64cb522c" "/opt/rustwide/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 426d706d784ef7c66e5db853b3e46a7db5eab9cf943683c52be211bfb634b0f2 [INFO] running `Command { std: "docker" "start" "-a" "426d706d784ef7c66e5db853b3e46a7db5eab9cf943683c52be211bfb634b0f2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "426d706d784ef7c66e5db853b3e46a7db5eab9cf943683c52be211bfb634b0f2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "426d706d784ef7c66e5db853b3e46a7db5eab9cf943683c52be211bfb634b0f2", kill_on_drop: false }` [INFO] [stdout] 426d706d784ef7c66e5db853b3e46a7db5eab9cf943683c52be211bfb634b0f2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:f5e78b491441a5b9644e276a59809eedfadf4ee1de96655588122b3a64cb522c" "/opt/rustwide/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 19ef9c1b4e54adede845289a5ef782d21239836ec08428cfa00a1b0fde12c605 [INFO] running `Command { std: "docker" "start" "-a" "19ef9c1b4e54adede845289a5ef782d21239836ec08428cfa00a1b0fde12c605", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.147 [INFO] [stderr] Compiling proc-macro2 v1.0.66 [INFO] [stderr] Compiling unicode-ident v1.0.11 [INFO] [stderr] Compiling thiserror v1.0.47 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling quote v1.0.33 [INFO] [stderr] Compiling syn v2.0.29 [INFO] [stderr] Compiling cc v1.0.83 [INFO] [stderr] Compiling errno v0.2.8 [INFO] [stderr] Compiling howlong v0.1.7 (/opt/rustwide/workdir) [INFO] [stderr] Compiling thiserror-impl v1.0.47 [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 7.25s [INFO] running `Command { std: "docker" "inspect" "19ef9c1b4e54adede845289a5ef782d21239836ec08428cfa00a1b0fde12c605", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "19ef9c1b4e54adede845289a5ef782d21239836ec08428cfa00a1b0fde12c605", kill_on_drop: false }` [INFO] [stdout] 19ef9c1b4e54adede845289a5ef782d21239836ec08428cfa00a1b0fde12c605 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:f5e78b491441a5b9644e276a59809eedfadf4ee1de96655588122b3a64cb522c" "/opt/rustwide/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3fd3362c60a08ff90ae7b26ec15464007da0dc58c221e4594bd03f7597cf201d [INFO] running `Command { std: "docker" "start" "-a" "3fd3362c60a08ff90ae7b26ec15464007da0dc58c221e4594bd03f7597cf201d", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling crossbeam-utils v0.8.16 [INFO] [stderr] Compiling serde v1.0.188 [INFO] [stderr] Compiling memchr v2.6.2 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling rayon-core v1.11.0 [INFO] [stderr] Compiling ryu v1.0.15 [INFO] [stderr] Compiling itoa v1.0.9 [INFO] [stderr] Compiling either v1.9.0 [INFO] [stderr] Compiling serde_json v1.0.105 [INFO] [stderr] Compiling plotters-backend v0.3.5 [INFO] [stderr] Compiling unicode-width v0.1.10 [INFO] [stderr] Compiling regex-syntax v0.7.5 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling half v1.8.2 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling plotters-svg v0.3.5 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling vec_map v0.8.2 [INFO] [stderr] Compiling serde_derive v1.0.188 [INFO] [stderr] Compiling oorandom v11.1.3 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling walkdir v2.3.3 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling memoffset v0.9.0 [INFO] [stderr] Compiling crossbeam-epoch v0.9.15 [INFO] [stderr] Compiling num-traits v0.2.16 [INFO] [stderr] Compiling aho-corasick v1.0.5 [INFO] [stderr] Compiling csv-core v0.1.10 [INFO] [stderr] Compiling crossbeam-channel v0.5.8 [INFO] [stderr] Compiling crossbeam-deque v0.8.3 [INFO] [stderr] Compiling criterion-plot v0.4.5 [INFO] [stderr] Compiling rayon v1.7.0 [INFO] [stderr] Compiling regex-automata v0.3.7 [INFO] [stderr] Compiling plotters v0.3.5 [INFO] [stderr] Compiling csv v1.2.2 [INFO] [stderr] Compiling serde_cbor v0.11.2 [INFO] [stderr] Compiling regex v1.9.4 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling criterion v0.3.6 [INFO] [stderr] Compiling howlong v0.1.7 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 19.29s [INFO] running `Command { std: "docker" "inspect" "3fd3362c60a08ff90ae7b26ec15464007da0dc58c221e4594bd03f7597cf201d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3fd3362c60a08ff90ae7b26ec15464007da0dc58c221e4594bd03f7597cf201d", kill_on_drop: false }` [INFO] [stdout] 3fd3362c60a08ff90ae7b26ec15464007da0dc58c221e4594bd03f7597cf201d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:f5e78b491441a5b9644e276a59809eedfadf4ee1de96655588122b3a64cb522c" "/opt/rustwide/cargo-home/bin/cargo" "+6c5b2f071bf9fe3a4b05942f31d42d076171aa61" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] a5350a906d4f9d356282f53e32750ffcd0a6983d9641f72f0aa57b32e9dcc52b [INFO] running `Command { std: "docker" "start" "-a" "a5350a906d4f9d356282f53e32750ffcd0a6983d9641f72f0aa57b32e9dcc52b", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/howlong-b5c24b899401f817) [INFO] [stdout] running 0 tests [INFO] [stderr] Running tests/clock.rs (/opt/rustwide/target/debug/deps/clock-d554b67627c78bc2) [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] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test test_process_system_cpu_clock ... ok [INFO] [stdout] test test_process_real_cpu_clock ... ok [INFO] [stdout] test test_high_resolution_clock ... ok [INFO] [stdout] test test_system_clock ... ok [INFO] [stdout] test test_steady_clock ... ok [INFO] [stdout] test test_thread_clock ... ok [INFO] [stdout] test test_process_user_cpu_clock ... ok [INFO] [stdout] test test_process_cpu_clock ... ok [INFO] [stderr] Running tests/timer.rs (/opt/rustwide/target/debug/deps/timer-a72803fb299d67db) [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.45s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test test_process_system_cpu_timer ... ok [INFO] [stdout] test test_process_real_cpu_timer ... ok [INFO] [stdout] test test_high_resolution_timer ... ok [INFO] [stdout] test test_system_timer ... ok [INFO] [stdout] test test_process_user_cpu_timer ... ok [INFO] [stdout] test test_thread_timer ... ok [INFO] [stdout] test test_process_cpu_timer ... ok [INFO] [stderr] Doc-tests howlong [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.50s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test src/lib.rs - (line 28) ... ok [INFO] [stdout] test src/clock/mod.rs - clock (line 41) ... ok [INFO] [stdout] test src/timer.rs - timer (line 8) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.44s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "a5350a906d4f9d356282f53e32750ffcd0a6983d9641f72f0aa57b32e9dcc52b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a5350a906d4f9d356282f53e32750ffcd0a6983d9641f72f0aa57b32e9dcc52b", kill_on_drop: false }` [INFO] [stdout] a5350a906d4f9d356282f53e32750ffcd0a6983d9641f72f0aa57b32e9dcc52b