[INFO] crate tracing-timing 0.2.12 is already in cache
[INFO] extracting crate tracing-timing 0.2.12 into work/ex/beta-1.38-1/sources/1.37.0/reg/tracing-timing/0.2.12
[INFO] extracting crate tracing-timing 0.2.12 into work/ex/beta-1.38-1/sources/beta-2019-08-13/reg/tracing-timing/0.2.12
[INFO] validating manifest of tracing-timing-0.2.12 on toolchain 1.37.0
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of tracing-timing-0.2.12 on toolchain beta-2019-08-13
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing tracing-timing-0.2.12
[INFO] finished frobbing tracing-timing-0.2.12
[INFO] frobbed toml for tracing-timing-0.2.12 written to work/ex/beta-1.38-1/sources/1.37.0/reg/tracing-timing/0.2.12/Cargo.toml
[INFO] started frobbing tracing-timing-0.2.12
[INFO] finished frobbing tracing-timing-0.2.12
[INFO] frobbed toml for tracing-timing-0.2.12 written to work/ex/beta-1.38-1/sources/beta-2019-08-13/reg/tracing-timing/0.2.12/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tracing v0.1.6
[INFO] [stderr]   Downloaded tracing-attributes v0.1.2
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing tracing-timing-0.2.12 against beta-2019-08-13 for beta-1.38-1
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/reg/tracing-timing/0.2.12:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-08-13" "build" "--frozen"`
[INFO] [stdout] a1fa849d7f798a57bcaa4b83c241933b521d47ac358f089d70e205a7be9243ab
[INFO] running `"docker" "start" "-a" "a1fa849d7f798a57bcaa4b83c241933b521d47ac358f089d70e205a7be9243ab"`
[INFO] [stderr]    Compiling lazy_static v1.3.0
[INFO] [stderr]    Compiling quanta v0.3.1
[INFO] [stderr]    Compiling nom v4.2.3
[INFO] [stderr]    Compiling crossbeam-utils v0.6.6
[INFO] [stderr]    Compiling tracing-core v0.1.5
[INFO] [stderr]    Compiling crossbeam-epoch v0.7.2
[INFO] [stderr]    Compiling crossbeam-channel v0.3.9
[INFO] [stderr]    Compiling crossbeam-queue v0.1.2
[INFO] [stderr]    Compiling crossbeam-deque v0.7.1
[INFO] [stderr]    Compiling hdrhistogram v6.3.4
[INFO] [stderr]    Compiling crossbeam v0.7.2
[INFO] [stderr]    Compiling tracing-timing v0.2.12 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.20s
[INFO] running `"docker" "inspect" "a1fa849d7f798a57bcaa4b83c241933b521d47ac358f089d70e205a7be9243ab"`
[INFO] running `"docker" "rm" "-f" "a1fa849d7f798a57bcaa4b83c241933b521d47ac358f089d70e205a7be9243ab"`
[INFO] [stdout] a1fa849d7f798a57bcaa4b83c241933b521d47ac358f089d70e205a7be9243ab
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/reg/tracing-timing/0.2.12:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-08-13" "test" "--frozen" "--no-run"`
[INFO] [stdout] 16370a0cc99e89cbd5754575209e1a4c451f58a1b71c6c1d054461b4980f81bb
[INFO] running `"docker" "start" "-a" "16370a0cc99e89cbd5754575209e1a4c451f58a1b71c6c1d054461b4980f81bb"`
[INFO] [stderr]    Compiling proc-macro2 v1.0.1
[INFO] [stderr]    Compiling syn v1.0.3
[INFO] [stderr]    Compiling c2-chacha v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.2.1
[INFO] [stderr]    Compiling rand v0.7.0
[INFO] [stderr]    Compiling quote v1.0.2
[INFO] [stderr]    Compiling rand_distr v0.2.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.2
[INFO] [stderr]    Compiling tracing v0.1.6
[INFO] [stderr]    Compiling tracing-timing v0.2.12 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 31.26s
[INFO] running `"docker" "inspect" "16370a0cc99e89cbd5754575209e1a4c451f58a1b71c6c1d054461b4980f81bb"`
[INFO] running `"docker" "rm" "-f" "16370a0cc99e89cbd5754575209e1a4c451f58a1b71c6c1d054461b4980f81bb"`
[INFO] [stdout] 16370a0cc99e89cbd5754575209e1a4c451f58a1b71c6c1d054461b4980f81bb
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/reg/tracing-timing/0.2.12:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-08-13" "test" "--frozen"`
[INFO] [stdout] 1e196444de676709cb1d258d2585bd87a9e4e52e7fdc116b1223f6414c3c1f3c
[INFO] running `"docker" "start" "-a" "1e196444de676709cb1d258d2585bd87a9e4e52e7fdc116b1223f6414c3c1f3c"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/tracing_timing-025e05865a5fd316
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/lib-bdc3cace24fba4d6
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test downcaster ... ok
[INFO] [stdout] test by_default ... ok
[INFO] [stdout] test by_name ... ok
[INFO] [stdout] test by_field ... ok
[INFO] [stdout] test by_closure ... ok
[INFO] [stdout] test by_field_typed ... ok
[INFO] [stdout] test custom_time ... ok
[INFO] [stdout] test by_target ... ok
[INFO] [stdout] test explicit_event_parent ... ok
[INFO] [stdout] test informed_histogram_constructor ... ok
[INFO] [stdout] test free_standing_event ... ok
[INFO] [stdout] test dupe_span ... ok
[INFO] [stdout] test event_order ... ok
[INFO] [stdout] test explicit_span_parent ... ok
[INFO] [stdout] test same_event_two_threads ... ok
[INFO] [stdout] test tracks_current_span_deep ... ok
[INFO] [stdout] test tracks_current_span ... ok
[INFO] [stdout] test nested ... ok
[INFO] [stdout] test nested_diff ... ok
[INFO] [stderr]    Doc-tests tracing-timing
[INFO] [stdout] test samples ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/lib.rs - EventGroup (line 206) ... ok
[INFO] [stdout] test src/builder.rs - builder::Builder (line 10) ... ok
[INFO] [stdout] test src/builder.rs - builder::Builder<S, E>::build_informed (line 77) ... ok
[INFO] [stdout] test src/lib.rs - SpanGroup (line 178) ... ok
[INFO] [stdout] test src/lib.rs -  (line 69) ... ok
[INFO] [stdout] test src/lib.rs -  (line 6) ... ok
[INFO] [stdout] test src/lib.rs - TimingSubscriber<S, E>::downcaster (line 662) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "1e196444de676709cb1d258d2585bd87a9e4e52e7fdc116b1223f6414c3c1f3c"`
[INFO] running `"docker" "rm" "-f" "1e196444de676709cb1d258d2585bd87a9e4e52e7fdc116b1223f6414c3c1f3c"`
[INFO] [stdout] 1e196444de676709cb1d258d2585bd87a9e4e52e7fdc116b1223f6414c3c1f3c