[INFO] fetching crate timescope-runtime 0.1.2...
[INFO] testing timescope-runtime-0.1.2 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate timescope-runtime 0.1.2 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate timescope-runtime 0.1.2
[INFO] finished tweaking crates.io crate timescope-runtime 0.1.2
[INFO] tweaked toml for crates.io crate timescope-runtime 0.1.2 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate timescope-runtime 0.1.2 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate timescope-runtime 0.1.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82
[INFO] running `Command { std: "docker" "start" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling timescope-runtime v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.39s
[INFO] running `Command { std: "docker" "inspect" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling timescope-runtime v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.65s
[INFO] running `Command { std: "docker" "inspect" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/timescope_runtime-47def546fd732850)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test allocator::tests::align_up_rounds_to_next_alignment_boundary ... ok
[INFO] [stdout] test concurrent::tests::push_overwrites_oldest_value_when_full ... ok
[INFO] [stdout] test allocator::tests::allocate_uninit_raw_respects_requested_alignment ... ok
[INFO] [stdout] test concurrent::tests::clone_vec_is_empty_before_push ... ok
[INFO] [stdout] test tests::settings_history_size_is_sanitized_to_non_zero ... ok
[INFO] [stdout] test tests::time_scope_registers_history_in_global_runtime ... ok
[INFO] [stdout] test tests::time_scope_uses_default_history_size ... ok
[INFO] [stdout] test tests::register_thread_name_records_current_thread_info ... ok
[INFO] [stdout] test thread::tests::current_returns_different_ids_for_different_threads ... ok
[INFO] [stdout] test tests::time_scope_guard_can_escape_thread_local_with ... ok
[INFO] [stdout] test scope::tests::drop_records_elapsed_duration ... ok
[INFO] [stdout] test scope::tests::history_is_capped_by_buffer_size ... ok
[INFO] [stdout] test allocator::tests::allocate_initializes_value ... ok
[INFO] [stdout] test scope::tests::history_keeps_parent_scope_id ... ok
[INFO] [stdout] test tests::settings_default_history_size_is_non_zero ... ok
[INFO] [stdout] test thread::tests::current_returns_same_id_inside_same_thread ... ok
[INFO] [stdout] test tests::nested_time_scope_records_parent_scope ... ok
[INFO] [stdout] test concurrent::tests::push_is_safe_from_multiple_threads ... ok
[INFO] [stderr]    Doc-tests timescope_runtime
[INFO] [stdout] test concurrent::tests::clone_vec_returns_values_from_oldest_to_newest ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime (line 30) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::get_histories (line 74) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::get_settings (line 113) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::get_thread_infos (line 86) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::new (line 60) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::register_thread_name (line 138) ... ignored
[INFO] [stdout] test src/lib.rs - GlobalTimeScopeRuntime::set_settings (line 100) ... ignored
[INFO] [stdout] test src/lib.rs - RuntimeThreadInfo (line 239) ... ignored
[INFO] [stdout] test src/lib.rs - ScopeHistory (line 401) ... ignored
[INFO] [stdout] test src/lib.rs - ScopeID (line 43) ... ignored
[INFO] [stdout] test src/lib.rs - TimeScope (line 328) ... ignored
[INFO] [stdout] test src/lib.rs - TimeScope::get_scope (line 353) ... ignored
[INFO] [stdout] test src/lib.rs - TimeScope::new (line 339) ... ignored
[INFO] [stdout] test src/lib.rs - TimeScopeSettings (line 257) ... ignored
[INFO] [stdout] test src/lib.rs - enable_profile (line 168) ... ignored
[INFO] [stdout] test src/lib.rs - get_thread_infos (line 204) ... ignored
[INFO] [stdout] test src/lib.rs - get_time_scope_settings (line 229) ... ignored
[INFO] [stdout] test src/lib.rs - is_profile_enabled (line 180) ... ignored
[INFO] [stdout] test src/lib.rs - register_thread_name (line 194) ... ignored
[INFO] [stdout] test src/lib.rs - set_time_scope_settings (line 218) ... ignored
[INFO] [stdout] test src/scope.rs - scope::ScopeDrop (line 24) ... ignored
[INFO] [stdout] test src/scope.rs - scope::ScopeDrop<'_>::disabled (line 84) ... ignored
[INFO] [stdout] test src/thread.rs - thread::RuntimeThreadID (line 10) ... ignored
[INFO] [stdout] test src/thread.rs - thread::RuntimeThreadID::current (line 31) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 24 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.43s; merged doctests compilation took 0.42s
[INFO] running `Command { std: "docker" "inspect" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82", kill_on_drop: false }`
[INFO] [stdout] 27d22f074dc05544b4ca72b0f2f0b6a882b5fba4e572dc7d2d7790f6be735f82
