[INFO] fetching crate timber 0.1.0...
[INFO] building timber-0.1.0 against try#d5c88c22807925c189328ef9d923c5d2b11a95b8+rustflags=-Zprofile-closures for pr-87066-3
[INFO] extracting crate timber 0.1.0 into /workspace/builds/worker-52/source
[INFO] validating manifest of crates.io crate timber 0.1.0 on toolchain d5c88c22807925c189328ef9d923c5d2b11a95b8
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate timber 0.1.0
[INFO] finished tweaking crates.io crate timber 0.1.0
[INFO] tweaked toml for crates.io crate timber 0.1.0 written to /workspace/builds/worker-52/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "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-52/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-52/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] afd8eab59d95aca4556f1af2205d05b1be45adb9e2cfe261d68f61240ff94dab
[INFO] running `Command { std: "docker" "start" "-a" "afd8eab59d95aca4556f1af2205d05b1be45adb9e2cfe261d68f61240ff94dab", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "afd8eab59d95aca4556f1af2205d05b1be45adb9e2cfe261d68f61240ff94dab", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "afd8eab59d95aca4556f1af2205d05b1be45adb9e2cfe261d68f61240ff94dab", kill_on_drop: false }`
[INFO] [stdout] afd8eab59d95aca4556f1af2205d05b1be45adb9e2cfe261d68f61240ff94dab
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-52/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-52/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 -Zprofile-closures" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c0584aa4853a50e1b4b20e5e7de50bf26553bf63061b8561ca11cb3fca86ccb4
[INFO] running `Command { std: "docker" "start" "-a" "c0584aa4853a50e1b4b20e5e7de50bf26553bf63061b8561ca11cb3fca86ccb4", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.99
[INFO] [stderr] SG_CR_Eslkdjf: build_script_build, (75b84863966839e5, 8a92c743b00988cf), 0, 0
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr] SG_CR_Eslkdjf: time, (138085e056a270f6, 13f188afb496dab5), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (ab27881b1d952734, b4608fc61bb51f02), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (58279649268ddf39, fea2741e190b85b2), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (dcc347125b202c60, a86e2941fdc6fc0e), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (2c250627461396ef, c0d51850912ff286), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (de65972c6c48e96, b06d52701aae0214), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (23399ecd55d29399, 580ce22a972ce8d1), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (70f138978b6de95b, 81437fb899433f03), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (a8947dfdf7446d3a, b5da497bbf632415), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (f7a54098d338807c, 33f324978a4b46b3), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (3d7c0d999da1ba64, cd785a73321429e6), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (2991ac7eee33b7d3, 6de5b1049729bba8), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (96f9ccc74a649054, 4ec8ed49b6573d84), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (22f962830f6d6fc, 4d429a3ce3d11dfd), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (41cfa6f38dfac18a, 52f6e0bdc70a0eb0), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (3aea8b67b39d444d, e421ada6eb94958d), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (397b46221c694a86, 72fce51e181522e), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (52b79db17c590896, 8e82b1db2dc8bf67), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (1e86a29f27607a6c, cec43fd4558afc6c), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (21b0e10557808477, 298224d9a7a19c8c), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (9ef07066f863ad4d, 23adf36ed2413e91), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (a08ac9f66eb959e5, 304a9c81e4d0f978), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (facb33abd0ea176e, d45824f206e3c8fa), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (833bdf8974a08040, 701a4e1dcb5b6294), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (ae9fd7a17906a4cb, 397bed7408cbe9a7), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (86d078c6ae428e74, 2fcc5bcc11e9162f), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (479b68a6428392e8, fc44c32c1c347898), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (34b4930610ab3b30, 96a5cfc1ced13d20), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (884916f06251a717, 7e366846c901914), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (1a66039006152580, 50a3a6d04ab3fded), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (ef7be2b5e0bc14f5, 6b3595ceedd6c3b6), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (cd3ffa83d5add1c0, 75441c476f60fc86), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (80513e3d241d83d0, 1906fff752681e77), 0, 0
[INFO] [stderr]    Compiling timber v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]   --> timber.rs:61:60
[INFO] [stdout]    |
[INFO] [stdout] 61 | use std::sync::{Arc, Mutex, MutexGuard, PoisonError, Once, ONCE_INIT};
[INFO] [stdout]    |                                                            ^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]    --> timber.rs:152:25
[INFO] [stdout]     |
[INFO] [stdout] 152 |     static ONCE: Once = ONCE_INIT;
[INFO] [stdout]     |                         ^^^^^^^^^ help: replace the use of the deprecated constant: `Once::new()`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] SG_CR_Eslkdjf: timber, (727def51fd764532, a991959feba37e8), 0, 0
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.51s
[INFO] running `Command { std: "docker" "inspect" "c0584aa4853a50e1b4b20e5e7de50bf26553bf63061b8561ca11cb3fca86ccb4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c0584aa4853a50e1b4b20e5e7de50bf26553bf63061b8561ca11cb3fca86ccb4", kill_on_drop: false }`
[INFO] [stdout] c0584aa4853a50e1b4b20e5e7de50bf26553bf63061b8561ca11cb3fca86ccb4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-52/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-52/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 -Zprofile-closures" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d5c88c22807925c189328ef9d923c5d2b11a95b8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2f13d12617d2dc462ab20c480cd88f0e7fb55661960ba7e92750d637fbc76fd0
[INFO] running `Command { std: "docker" "start" "-a" "2f13d12617d2dc462ab20c480cd88f0e7fb55661960ba7e92750d637fbc76fd0", kill_on_drop: false }`
[INFO] [stderr] SG_CR_Eslkdjf: build_script_build, (75b84863966839e5, 8a92c743b00988cf), 0, 0
[INFO] [stderr] SG_CR_Eslkdjf: time, (138085e056a270f6, 13f188afb496dab5), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (ab27881b1d952734, b4608fc61bb51f02), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (58279649268ddf39, fea2741e190b85b2), 8, 8
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]   --> timber.rs:61:60
[INFO] [stdout]    |
[INFO] [stdout] 61 | use std::sync::{Arc, Mutex, MutexGuard, PoisonError, Once, ONCE_INIT};
[INFO] [stdout]    |                                                            ^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] SG_CR_Eslkdjf: time, (dcc347125b202c60, a86e2941fdc6fc0e), 16, 16
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]    --> timber.rs:152:25
[INFO] [stdout]     |
[INFO] [stdout] 152 |     static ONCE: Once = ONCE_INIT;
[INFO] [stdout]     |                         ^^^^^^^^^ help: replace the use of the deprecated constant: `Once::new()`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] SG_CR_Eslkdjf: time, (2c250627461396ef, c0d51850912ff286), 8, 8
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] SG_CR_Eslkdjf: time, (de65972c6c48e96, b06d52701aae0214), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (23399ecd55d29399, 580ce22a972ce8d1), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (70f138978b6de95b, 81437fb899433f03), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (a8947dfdf7446d3a, b5da497bbf632415), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (f7a54098d338807c, 33f324978a4b46b3), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (3d7c0d999da1ba64, cd785a73321429e6), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (2991ac7eee33b7d3, 6de5b1049729bba8), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (96f9ccc74a649054, 4ec8ed49b6573d84), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (22f962830f6d6fc, 4d429a3ce3d11dfd), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (41cfa6f38dfac18a, 52f6e0bdc70a0eb0), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (3aea8b67b39d444d, e421ada6eb94958d), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (397b46221c694a86, 72fce51e181522e), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (52b79db17c590896, 8e82b1db2dc8bf67), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (1e86a29f27607a6c, cec43fd4558afc6c), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (21b0e10557808477, 298224d9a7a19c8c), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (9ef07066f863ad4d, 23adf36ed2413e91), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (a08ac9f66eb959e5, 304a9c81e4d0f978), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (facb33abd0ea176e, d45824f206e3c8fa), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (833bdf8974a08040, 701a4e1dcb5b6294), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (ae9fd7a17906a4cb, 397bed7408cbe9a7), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (86d078c6ae428e74, 2fcc5bcc11e9162f), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (479b68a6428392e8, fc44c32c1c347898), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (34b4930610ab3b30, 96a5cfc1ced13d20), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (884916f06251a717, 7e366846c901914), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (1a66039006152580, 50a3a6d04ab3fded), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (ef7be2b5e0bc14f5, 6b3595ceedd6c3b6), 8, 8
[INFO] [stderr] SG_CR_Eslkdjf: time, (cd3ffa83d5add1c0, 75441c476f60fc86), 16, 16
[INFO] [stderr] SG_CR_Eslkdjf: time, (80513e3d241d83d0, 1906fff752681e77), 0, 0
[INFO] [stderr] SG_CR_Eslkdjf: timber, (727def51fd764532, a991959feba37e8), 0, 0
[INFO] [stderr]    Compiling timber v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]   --> timber.rs:61:60
[INFO] [stdout]    |
[INFO] [stdout] 61 | use std::sync::{Arc, Mutex, MutexGuard, PoisonError, Once, ONCE_INIT};
[INFO] [stdout]    |                                                            ^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated constant `std::sync::ONCE_INIT`: the `new` function is now preferred
[INFO] [stdout]    --> timber.rs:152:25
[INFO] [stdout]     |
[INFO] [stdout] 152 |     static ONCE: Once = ONCE_INIT;
[INFO] [stdout]     |                         ^^^^^^^^^ help: replace the use of the deprecated constant: `Once::new()`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.61s
[INFO] running `Command { std: "docker" "inspect" "2f13d12617d2dc462ab20c480cd88f0e7fb55661960ba7e92750d637fbc76fd0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2f13d12617d2dc462ab20c480cd88f0e7fb55661960ba7e92750d637fbc76fd0", kill_on_drop: false }`
[INFO] [stdout] 2f13d12617d2dc462ab20c480cd88f0e7fb55661960ba7e92750d637fbc76fd0