[INFO] fetching crate reqray 0.2.2... [INFO] testing reqray-0.2.2 against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031 [INFO] extracting crate reqray 0.2.2 into /workspace/builds/worker-63/source [INFO] validating manifest of crates.io crate reqray 0.2.2 on toolchain 722e1797249a965b6335aebd65d777f917e498f1 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate reqray 0.2.2 [INFO] finished tweaking crates.io crate reqray 0.2.2 [INFO] tweaked toml for crates.io crate reqray 0.2.2 written to /workspace/builds/worker-63/source/Cargo.toml [INFO] crate crates.io crate reqray 0.2.2 already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "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 tracing-attributes v0.1.11 [INFO] [stderr] Downloaded tokio-macros v0.3.2 [INFO] [stderr] Downloaded signal-hook-registry v1.2.2 [INFO] [stderr] Downloaded parking_lot_core v0.8.1 [INFO] [stderr] Downloaded mio v0.7.6 [INFO] [stderr] Downloaded ctor v0.1.16 [INFO] [stderr] Downloaded atomic-shim v0.1.0 [INFO] [stderr] Downloaded sharded-slab v0.1.0 [INFO] [stderr] Downloaded async-global-executor v1.4.3 [INFO] [stderr] Downloaded raw-cpuid v8.1.2 [INFO] [stderr] Downloaded loom v0.3.6 [INFO] [stderr] Downloaded tracing-subscriber v0.2.15 [INFO] [stderr] Downloaded tracing-log v0.1.1 [INFO] [stderr] Downloaded socket2 v0.3.18 [INFO] [stderr] Downloaded tokio v0.3.6 [INFO] [stderr] Downloaded async-std v1.8.0 [INFO] [stderr] Downloaded generator v0.6.23 [INFO] [stderr] Downloaded quanta v0.6.5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 188b6523ca37787db70be34cc2070e547a7c92befcad6b1163ea1ae39eb5f875 [INFO] running `Command { std: "docker" "start" "-a" "188b6523ca37787db70be34cc2070e547a7c92befcad6b1163ea1ae39eb5f875", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "188b6523ca37787db70be34cc2070e547a7c92befcad6b1163ea1ae39eb5f875", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "188b6523ca37787db70be34cc2070e547a7c92befcad6b1163ea1ae39eb5f875", kill_on_drop: false }` [INFO] [stdout] 188b6523ca37787db70be34cc2070e547a7c92befcad6b1163ea1ae39eb5f875 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 53f154d0098cc75248c2342a348cb9d1abccc1cad703d50d03fa15ca98a42781 [INFO] running `Command { std: "docker" "start" "-a" "53f154d0098cc75248c2342a348cb9d1abccc1cad703d50d03fa15ca98a42781", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.24 [INFO] [stderr] Compiling unicode-xid v0.2.1 [INFO] [stderr] Compiling syn v1.0.53 [INFO] [stderr] Compiling libc v0.2.80 [INFO] [stderr] Compiling serde_derive v1.0.117 [INFO] [stderr] Compiling semver-parser v0.7.0 [INFO] [stderr] Compiling serde v1.0.117 [INFO] [stderr] Compiling byteorder v1.3.4 [INFO] [stderr] Compiling cc v1.0.66 [INFO] [stderr] Compiling log v0.4.11 [INFO] [stderr] Compiling regex-syntax v0.6.21 [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling serde_json v1.0.60 [INFO] [stderr] Compiling itoa v0.4.6 [INFO] [stderr] Compiling pin-project-lite v0.2.0 [INFO] [stderr] Compiling atomic-shim v0.1.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling once_cell v1.5.2 [INFO] [stderr] Compiling smallvec v1.5.0 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling tracing-core v0.1.17 [INFO] [stderr] Compiling sharded-slab v0.1.0 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling semver v0.9.0 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling tracing-log v0.1.1 [INFO] [stderr] Compiling quote v1.0.7 [INFO] [stderr] Compiling raw-cpuid v8.1.2 [INFO] [stderr] Compiling time v0.1.44 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling regex-automata v0.1.9 [INFO] [stderr] Compiling regex v1.4.2 [INFO] [stderr] Compiling quanta v0.6.5 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling tracing-attributes v0.1.11 [INFO] [stderr] Compiling tracing v0.1.22 [INFO] [stderr] Compiling tracing-serde v0.1.2 [INFO] [stderr] Compiling tracing-subscriber v0.2.15 [INFO] [stderr] Compiling reqray v0.2.2 (/opt/rustwide/workdir) [INFO] [stdout] warning: field is never read: `parent_idx` [INFO] [stdout] --> src/internal.rs:50:5 [INFO] [stdout] | [INFO] [stdout] 50 | parent_idx: Option, [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 29.37s [INFO] running `Command { std: "docker" "inspect" "53f154d0098cc75248c2342a348cb9d1abccc1cad703d50d03fa15ca98a42781", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "53f154d0098cc75248c2342a348cb9d1abccc1cad703d50d03fa15ca98a42781", kill_on_drop: false }` [INFO] [stdout] 53f154d0098cc75248c2342a348cb9d1abccc1cad703d50d03fa15ca98a42781 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7a05aa6e23d9642cfc7a8f7413d13257d854e3569807fd7001471b2a835419f4 [INFO] running `Command { std: "docker" "start" "-a" "7a05aa6e23d9642cfc7a8f7413d13257d854e3569807fd7001471b2a835419f4", kill_on_drop: false }` [INFO] [stderr] Compiling slab v0.4.2 [INFO] [stderr] Compiling maybe-uninit v2.0.0 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling futures v0.1.30 [INFO] [stderr] Compiling futures-core v0.3.8 [INFO] [stderr] Compiling memchr v2.3.4 [INFO] [stderr] Compiling futures-io v0.3.8 [INFO] [stderr] Compiling cache-padded v1.1.1 [INFO] [stderr] Compiling proc-macro-nested v0.1.6 [INFO] [stderr] Compiling waker-fn v1.1.0 [INFO] [stderr] Compiling parking v2.0.0 [INFO] [stderr] Compiling fastrand v1.4.0 [INFO] [stderr] Compiling futures-sink v0.3.8 [INFO] [stderr] Compiling event-listener v2.5.1 [INFO] [stderr] Compiling pin-project-internal v0.4.27 [INFO] [stderr] Compiling async-task v4.0.3 [INFO] [stderr] Compiling vec-arena v1.0.0 [INFO] [stderr] Compiling atomic-waker v1.0.0 [INFO] [stderr] Compiling bytes v0.6.0 [INFO] [stderr] Compiling unindent v0.1.7 [INFO] [stderr] Compiling crossbeam-utils v0.7.2 [INFO] [stderr] Compiling memoffset v0.5.6 [INFO] [stderr] Compiling crossbeam-epoch v0.8.2 [INFO] [stderr] Compiling crossbeam-utils v0.8.1 [INFO] [stderr] Compiling tokio v0.3.6 [INFO] [stderr] Compiling futures-task v0.3.8 [INFO] [stderr] Compiling instant v0.1.9 [INFO] [stderr] Compiling async-mutex v1.4.0 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling parking_lot_core v0.6.2 [INFO] [stderr] Compiling parking_lot v0.9.0 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling polling v2.0.2 [INFO] [stderr] Compiling nb-connect v1.0.2 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling signal-hook-registry v1.2.2 [INFO] [stderr] Compiling mio v0.7.6 [INFO] [stderr] Compiling pin-project-internal v1.0.2 [INFO] [stderr] Compiling futures-macro v0.3.8 [INFO] [stderr] Compiling tokio-macros v0.3.2 [INFO] [stdout] warning: field is never read: `parent_idx` [INFO] [stdout] --> src/internal.rs:50:5 [INFO] [stdout] | [INFO] [stdout] 50 | parent_idx: Option, [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling lock_api v0.3.4 [INFO] [stderr] Compiling lock_api v0.4.2 [INFO] [stderr] Compiling pin-project v1.0.2 [INFO] [stderr] Compiling parking_lot_core v0.8.1 [INFO] [stderr] Compiling concurrent-queue v1.2.2 [INFO] [stderr] Compiling tokio-sync v0.1.8 [INFO] [stderr] Compiling futures-channel v0.3.8 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling mio v0.6.23 [INFO] [stderr] Compiling indoc v1.0.3 [INFO] [stderr] Compiling smallvec v0.6.13 [INFO] [stderr] Compiling futures-lite v1.11.3 [INFO] [stderr] Compiling tokio-executor v0.1.10 [INFO] [stderr] Compiling crossbeam-queue v0.2.3 [INFO] [stderr] Compiling mio-uds v0.6.8 [INFO] [stderr] Compiling parking_lot v0.11.1 [INFO] [stderr] Compiling tokio-io v0.1.13 [INFO] [stderr] Compiling futures-util v0.3.8 [INFO] [stderr] Compiling async-channel v1.5.1 [INFO] [stderr] Compiling pin-project v0.4.27 [INFO] [stderr] Compiling crossbeam-deque v0.7.3 [INFO] [stderr] Compiling tokio-timer v0.2.13 [INFO] [stderr] Compiling tokio-current-thread v0.1.7 [INFO] [stderr] Compiling tokio-threadpool v0.1.18 [INFO] [stderr] Compiling tokio-reactor v0.1.12 [INFO] [stderr] Compiling tokio-codec v0.1.2 [INFO] [stderr] Compiling async-executor v1.4.0 [INFO] [stderr] Compiling async-io v1.3.1 [INFO] [stderr] Compiling blocking v1.0.2 [INFO] [stderr] Compiling tokio-fs v0.1.7 [INFO] [stderr] Compiling tokio-udp v0.1.6 [INFO] [stderr] Compiling tokio-uds v0.2.7 [INFO] [stderr] Compiling tokio-tcp v0.1.4 [INFO] [stderr] Compiling async-global-executor v1.4.3 [INFO] [stderr] Compiling tokio v0.1.22 [INFO] [stderr] Compiling async-std v1.8.0 [INFO] [stderr] Compiling futures-executor v0.3.8 [INFO] [stderr] Compiling futures v0.3.8 [INFO] [stderr] Compiling tracing-futures v0.2.4 [INFO] [stderr] Compiling reqray v0.2.2 (/opt/rustwide/workdir) [INFO] [stdout] warning: field is never read: `parent_idx` [INFO] [stdout] --> src/internal.rs:50:5 [INFO] [stdout] | [INFO] [stdout] 50 | parent_idx: Option, [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 1m 41s [INFO] running `Command { std: "docker" "inspect" "7a05aa6e23d9642cfc7a8f7413d13257d854e3569807fd7001471b2a835419f4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7a05aa6e23d9642cfc7a8f7413d13257d854e3569807fd7001471b2a835419f4", kill_on_drop: false }` [INFO] [stdout] 7a05aa6e23d9642cfc7a8f7413d13257d854e3569807fd7001471b2a835419f4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] fcfeed5866da94344c8a34350c074e578fb3752dbb34772a9db88e8151711484 [INFO] running `Command { std: "docker" "start" "-a" "fcfeed5866da94344c8a34350c074e578fb3752dbb34772a9db88e8151711484", 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] warning: field is never read: `parent_idx` [INFO] [stderr] --> src/internal.rs:50:5 [INFO] [stderr] | [INFO] [stderr] 50 | parent_idx: Option, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` on by default [INFO] [stderr] [INFO] [stderr] warning: `reqray` (lib) generated 1 warning [INFO] [stderr] warning: `reqray` (lib test) generated 1 warning (1 duplicate) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.81s [INFO] [stdout] [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/reqray-011c90b78a3ec7d1) [INFO] [stdout] running 7 tests [INFO] [stdout] test display::test::display_one_ns ... ok [INFO] [stdout] test display::test::display_compound_call ... ok [INFO] [stdout] test internal::test::test_compound ... ok [INFO] [stdout] test display::test::display_nest_deeply ... ok [INFO] [stdout] test internal::test::test_simple ... ok [INFO] [stdout]   INFO ThreadId(05) cooking_party: CP increment 1_000_000 [INFO] [stdout]   INFO ThreadId(08) cooking_party: CP increment 1_000_000 [INFO] [stdout]   INFO ThreadId(05) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(08) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(05) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(132) cooking_party:eat_three: increment 1_000 [INFO] [stdout]   INFO ThreadId(132) cooking_party:eat_three: increment 1_000 [INFO] [stdout]   INFO ThreadId(05) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(128) cooking_party:eat_three: increment 1_000 [INFO] [stdout]   INFO ThreadId(08) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(132) cooking_party:eat_three: increment 1_000 [INFO] [stdout]   INFO ThreadId(05) cooking_party: CP increment 100_000_000 [INFO] [stdout]   INFO ThreadId(128) cooking_party:eat_three: increment 1_000 [INFO] [stdout]   INFO ThreadId(08) cooking_party:cook_three: increment 10_000 [INFO] [stdout]   INFO ThreadId(132) cooking_party:eat_three: increment 1_000 [INFO] [stdout] test display::test::display_with_futures ... ok [INFO] [stdout]   INFO ThreadId(08) cooking_party: CP increment 100_000_000 [INFO] [stdout] test internal::test::test_with_futures ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s [INFO] [stdout] [INFO] [stderr] Doc-tests reqray [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test src/lib.rs - CallTreeCollectorBuilder (line 142) ... ok [INFO] [stdout] test src/lib.rs - (line 67) ... ok [INFO] [stdout] test src/lib.rs - (line 44) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.64s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "fcfeed5866da94344c8a34350c074e578fb3752dbb34772a9db88e8151711484", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fcfeed5866da94344c8a34350c074e578fb3752dbb34772a9db88e8151711484", kill_on_drop: false }` [INFO] [stdout] fcfeed5866da94344c8a34350c074e578fb3752dbb34772a9db88e8151711484